Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2020 13:27
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 16335292 kB
MemAvailable 31292316 kB
Buffers 0 kB
Cached 15192384 kB
SwapCached 4820 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-31 13:26:53
DTS_END                         2020-12-31 13:26:53.6310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36424
TRN_ID                          242047
ATT_ID                          702
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: 9467.09 22432 1136051
customer order: creation 179.48 325 21538
customer order: refuse 14.90 294 1788
order to supplier: creation 46.92 1323 5630
order to supplier: removal 3.33 100805 400
invoice (draft): creation 48.31 3212 5797
invoice (draft): removal 52.88 848 6346
invoice accept: apply 43.87 15882 5264
invoice accept: cancel 48.35 773 5802
customer reserve: creation 4011.93 157 481431
customer reserve: removal 4701.77 56 564212
realization accept: apply 86.91 166 10429
realization accept: cancel 93.63 102 11236
payment from customer: creation 63.05 95 7566
payment from customer: removal 5.92 112 710
payment to supplier: creation 51.13 118 6136
payment to supplier: removal 3.96 58 475
service: total inventory turnovers 3.96 3403 475
service: total monetary turnovers 5.88 574 706
service: aggregate perf. data 0.89 17868 107
service: refresh index statistics 0.03 302468 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2020 13:27:34 to 31-12-2020 13:27:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3643
TEST_TIME 2 4487
TEST_TIME 3 5014
TEST_TIME 4 5011
TEST_TIME 5 5694
TEST_TIME 6 6083
TEST_TIME 7 6481
TEST_TIME 8 6486
TEST_TIME 9 6896
TEST_TIME 10 7569
TEST_TIME 11 7791
TEST_TIME 12 8140
TEST_TIME 13 8388
TEST_TIME 14 8279
TEST_TIME 15 8182
TEST_TIME 16 8211
TEST_TIME 17 8377
TEST_TIME 18 8434
TEST_TIME 19 8616
TEST_TIME 20 8919
TEST_TIME 21 8862
TEST_TIME 22 8992
TEST_TIME 23 9447
TEST_TIME 24 9620
TEST_TIME 25 9809
TEST_TIME 26 9873
TEST_TIME 27 9935
TEST_TIME 28 10082
TEST_TIME 29 10927
TEST_TIME 30 11187
TEST_TIME 31 11133
TEST_TIME 32 11086
TEST_TIME 33 11082
TEST_TIME 34 10971
TEST_TIME 35 11153
TEST_TIME 36 10908
TEST_TIME 37 10979
TEST_TIME 38 11002
TEST_TIME 39 10893
TEST_TIME 40 10810
TEST_TIME 41 10872
TEST_TIME 42 10620
TEST_TIME 43 10461
TEST_TIME 44 10379
TEST_TIME 45 10386
TEST_TIME 46 10377
TEST_TIME 47 10358
TEST_TIME 48 10268
TEST_TIME 49 10203
TEST_TIME 50 10208
TEST_TIME 51 10182
TEST_TIME 52 10125
TEST_TIME 53 10006
TEST_TIME 54 9915
TEST_TIME 55 9902
TEST_TIME 56 9913
TEST_TIME 57 9876
TEST_TIME 58 9908
TEST_TIME 59 9916
TEST_TIME 60 9978
TEST_TIME 61 9960
TEST_TIME 62 9972
TEST_TIME 63 9978
TEST_TIME 64 9940
TEST_TIME 65 10015
TEST_TIME 66 10055
TEST_TIME 67 10019
TEST_TIME 68 9995
TEST_TIME 69 9978
TEST_TIME 70 9859
TEST_TIME 71 9803
TEST_TIME 72 9741
TEST_TIME 73 9693
TEST_TIME 74 9701
TEST_TIME 75 9629
TEST_TIME 76 9583
TEST_TIME 77 9666
TEST_TIME 78 9629
TEST_TIME 79 9606
TEST_TIME 80 9645
TEST_TIME 81 9636
TEST_TIME 82 9590
TEST_TIME 83 9575
TEST_TIME 84 9597
TEST_TIME 85 9679
TEST_TIME 86 9706
TEST_TIME 87 9848
TEST_TIME 88 9755
TEST_TIME 89 9667
TEST_TIME 90 9613
TEST_TIME 91 9606
TEST_TIME 92 9578
TEST_TIME 93 9581
TEST_TIME 94 9635
TEST_TIME 95 9617
TEST_TIME 96 9612
TEST_TIME 97 9559
TEST_TIME 98 9560
TEST_TIME 99 9605
TEST_TIME 100 9603
TEST_TIME 101 9656
TEST_TIME 102 9644
TEST_TIME 103 9690
TEST_TIME 104 9727
TEST_TIME 105 9682
TEST_TIME 106 9592
TEST_TIME 107 9534
TEST_TIME 108 9485
TEST_TIME 109 9416
TEST_TIME 110 9404
TEST_TIME 111 9456
TEST_TIME 112 9422
TEST_TIME 113 9427
TEST_TIME 114 9408
TEST_TIME 115 9382
TEST_TIME 116 9369
TEST_TIME 117 9470
TEST_TIME 118 9434
TEST_TIME 119 9460
TEST_TIME 120 9469

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-12-2020 13:27:37 to 31-12-2020 13:27:41.

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 1580447 1580447 0 0.00 0 14718 6 0 0
doc_list_biud 1580446 1580446 0 0.00 0 103023 26 0 0
sp_add_invoice_to_stock 5390 5264 126 2.33 33 190835 15883 0 126
sp_cancel_adding_invoice 5802 5802 0 0.00 5 78034 774 0 0
sp_cancel_client_order 1788 1788 0 0.00 5 8815 294 0 0
sp_cancel_customer_reserve 564212 564212 0 0.00 1 103025 56 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 11157 112 0 0
sp_cancel_pay_to_supplier 475 475 0 0.00 3 1934 58 0 0
sp_cancel_supplier_invoice 6355 6346 9 0.14 2 44980 848 0 9
sp_cancel_supplier_order 400 400 0 0.00 5 1004810 100805 0 0
sp_cancel_write_off 11236 11236 0 0.00 1 12108 103 0 0
sp_client_order 21538 21538 0 0.00 4 88562 326 0 0
sp_customer_reserve 481431 481431 0 0.00 0 53950 157 0 0
sp_fill_shopping_cart 32956 32956 0 0.00 1 27842 41 0 0
sp_fill_shopping_cart_clo_res 481427 481427 0 0.00 0 15253 13 0 0
sp_get_clo_for_invoice 5260 5260 0 0.00 0 31815 285 0 0
sp_kill_cost_storno 18223 18223 0 0.00 0 8854 52 0 0
sp_kill_qstorno_ret_qs2qd 1146406 1146406 0 0.00 0 103013 31 0 0
sp_kill_qty_storno 605473 605473 0 0.00 0 103023 67 0 0
sp_lock_selected_doc 21779 21779 0 0.00 0 7708 5 0 0
sp_make_cost_storno 29391 29391 0 0.00 0 12504 51 0 0
sp_multiply_rows_for_pdistr 29391 29391 0 0.00 0 7560 29 0 0
sp_multiply_rows_for_qdistr 21538 21538 0 0.00 0 82680 165 0 0
sp_pay_from_customer 7566 7566 0 0.00 4 8592 95 0 0
sp_pay_to_supplier 6136 6136 0 0.00 5 8330 119 0 0
sp_payment_common 13702 13702 0 0.00 1 7816 18 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 8805 270 0 0
sp_qd_handle_on_invoice_upd_sts 11062 11062 0 0.00 0 38668 351 0 0
sp_qd_handle_on_reserve_upd_sts 10429 10429 0 0.00 0 12993 52 0 0
sp_reserve_write_off 10429 10429 0 0.00 7 16558 167 0 0
sp_supplier_invoice 5797 5797 0 0.00 6 99004 3213 0 0
sp_supplier_order 5630 5630 0 0.00 6 43781 1324 0 0
srv_aggregate_perf_data 107 107 0 0.00 2780 305819 17869 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1485 170198 47607 0 0
srv_find_qd_qs_mism 1085523 1085523 0 0.00 0 12294 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 48 85524 3404 0 0
srv_make_money_saldo 706 706 0 0.00 1 13312 574 0 0
srv_recalc_idx_stat 3 3 0 0.00 211161 354082 302468 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5264 5264 0 0.00 0 13562 158 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 3282 84 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 4728 16 0 0
t$perf-norm:sp_cancel_customer_reserve 765 765 0 0.00 0 3060 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 3643 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 475 475 0 0.00 0 2268 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 519 12 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20043 1570 0 0
t$perf-norm:sp_cancel_write_off 1090 1090 0 0.00 0 809 4 0 0
t$perf-norm:sp_client_order 21538 21538 0 0.00 0 9196 19 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 598 4 0 0
t$perf-norm:sp_pay_from_customer 7566 7566 0 0.00 0 7923 12 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 11729 14 0 0
t$perf-norm:sp_reserve_write_off 10429 10429 0 0.00 0 5887 11 0 0
t$perf-norm:sp_supplier_invoice 5797 5797 0 0.00 0 5157 16 0 0
t$perf-norm:sp_supplier_order 5631 5631 0 0.00 0 8464 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 740 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 102 2 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 4048 18 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 1290 6 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10772 10520 252 2.33 0 4331 14 0 252
v_all_customers 39504 39504 0 0.00 0 93 1 0 0
v_all_suppliers 31308 31308 0 0.00 0 264 1 0 0
v_all_wares 43076 43076 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 392 6 0 0
v_cancel_client_order 3576 3576 0 0.00 0 592 3 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 5793 10 0 0
v_cancel_customer_reserve 1530 1530 0 0.00 0 562 3 0 0
v_cancel_payment_to_supplier 950 950 0 0.00 0 1593 6 0 0
v_cancel_supplier_invoice 852 834 18 2.11 0 4715 20 0 18
v_cancel_supplier_order 800 800 0 0.00 0 959 6 0 0
v_cancel_write_off 2180 2180 0 0.00 0 3592 14 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 1079 1 0 0
v_max_id_clo_res 969 969 0 0.00 0 6524 15 0 0
v_max_id_ord_sup 5789 5789 0 0.00 0 4326 4 0 0
v_max_non_paid_invoice 1899 1899 0 0.00 0 807 3 0 0
v_max_non_paid_realizn 3989 3989 0 0.00 0 867 3 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 7866 5 0 0
v_min_id_clo_res 969 969 0 0.00 0 21899 38 0 0
v_min_id_ord_sup 5789 5789 0 0.00 0 6229 9 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 3694 10 0 0
v_min_non_paid_realizn 7566 7566 0 0.00 0 5184 11 0 0
v_our_firm 10726 10726 0 0.00 0 131 1 0 0
v_reserve_write_off 20858 20858 0 0.00 0 4415 5 0 0
x_lock_depdocs_on_canc_invoice 11731 11731 0 0.00 0 10163 86 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 14402 377 0 0
x_make_qstorno_1000_1200 5630 5630 0 0.00 1 43727 1247 0 0
x_make_qstorno_1200_2000 5797 5797 0 0.00 2 98990 3056 0 0
x_make_qstorno_2100_3300 481431 481431 0 0.00 0 53949 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2020 13:27:42 to 31-12-2020 13:27:46.

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
10:56:57 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:58 29181 8192000000 9709681168 10001797120 783863024 8.733 100 88 176 0 813471952 7704272 21904656
10:59:33 11689 8192000000 9744548128 10047737856 824323440 9.142 100 84 168 0 856093120 11555424 20214256
11:00:48 17993 8192000000 9779358640 10090893312 850382208 9.404 100 84 168 0 887606832 17473344 19751280
11:02:12 13263 8192000000 9858435632 10190028800 909990800 9.997 100 80 158 2 969569920 42415200 17163920
11:03:27 7285 8192000000 9918079856 10261692416 953731264 10.428 100 79 158 1 1037927200 67916688 16279248
11:04:37 4428 8192000000 9968712096 10321276928 993953984 10.820 100 82 164 0 1079470128 69698160 15817984
11:05:44 2694 8192000000 10018261424 10375438336 1021307248 11.085 100 87 173 0 1125676320 87412368 16956704
11:06:49 2005 8192000000 10010571024 10376949760 1049111600 11.352 100 93 187 1 1127869840 59483952 19274288
11:07:53 12199 8192000000 10037499936 10413412352 1060433872 11.461 100 77 152 0 1153575440 77949360 15192208
11:09:07 4201 8192000000 9992325280 10384289792 1067794336 11.531 100 42 84 0 1122310880 45410016 9106528
11:10:13 40876 8192000000 10008412192 10403885056 1070909424 11.561 100 41 80 1 1096329040 16657056 8762560
11:12:00 6187 8192000000 9999683040 10400530432 1078292576 11.631 100 41 82 2 1117549408 30594912 8661920
11:13:11 9085 8192000000 10002346128 10396368896 1088004320 11.724 100 42 85 3 1116874912 19522480 9348112
11:14:20 6760 8192000000 10008491232 10412273664 1093437072 11.775 100 41 83 2 1124762624 22972048 8353504
11:15:33 12780 8192000000 10009843840 10398646272 1089779232 11.741 100 77 151 0 1118374864 11917680 16677952
11:16:46 8805 8192000000 10002249568 10413277184 1080663408 11.654 99 31 63 1 1134120192 46908784 6548000
11:17:57 3295 8192000000 9973415888 10367340544 1058518960 11.442 99 38 79 3 1078202704 11274240 8409504
11:19:03 6164 8192000000 9950252096 10330398720 1005073664 10.928 96 35 72 0 1027181312 14383184 7724464
11:20:14 34668 8192000000 9911503664 10300477440 984467680 10.728 96 34 67 2 1007762576 15475520 7819376
11:21:49 9991 8192000000 9935020736 10328166400 984836864 10.731 95 40 80 0 1013402112 18714064 9851184
11:22:59 3803 8192000000 9958049504 10332508160 1033082944 11.198 99 47 94 2 1059494992 16330032 10082016
11:24:06 8630 8192000000 9973512112 10363023360 1058812832 11.445 100 25 48 1 1083001616 19292352 4896432
11:25:17 41217 8192000000 9959821552 10367016960 1062829568 11.484 100 34 68 1 1083801936 12728944 8243424
11:26:59 7520 8192000000 10006426720 10394828800 1088540384 11.729 100 56 111 2 1111382256 10904176 11937696
11:28:27 28484 8192000000 10064028320 10467799040 1105730768 11.892 100 55 110 1 1168322272 50533728 12057776
11:30:01 11275 8192000000 10084487616 10481713152 1128067424 12.103 100 59 118 0 1193806848 53761936 11977488
11:31:18 39182 8192000000 10121015760 10548932608 1133536704 12.155 100 44 86 2 1247315376 103992592 9786080
11:33:00 16959 8192000000 10127108512 10545106944 1126582736 12.089 99 45 90 1 1240461616 103801312 10077568
11:34:26 5834 8192000000 10123482096 10539773952 1141900528 12.233 100 40 80 2 1233679440 83231152 8547760
11:35:36 7236 8192000000 10163362320 10565738496 1130403024 12.125 99 71 140 1 1269154880 124457392 14294464
11:36:55 3512 8192000000 10148851920 10557018112 1154895328 12.355 100 67 132 1 1246917184 78367040 13654816
11:38:02 1484 8192000000 10186392000 10595270656 1153978320 12.347 100 77 149 2 1286957824 117460448 15519056
11:39:08 1930 8192000000 10184779600 10607357952 1162432896 12.426 100 74 145 2 1296477312 117667984 16376432
11:40:13 4631 8192000000 10171629248 10593476608 1153542016 12.343 100 64 125 2 1281528000 114286880 13699104
11:41:30 8230 8192000000 10201562176 10616320000 1129555856 12.117 99 56 112 4 1290846384 148142688 13147840
11:42:43 5599 8192000000 10225946784 10633199616 1147784288 12.289 100 69 138 3 1327504480 163198384 16521808
11:43:55 4093 8192000000 10268972992 10669150208 1152896224 12.337 100 87 174 0 1370004144 197075024 20032896
11:45:04 3690 8192000000 10286454640 10687729664 1128813248 12.110 100 70 139 4 1378944784 234101216 16030320
11:46:13 5103 8192000000 10294282944 10709188608 1151831376 12.327 100 48 95 1 1405718720 242431408 11455936
11:47:33 10033 8192000000 10318079872 10742145024 1144000096 12.253 100 30 60 2 1429075360 277893376 7181888
11:49:01 18971 8192000000 10317553056 10778025984 1144769888 12.260 100 36 72 0 1453018432 298719392 9529152
11:50:29 17606 8192000000 10332163200 10769461248 1147635488 12.287 100 33 66 1 1450061344 293784656 8641200
11:51:53 12678 8192000000 10355055072 10778374144 1152788368 12.336 100 33 66 0 1456461168 295532288 8140512
11:53:07 19972 8192000000 10353066208 10798673920 1151117200 12.320 100 32 64 2 1465799712 307339216 7343296
11:54:28 25931 8192000000 10319845888 10795974656 1136814864 12.186 100 23 46 2 1437244720 294232080 6197776
11:56:02 41835 8192000000 10077813120 10541268992 1094637024 11.787 97 13 25 1 1205665808 107924272 3104512
11:57:49 53378 8192000000 10091149616 10533347328 1112356336 11.955 98 28 55 0 1167739264 49966624 5416304
11:59:47 101170 8192000000 10096604912 10611163136 1103434592 11.870 97 10 20 2 1179403744 74247424 1721728
12:02:30 30491 8192000000 10078920368 10509836288 1119170064 12.019 99 40 76 1 1170563024 42758496 8634464
12:04:01 52546 8192000000 10090110512 10543525888 1126653776 12.090 99 13 24 1 1162094080 32698112 2742192
12:05:56 39487 8192000000 10092059248 10520047616 1133153376 12.151 99 27 54 2 1184371856 45010384 6208096
12:07:41 167432 8192000000 10099938000 10546110464 1128550976 12.108 99 22 44 1 1210243792 76831200 4861616
12:11:36 30812 8192000000 10129412096 10589073408 1154049344 12.347 100 22 44 2 1224959856 65928816 4981696
12:13:09 27766 8192000000 10090093456 10547974144 1151158896 12.320 100 10 20 0 1194294768 40659904 2475968
12:14:43 54597 8192000000 10110855456 10563710976 1154243232 12.349 100 16 32 0 1215934576 58588752 3102592
12:16:37 56977 8192000000 10104186416 10598543360 1156896064 12.374 100 15 30 1 1214879104 54765040 3218000
12:18:35 43662 8192000000 10074367104 10539683840 1157508928 12.380 100 13 26 1 1185153104 25000720 2643456
12:20:53 53250 8192000000 10095066032 10588303360 1118245424 12.010 97 10 20 0 1169830736 49312944 2272368
12:23:34 38699 8192000000 10084614544 10538958848 1093997312 11.781 95 26 52 2 1149409440 49741552 5670576
12:25:19 49820 8192000000 10099350208 10577145856 1090842336 11.751 95 26 52 1 1143782208 47107216 5832656
12:27:09 34852 8192000000 10068391312 10506678272 1088903936 11.732 96 9 17 2 1115304016 24854608 1545472
12:28:44 54714 8192000000 10075640720 10507620352 1063191984 11.487 94 19 37 1 1122434544 55218864 4023696
12:30:43 71265 8192000000 10090774720 10550538240 1052369056 11.383 94 15 31 1 1121138656 65842304 2927296
12:33:00 101254 8192000000 10070304912 10503073792 1020803040 11.080 92 16 32 0 1059876688 35712224 3361424
12:35:52 113304 8192000000 10086897008 10555068416 1105554096 11.890 100 24 48 3 1221113584 109866960 5692528
12:40:11 78135 8192000000 10089264096 10594152448 1104083456 11.876 99 7 14 0 1175067424 69565264 1418704
12:42:29 55875 8192000000 10038869936 10515075072 1093908416 11.780 98 28 56 1 1181842464 81255472 6678576
12:44:32 45259 8192000000 10075349184 10518409216 1108987584 11.923 99 33 66 2 1158584256 42102208 7494464
12:46:18 48344 8192000000 10054949408 10492891136 1083141376 11.677 97 32 64 1 1122217184 32043920 7031888
12:48:09 25955 8192000000 10093353680 10522419200 1106326624 11.898 99 41 82 0 1175640512 61060480 8253408
12:49:37 54212 8192000000 10144064320 10631163904 1131667456 12.137 100 36 72 1 1248652896 109025616 7959824
12:51:34 61706 8192000000 10133785312 10623963136 1107992224 11.913 98 10 20 1 1196449616 85846608 2610784
12:53:43 122880 8192000000 10076445696 10585436160 1133647152 12.156 100 7 14 0 1190112144 55001728 1463264
12:57:01 82607 8192000000 10102639120 10577944576 1115629280 11.986 97 9 16 1 1166373248 49056528 1687440
12:59:24 99236 8192000000 10089217552 10598285312 1145579344 12.268 99 13 26 2 1213518640 65649344 2289952
13:02:19 33304 8192000000 10090920432 10534404096 1144707248 12.260 99 25 50 1 1178873104 28855280 5310576
13:04:01 22007 8192000000 10162022768 10595135488 1151510576 12.324 99 55 109 0 1227331104 64541168 11279360
13:05:24 115375 8192000000 10120044288 10572132352 1092230880 11.764 94 16 32 0 1186932896 90859808 3842208
13:08:24 155851 8192000000 10073476784 10526572544 1077527376 11.624 93 8 16 0 1116904992 37843648 1533968
13:12:00 170198 8192000000 10091517200 10542923776 1105454528 11.889 96 11 22 0 1160202560 52356816 2391216
13:15:50 68567 8192000000 10124536112 10605953024 1126270656 12.086 98 21 43 0 1226758112 96099312 4388144
13:18:01 62744 8192000000 10075474720 10516414464 1076470080 11.614 96 11 22 0 1116766032 37895376 2400576
13:20:34 50741 8192000000 10058749072 10502856704 1103766608 11.873 98 16 32 3 1146698544 39422176 3509760
13:22:32 69184 8192000000 10065706464 10574913536 1094771648 11.788 97 23 46 0 1213651456 113679248 5200560
13:25:08 52086 8192000000 10081912624 10564722688 1112931136 11.960 100 26 52 1 1192235360 73728944 5575280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-12-2020 13:27:48 to 31-12-2020 13:28:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 31-12-2020 13:28:03 to 31-12-2020 13:28:05.

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          241122
MON$OLDEST_ACTIVE               242164
MON$OLDEST_SNAPSHOT             242164
MON$NEXT_TRANSACTION            242164
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                       2134400
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             817
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2313 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.2313 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Dec 31 13:26:54 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Dec 31 13:26:55 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

31.12.20 13:28:06. 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-31 13:28:09 No severe PSQL-related problems occured
31.12.20 13:28:10. End of report.