Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.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 16828472 kB
MemAvailable 31394812 kB
Buffers 0 kB
Cached 14721136 kB
SwapCached 3712 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-15 13:26:55
DTS_END                         2020-12-15 13:26:55.8970
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/36086
TRN_ID                          233190
ATT_ID                          683
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: 8996.14 24632 1079537
customer order: creation 165.82 439 19898
customer order: refuse 13.78 364 1653
order to supplier: creation 44.83 1297 5380
order to supplier: removal 3.40 106981 408
invoice (draft): creation 42.65 3529 5118
invoice (draft): removal 51.40 857 6168
invoice accept: apply 39.86 16002 4783
invoice accept: cancel 47.06 785 5647
customer reserve: creation 3659.33 156 439120
customer reserve: removal 4638.17 63 556580
realization accept: apply 81.72 195 9807
realization accept: cancel 84.44 103 10133
payment from customer: creation 56.26 119 6751
payment from customer: removal 5.62 76 674
payment to supplier: creation 47.45 162 5694
payment to supplier: removal 3.67 79 440
service: total inventory turnovers 3.82 3078 458
service: total monetary turnovers 5.89 447 707
service: aggregate perf. data 0.95 15578 114
service: refresh index statistics 0.03 342326 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2020 13:27:45 to 15-12-2020 13:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2213
TEST_TIME 2 3017
TEST_TIME 3 2942
TEST_TIME 4 4004
TEST_TIME 5 4280
TEST_TIME 6 4615
TEST_TIME 7 4832
TEST_TIME 8 5095
TEST_TIME 9 5270
TEST_TIME 10 5480
TEST_TIME 11 5782
TEST_TIME 12 5811
TEST_TIME 13 6512
TEST_TIME 14 6947
TEST_TIME 15 7694
TEST_TIME 16 7858
TEST_TIME 17 7945
TEST_TIME 18 8564
TEST_TIME 19 8786
TEST_TIME 20 9659
TEST_TIME 21 9547
TEST_TIME 22 9632
TEST_TIME 23 9670
TEST_TIME 24 9868
TEST_TIME 25 11358
TEST_TIME 26 11200
TEST_TIME 27 10828
TEST_TIME 28 10480
TEST_TIME 29 10381
TEST_TIME 30 10370
TEST_TIME 31 10297
TEST_TIME 32 10225
TEST_TIME 33 10024
TEST_TIME 34 10025
TEST_TIME 35 9852
TEST_TIME 36 9682
TEST_TIME 37 9634
TEST_TIME 38 9613
TEST_TIME 39 9659
TEST_TIME 40 9601
TEST_TIME 41 9585
TEST_TIME 42 9462
TEST_TIME 43 9705
TEST_TIME 44 9567
TEST_TIME 45 9443
TEST_TIME 46 9496
TEST_TIME 47 9693
TEST_TIME 48 9655
TEST_TIME 49 9684
TEST_TIME 50 9706
TEST_TIME 51 9516
TEST_TIME 52 9447
TEST_TIME 53 9357
TEST_TIME 54 9345
TEST_TIME 55 9258
TEST_TIME 56 9272
TEST_TIME 57 9269
TEST_TIME 58 9251
TEST_TIME 59 9177
TEST_TIME 60 9167
TEST_TIME 61 9146
TEST_TIME 62 9338
TEST_TIME 63 9338
TEST_TIME 64 9350
TEST_TIME 65 9306
TEST_TIME 66 9449
TEST_TIME 67 9442
TEST_TIME 68 9397
TEST_TIME 69 9301
TEST_TIME 70 9201
TEST_TIME 71 9205
TEST_TIME 72 9133
TEST_TIME 73 9111
TEST_TIME 74 9094
TEST_TIME 75 9015
TEST_TIME 76 8935
TEST_TIME 77 8936
TEST_TIME 78 8901
TEST_TIME 79 8957
TEST_TIME 80 8942
TEST_TIME 81 8927
TEST_TIME 82 8995
TEST_TIME 83 8999
TEST_TIME 84 9003
TEST_TIME 85 9066
TEST_TIME 86 9037
TEST_TIME 87 9091
TEST_TIME 88 9221
TEST_TIME 89 9265
TEST_TIME 90 9178
TEST_TIME 91 9110
TEST_TIME 92 9042
TEST_TIME 93 9053
TEST_TIME 94 9011
TEST_TIME 95 9008
TEST_TIME 96 8973
TEST_TIME 97 8976
TEST_TIME 98 8956
TEST_TIME 99 8964
TEST_TIME 100 9041
TEST_TIME 101 9015
TEST_TIME 102 9013
TEST_TIME 103 9018
TEST_TIME 104 9030
TEST_TIME 105 9099
TEST_TIME 106 9145
TEST_TIME 107 9129
TEST_TIME 108 9073
TEST_TIME 109 9019
TEST_TIME 110 8973
TEST_TIME 111 8926
TEST_TIME 112 8933
TEST_TIME 113 8901
TEST_TIME 114 8890
TEST_TIME 115 8906
TEST_TIME 116 8899
TEST_TIME 117 8887
TEST_TIME 118 8875
TEST_TIME 119 8882
TEST_TIME 120 8997

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2020 13:27:49 to 15-12-2020 13:27:52.

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 1486692 1486692 0 0.00 0 15522 6 0 0
doc_list_biud 1486691 1486691 0 0.00 0 81880 30 0 0
sp_add_invoice_to_stock 4958 4783 175 3.52 35 209229 16002 0 175
sp_cancel_adding_invoice 5647 5647 0 0.00 6 53196 786 0 0
sp_cancel_client_order 1653 1653 0 0.00 4 18189 365 0 0
sp_cancel_customer_reserve 556580 556580 0 0.00 1 32908 63 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 4613 77 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 4584 79 0 0
sp_cancel_supplier_invoice 6180 6168 12 0.19 2 81884 857 0 12
sp_cancel_supplier_order 408 408 0 0.00 7 1086761 106981 0 0
sp_cancel_write_off 10133 10133 0 0.00 1 5880 103 0 0
sp_client_order 19898 19898 0 0.00 4 31682 440 0 0
sp_customer_reserve 439120 439120 0 0.00 0 52346 156 0 0
sp_fill_shopping_cart 30384 30384 0 0.00 1 20234 49 0 0
sp_fill_shopping_cart_clo_res 439117 439117 0 0.00 0 21197 17 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 44855 486 0 0
sp_kill_cost_storno 16894 16894 0 0.00 0 5849 49 0 0
sp_kill_qstorno_ret_qs2qd 1129869 1129869 0 0.00 0 81879 34 0 0
sp_kill_qty_storno 595176 595176 0 0.00 0 81880 74 0 0
sp_lock_selected_doc 20140 20140 0 0.00 0 8085 5 0 0
sp_make_cost_storno 27032 27032 0 0.00 0 15189 65 0 0
sp_multiply_rows_for_pdistr 27032 27032 0 0.00 0 8202 37 0 0
sp_multiply_rows_for_qdistr 19898 19898 0 0.00 0 31673 269 0 0
sp_pay_from_customer 6751 6751 0 0.00 4 12639 120 0 0
sp_pay_to_supplier 5694 5694 0 0.00 5 12852 162 0 0
sp_payment_common 12445 12445 0 0.00 1 12199 32 0 0
sp_qd_handle_on_cancel_clo 1653 1653 0 0.00 0 18181 332 0 0
sp_qd_handle_on_invoice_upd_sts 10427 10427 0 0.00 0 33991 354 0 0
sp_qd_handle_on_reserve_upd_sts 9807 9807 0 0.00 0 16033 51 0 0
sp_reserve_write_off 9807 9807 0 0.00 7 16624 195 0 0
sp_supplier_invoice 5118 5118 0 0.00 7 103362 3529 0 0
sp_supplier_order 5380 5380 0 0.00 5 35900 1298 0 0
srv_aggregate_perf_data 114 114 0 0.00 2815 206511 15578 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1112 139173 50325 0 0
srv_find_qd_qs_mism 1034161 1034161 0 0.00 0 13112 5 0 0
srv_make_invnt_saldo 458 458 0 0.00 56 88474 3078 0 0
srv_make_money_saldo 707 707 0 0.00 1 15561 447 0 0
srv_recalc_idx_stat 4 4 0 0.00 124111 498307 342327 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4784 4784 0 0.00 0 8364 139 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3159 67 0 0
t$perf-norm:sp_cancel_client_order 1653 1653 0 0.00 0 2441 10 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 5129 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 808 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 4220 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 533 15 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 14432 1350 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 2713 9 0 0
t$perf-norm:sp_client_order 19898 19898 0 0.00 0 11444 16 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 394 4 0 0
t$perf-norm:sp_pay_from_customer 6751 6751 0 0.00 0 6604 9 0 0
t$perf-norm:sp_pay_to_supplier 5694 5694 0 0.00 0 3007 6 0 0
t$perf-norm:sp_reserve_write_off 9807 9807 0 0.00 0 5858 10 0 0
t$perf-norm:sp_supplier_invoice 5118 5118 0 0.00 0 3747 13 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 8755 14 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 83 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 920 8 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 595 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 117 33 0 0
v_add_invoice_to_stock 9910 9560 350 3.53 0 8541 16 0 350
v_all_customers 36490 36490 0 0.00 0 13 1 0 0
v_all_suppliers 29174 29174 0 0.00 0 97 1 0 0
v_all_wares 39796 39796 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2603 12 0 0
v_cancel_client_order 3306 3306 0 0.00 0 3585 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 936 5 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1915 5 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 1951 8 0 0
v_cancel_supplier_invoice 768 744 24 3.12 0 918 13 0 24
v_cancel_supplier_order 816 816 0 0.00 0 1918 10 0 0
v_cancel_write_off 1900 1900 0 0.00 0 3934 16 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 547 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 4788 26 0 0
v_max_id_ord_sup 5112 5112 0 0.00 0 7833 7 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 1197 3 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 7885 7 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 4562 13 0 0
v_min_id_clo_res 992 992 0 0.00 0 3663 22 0 0
v_min_id_ord_sup 5112 5112 0 0.00 0 3638 6 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 8382 24 0 0
v_min_non_paid_realizn 6751 6751 0 0.00 0 9260 22 0 0
v_our_firm 9760 9760 0 0.00 0 15 1 0 0
v_reserve_write_off 19614 19614 0 0.00 0 11147 9 0 0
x_lock_depdocs_on_canc_invoice 11443 11443 0 0.00 0 18150 92 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 15172 368 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 2 35264 1211 0 0
x_make_qstorno_1200_2000 5118 5118 0 0.00 4 103272 3338 0 0
x_make_qstorno_2100_3300 439120 439120 0 0.00 0 52344 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2020 13:27:53 to 15-12-2020 13:27:57.

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:59 9 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:58:00 25079 8192000000 9723918080 10022281216 803580608 8.933 100 90 180 0 832937824 7566432 21790784
10:59:28 27379 8192000000 9760616064 10070597632 832433904 9.224 100 84 168 0 864990400 11995904 20560592
11:00:58 13632 8192000000 9796388816 10112901120 869027568 9.590 100 82 164 0 905361328 16937024 19396736
11:02:16 15743 8192000000 9868254656 10201501696 911199056 10.009 100 81 162 1 979280848 50276544 17805248
11:03:42 8691 8192000000 9904228608 10251771904 959118288 10.480 100 77 154 2 1023016736 47615616 16282832
11:04:57 4827 8192000000 9972806224 10324074496 997690224 10.856 100 89 176 1 1080697200 65077888 17929088
11:06:04 3061 8192000000 10007468864 10368131072 1016793792 11.041 100 84 168 0 1115545440 82412656 16338992
11:07:09 2240 8192000000 10012107792 10379579392 1039465680 11.260 100 89 178 2 1125471712 67648816 18357216
11:08:12 2019 8192000000 10032844848 10400382976 1062855456 11.484 100 91 177 1 1137899712 57039168 18005088
11:09:15 14250 8192000000 10013763872 10417201152 1066483616 11.518 100 52 104 0 1123753488 46441120 10828752
11:10:33 3956 8192000000 9997524144 10388013056 1071982576 11.571 100 44 87 1 1095864128 14547920 9333632
11:11:39 5491 8192000000 9980818848 10384474112 1077759488 11.626 100 39 77 3 1108370928 21668016 8943424
11:12:50 8458 8192000000 9993276160 10400722944 1084853376 11.694 100 21 42 3 1108331328 18467408 5010544
11:14:04 6245 8192000000 10019812976 10403647488 1087053088 11.715 100 73 146 2 1121904736 18650000 16201648
11:15:10 7082 8192000000 10022771632 10422362112 1091900992 11.761 100 27 53 1 1131434304 34012528 5520784
11:16:19 3903 8192000000 10011840848 10414768128 1096236768 11.802 100 47 95 1 1126208992 19323248 10648976
11:17:27 53951 8192000000 10002011744 10396258304 1067956688 11.533 98 33 65 1 1102460912 27993744 6510480
11:19:23 6926 8192000000 9938644176 10324140032 972932064 10.615 93 32 63 2 1008732048 29286880 6513104
11:20:35 12336 8192000000 9946373248 10337193984 998812816 10.867 96 28 55 0 1022974512 17784912 6376784
11:21:48 7175 8192000000 9935965424 10315976704 1000389264 10.882 98 40 82 4 1039717952 29727488 9601200
11:22:58 28405 8192000000 9976796768 10370494464 1032725984 11.195 100 50 101 0 1064953152 21054752 11172416
11:24:29 5761 8192000000 9970445808 10372509696 1049546288 11.356 100 39 76 0 1076073632 17746400 8780944
11:25:36 6046 8192000000 9974161616 10366808064 1063100064 11.486 100 31 61 3 1090895840 21326032 6469744
11:26:43 6935 8192000000 10006853392 10409852928 1085959632 11.704 100 40 79 3 1115638928 21126512 8552784
11:28:00 21816 8192000000 10037479904 10438942720 1101157136 11.849 100 49 96 0 1144318880 33465712 9696032
11:29:27 37119 8192000000 10109798000 10517352448 1119831280 12.025 100 52 104 2 1201394240 70727936 10835024
11:31:14 16384 8192000000 10154094912 10556596224 1134497744 12.164 100 55 109 2 1257264592 111506816 11260032
11:32:35 14964 8192000000 10166765312 10576023552 1146168176 12.274 100 47 94 0 1278788144 121916160 10703808
11:33:57 2349 8192000000 10215406704 10612793344 1151340048 12.322 100 66 132 1 1318915216 153479120 14096048
11:35:03 3281 8192000000 10242961888 10647355392 1162621200 12.428 100 66 131 0 1344614176 167110768 14882208
11:36:10 27437 8192000000 10296079440 10718179328 1173151104 12.526 100 58 116 0 1402131136 214624448 14355584
11:37:40 2601 8192000000 10310899616 10722488320 1172228368 12.518 100 66 133 2 1424016544 236844496 14943680
11:38:45 7309 8192000000 10373823264 10781020160 1161561584 12.418 99 84 168 1 1457744688 276883120 19299984
11:39:58 6438 8192000000 10364336016 10773479424 1165994992 12.459 100 74 146 1 1461858528 278948464 16915072
11:41:10 1916 8192000000 10378806016 10784215040 1178098080 12.572 100 84 165 1 1477103840 280192544 18813216
11:42:14 3176 8192000000 10338516304 10743017472 1134441072 12.163 98 60 114 3 1428408032 280186736 13780224
11:43:20 7054 8192000000 10376717808 10800070656 1154655008 12.353 100 46 90 0 1482516528 316723264 11138256
11:44:30 1111 8192000000 10453000512 10858729472 1152486800 12.333 100 82 162 2 1551541360 379413456 19641104
11:45:41 8405 8192000000 10355982016 10766000128 1126561552 12.089 98 49 98 2 1438900096 299734384 12604160
11:46:52 13450 8192000000 10293106704 10742861824 1137129104 12.189 100 35 70 2 1409390960 263326688 8935168
11:48:11 20718 8192000000 10306607904 10761371648 1114014496 11.970 98 26 52 0 1412978624 292150720 6813408
11:49:33 20343 8192000000 10302730768 10738999296 1120613568 12.033 100 27 53 1 1399676128 271981776 7080784
11:50:55 38058 8192000000 10076673504 10582458368 1120823264 12.035 100 24 48 2 1297427856 170876416 5728176
11:52:48 116353 8192000000 10071739136 10512273408 1102647472 11.863 98 34 67 2 1157662272 47204752 7810048
11:55:45 57642 8192000000 10081426928 10547294208 1114014448 11.970 99 33 66 1 1193294784 72250640 7029696
11:58:21 53936 8192000000 10077200832 10518142976 1099118960 11.829 97 13 26 1 1161374576 59689136 2566480
12:00:15 48222 8192000000 10068125904 10541182976 1119016208 12.018 100 22 44 1 1179842736 56609760 4216768
12:02:08 56559 8192000000 10058595824 10506137600 1136373120 12.181 100 21 43 0 1157358144 16464736 4520288
12:04:05 52984 8192000000 10088359136 10551562240 1129632768 12.118 99 6 9 2 1189482960 58413920 1436272
12:06:08 35980 8192000000 10094897744 10527625216 1136504800 12.183 99 46 86 2 1182167392 36590976 9071616
12:08:33 139173 8192000000 10086572560 10550964224 1100710016 11.844 96 21 43 2 1198896176 93919280 4266880
12:11:55 58936 8192000000 10139184336 10611752960 1142199200 12.236 100 26 52 3 1262514064 114895072 5419792
12:14:29 93679 8192000000 10109383952 10605412352 1114412096 11.974 97 12 24 1 1220629024 103317104 2899824
12:17:06 96778 8192000000 10078424224 10572300288 1139094496 12.207 100 14 28 0 1178137456 35945472 3097488
12:19:44 85713 8192000000 10094368528 10577240064 1123624496 12.061 98 11 22 0 1186770512 60875344 2270672
12:22:11 72035 8192000000 10118192160 10584059904 1148242496 12.293 100 14 28 0 1194256384 43105792 2908096
12:24:25 47572 8192000000 10127341696 10590638080 1159729680 12.401 100 15 30 0 1226838080 63785792 3322608
12:26:13 67222 8192000000 10177998752 10622050304 1127494096 12.098 97 26 52 1 1227782800 94951616 5337088
12:28:37 56120 8192000000 10093369968 10525290496 1098416592 11.823 96 19 38 1 1112339664 10520736 3402336
12:30:33 65241 8192000000 10151514928 10572869632 1139081408 12.207 99 32 64 0 1252161904 106321392 6759104
12:32:43 93017 8192000000 10061665696 10547449856 1027510608 11.144 91 6 12 0 1066542512 37783936 1247968
12:35:17 129775 8192000000 10066428816 10514653184 1116173440 11.991 99 41 82 2 1171736752 47078592 8484720
12:38:27 64408 8192000000 10071048320 10526969856 1102375344 11.860 98 8 16 0 1150378496 46475440 1527712
12:40:32 51220 8192000000 10031745184 10487570432 1093528768 11.776 98 10 18 1 1121058016 25673904 1855344
12:42:32 58480 8192000000 10074733088 10530521088 1089138096 11.734 97 17 33 1 1140123248 47594320 3390832
12:44:31 68865 8192000000 10069966816 10550575104 1102763728 11.864 98 16 32 2 1182132960 75764560 3604672
12:46:49 50688 8192000000 10093343968 10531299328 1094807696 11.788 97 19 38 1 1163126560 64384256 3934608
12:48:40 57623 8192000000 10072441648 10537222144 1069255776 11.545 95 11 22 1 1129870608 58049024 2565808
12:50:39 44981 8192000000 10086129984 10548367360 1109159760 11.924 99 19 39 1 1181591520 68059184 4372576
12:52:32 47674 8192000000 10155186384 10631966720 1109059296 11.924 97 38 76 0 1198931136 81990976 7880864
12:54:20 94120 8192000000 10064910512 10556719104 1104051680 11.876 98 16 32 0 1148247936 40899648 3296608
12:56:56 91534 8192000000 10086402144 10546937856 1115680848 11.986 98 20 40 0 1165792928 45611712 4500368
12:59:37 47945 8192000000 10080565600 10581417984 1122159264 12.047 99 9 18 0 1192101504 68154944 1787296
13:01:25 61982 8192000000 10081703376 10532700160 1115128416 11.981 99 11 21 1 1140214848 23712912 1373520
13:03:34 67000 8192000000 10121921264 10560200704 1118083120 12.009 98 26 51 1 1181690448 58373184 5234144
13:05:43 62406 8192000000 10100920896 10570526720 1139332720 12.209 100 17 34 1 1211478240 68621136 3524384
13:07:45 31697 8192000000 10118770272 10551406592 1142284976 12.237 99 41 80 1 1188129488 36839632 9004880
13:09:20 35666 8192000000 10102891552 10534191104 1127210384 12.095 98 30 61 3 1173043744 40099216 5734144
13:10:56 22187 8192000000 10132716224 10580844544 1146250880 12.274 100 45 88 2 1199004832 44012768 8741184
13:12:20 66982 8192000000 10089398240 10570256384 1109820944 11.931 97 11 22 1 1158112384 46174720 2116720
13:14:28 83801 8192000000 10073448592 10524770304 1138754848 12.204 100 21 42 1 1185615568 42874320 3986400
13:17:01 41708 8192000000 10065656032 10524086272 1118097472 12.009 98 5 10 0 1145220400 26077408 1045520
13:18:43 83962 8192000000 10090100416 10534756352 1123382112 12.059 98 10 20 1 1156894400 31395792 2116496
13:21:20 60650 8192000000 10075233552 10535714816 1087691616 11.721 95 17 34 0 1134116192 42335168 4089408
13:23:34 58262 8192000000 10133773664 10592890880 1097365664 11.813 95 24 48 0 1181406352 78666816 5373872
13:25:37 85296 8192000000 9274403968 9618046976 392999776 4.577 36 5 10 0 457351232 63222576 1128880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2020 13:28:15 to 15-12-2020 13:28:17.

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          233505
MON$OLDEST_ACTIVE               233506
MON$OLDEST_SNAPSHOT             233506
MON$NEXT_TRANSACTION            233506
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                       2091712
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             795
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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Tue Dec 15 13:27:05 2020
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Tue Dec 15 13:27:08 2020
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:169: 
--- end of diff output ---

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

15.12.20 13:28:18. 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-15 13:28:20 No severe PSQL-related problems occured
15.12.20 13:28:22. End of report.