Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.09.2021 13:38
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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
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 4793.92
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 15963956 kB
MemAvailable 30926296 kB
Buffers 0 kB
Cached 15734132 kB
SwapCached 336 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.1
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 2021-09-01 13:37:08
DTS_END                         2021-09-01 13:37:08.4140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49402
TRN_ID                          244933
ATT_ID                          672
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: 9324.93 27848 1118992
customer order: creation 176.40 393 21168
customer order: refuse 15.40 323 1848
order to supplier: creation 46.98 1390 5637
order to supplier: removal 3.69 97574 443
invoice (draft): creation 49.33 3374 5919
invoice (draft): removal 52.63 889 6316
invoice accept: apply 43.68 18765 5242
invoice accept: cancel 47.05 840 5646
customer reserve: creation 4000.98 187 480117
customer reserve: removal 4581.98 62 549837
realization accept: apply 87.22 200 10466
realization accept: cancel 84.85 124 10182
payment from customer: creation 62.06 128 7447
payment from customer: removal 6.08 114 730
payment to supplier: creation 51.13 138 6136
payment to supplier: removal 3.77 92 452
service: total inventory turnovers 3.83 4058 459
service: total monetary turnovers 6.84 568 821
service: aggregate perf. data 1.01 14789 121
service: refresh index statistics 0.04 412960 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-09-2021 13:39:01 to 01-09-2021 13:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7111
TEST_TIME 2 5425
TEST_TIME 3 5414
TEST_TIME 4 5626
TEST_TIME 5 6006
TEST_TIME 6 6137
TEST_TIME 7 6285
TEST_TIME 8 6506
TEST_TIME 9 6952
TEST_TIME 10 6980
TEST_TIME 11 7103
TEST_TIME 12 7161
TEST_TIME 13 7385
TEST_TIME 14 7707
TEST_TIME 15 7744
TEST_TIME 16 8149
TEST_TIME 17 8515
TEST_TIME 18 8590
TEST_TIME 19 9217
TEST_TIME 20 9514
TEST_TIME 21 9556
TEST_TIME 22 9820
TEST_TIME 23 10049
TEST_TIME 24 10554
TEST_TIME 25 10851
TEST_TIME 26 10570
TEST_TIME 27 11063
TEST_TIME 28 11356
TEST_TIME 29 11441
TEST_TIME 30 11265
TEST_TIME 31 11101
TEST_TIME 32 11127
TEST_TIME 33 10815
TEST_TIME 34 10605
TEST_TIME 35 10617
TEST_TIME 36 10560
TEST_TIME 37 10454
TEST_TIME 38 10428
TEST_TIME 39 10285
TEST_TIME 40 10302
TEST_TIME 41 10274
TEST_TIME 42 10153
TEST_TIME 43 10086
TEST_TIME 44 9915
TEST_TIME 45 9894
TEST_TIME 46 10165
TEST_TIME 47 10109
TEST_TIME 48 10158
TEST_TIME 49 10057
TEST_TIME 50 10244
TEST_TIME 51 10170
TEST_TIME 52 10166
TEST_TIME 53 10181
TEST_TIME 54 10259
TEST_TIME 55 10256
TEST_TIME 56 10289
TEST_TIME 57 10251
TEST_TIME 58 10144
TEST_TIME 59 10053
TEST_TIME 60 10039
TEST_TIME 61 9966
TEST_TIME 62 9853
TEST_TIME 63 9829
TEST_TIME 64 9722
TEST_TIME 65 9752
TEST_TIME 66 9668
TEST_TIME 67 9694
TEST_TIME 68 9629
TEST_TIME 69 9566
TEST_TIME 70 9622
TEST_TIME 71 9560
TEST_TIME 72 9786
TEST_TIME 73 9833
TEST_TIME 74 9789
TEST_TIME 75 9789
TEST_TIME 76 9816
TEST_TIME 77 9780
TEST_TIME 78 9846
TEST_TIME 79 9735
TEST_TIME 80 9640
TEST_TIME 81 9576
TEST_TIME 82 9573
TEST_TIME 83 9503
TEST_TIME 84 9482
TEST_TIME 85 9469
TEST_TIME 86 9458
TEST_TIME 87 9422
TEST_TIME 88 9459
TEST_TIME 89 9429
TEST_TIME 90 9430
TEST_TIME 91 9417
TEST_TIME 92 9476
TEST_TIME 93 9448
TEST_TIME 94 9435
TEST_TIME 95 9482
TEST_TIME 96 9470
TEST_TIME 97 9538
TEST_TIME 98 9478
TEST_TIME 99 9386
TEST_TIME 100 9348
TEST_TIME 101 9322
TEST_TIME 102 9301
TEST_TIME 103 9278
TEST_TIME 104 9286
TEST_TIME 105 9300
TEST_TIME 106 9277
TEST_TIME 107 9271
TEST_TIME 108 9270
TEST_TIME 109 9280
TEST_TIME 110 9341
TEST_TIME 111 9381
TEST_TIME 112 9406
TEST_TIME 113 9397
TEST_TIME 114 9367
TEST_TIME 115 9408
TEST_TIME 116 9382
TEST_TIME 117 9401
TEST_TIME 118 9415
TEST_TIME 119 9412
TEST_TIME 120 9333

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-09-2021 13:39:04 to 01-09-2021 13:39:08.

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 1562657 1562657 0 0.00 0 37672 7 0 0
doc_list_biud 1562657 1562657 0 0.00 0 32640 28 0 0
sp_add_invoice_to_stock 5378 5242 136 2.52 17 209810 18766 0 136
sp_cancel_adding_invoice 5646 5646 0 0.00 5 68386 841 0 0
sp_cancel_client_order 1848 1848 0 0.00 4 16037 324 0 0
sp_cancel_customer_reserve 549837 549837 0 0.00 1 21082 62 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 12217 115 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 3004 92 0 0
sp_cancel_supplier_invoice 6332 6316 16 0.25 2 30169 890 0 16
sp_cancel_supplier_order 443 443 0 0.00 7 1075552 97575 0 0
sp_cancel_write_off 10182 10182 0 0.00 1 12026 125 0 0
sp_client_order 21168 21168 0 0.00 4 31437 393 0 0
sp_customer_reserve 480117 480117 0 0.00 0 58351 188 0 0
sp_fill_shopping_cart 32724 32724 0 0.00 1 14168 42 0 0
sp_fill_shopping_cart_clo_res 480098 480098 0 0.00 0 14630 13 0 0
sp_get_clo_for_invoice 5224 5224 0 0.00 0 18109 307 0 0
sp_kill_cost_storno 17010 17010 0 0.00 0 37667 56 0 0
sp_kill_qstorno_ret_qs2qd 1116615 1116615 0 0.00 0 30154 34 0 0
sp_kill_qty_storno 589962 589962 0 0.00 0 32640 73 0 0
sp_lock_selected_doc 21894 21894 0 0.00 0 9382 9 0 0
sp_make_cost_storno 29273 29273 0 0.00 0 12832 54 0 0
sp_multiply_rows_for_pdistr 29273 29273 0 0.00 0 37251 34 0 0
sp_multiply_rows_for_qdistr 21168 21168 0 0.00 0 15215 189 0 0
sp_pay_from_customer 7447 7447 0 0.00 5 32086 129 0 0
sp_pay_to_supplier 6136 6136 0 0.00 5 37284 139 0 0
sp_payment_common 13583 13583 0 0.00 1 9710 22 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 16019 289 0 0
sp_qd_handle_on_invoice_upd_sts 10870 10870 0 0.00 0 32640 336 0 0
sp_qd_handle_on_reserve_upd_sts 10466 10466 0 0.00 0 32014 66 0 0
sp_reserve_write_off 10466 10466 0 0.00 6 32041 200 0 0
sp_supplier_invoice 5919 5919 0 0.00 7 96601 3375 0 0
sp_supplier_order 5637 5637 0 0.00 6 36135 1390 0 0
srv_aggregate_perf_data 121 121 0 0.00 2947 159576 14789 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2640 144481 41790 0 0
srv_find_qd_qs_mism 1068930 1068930 0 0.00 0 11982 6 0 0
srv_make_invnt_saldo 459 459 0 0.00 53 107023 4058 0 0
srv_make_money_saldo 821 821 0 0.00 1 18109 568 0 0
srv_recalc_idx_stat 5 5 0 0.00 269955 490774 412960 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5242 5242 0 0.00 0 15282 175 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 3283 99 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 1094 8 0 0
t$perf-norm:sp_cancel_customer_reserve 772 772 0 0.00 0 1064 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 1238 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 904 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1101 14 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 23973 1415 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 4646 13 0 0
t$perf-norm:sp_client_order 21168 21168 0 0.00 0 14360 16 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 9360 21 0 0
t$perf-norm:sp_pay_from_customer 7447 7447 0 0.00 0 13174 15 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 6046 8 0 0
t$perf-norm:sp_reserve_write_off 10466 10466 0 0.00 0 11947 12 0 0
t$perf-norm:sp_supplier_invoice 5919 5919 0 0.00 0 7030 18 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 12996 21 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 1722 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 303 5 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 699 9 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 9496 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10720 10448 272 2.53 0 3681 18 0 272
v_all_customers 38642 38642 0 0.00 0 18 1 0 0
v_all_suppliers 31824 31824 0 0.00 0 23 1 0 0
v_all_wares 42336 42336 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 2181 10 0 0
v_cancel_client_order 3696 3696 0 0.00 0 2874 7 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 2788 11 0 0
v_cancel_customer_reserve 1544 1544 0 0.00 0 3029 9 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 2629 12 0 0
v_cancel_supplier_invoice 922 890 32 3.47 0 2790 20 0 32
v_cancel_supplier_order 886 886 0 0.00 0 4950 15 0 0
v_cancel_write_off 2140 2140 0 0.00 0 5683 22 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 490 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 4303 18 0 0
v_max_id_ord_sup 5919 5919 0 0.00 0 7920 7 0 0
v_max_non_paid_invoice 1780 1780 0 0.00 0 1077 3 0 0
v_max_non_paid_realizn 3961 3961 0 0.00 0 3119 4 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 2485 5 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 7349 22 0 0
v_min_id_ord_sup 5919 5919 0 0.00 0 4512 12 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 6489 15 0 0
v_min_non_paid_realizn 7447 7447 0 0.00 0 9703 16 0 0
v_our_firm 10666 10666 0 0.00 0 14 1 0 0
v_reserve_write_off 20932 20932 0 0.00 0 4054 7 0 0
x_lock_depdocs_on_canc_invoice 11517 11517 0 0.00 0 12251 111 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 4487 283 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 2 36129 1307 0 0
x_make_qstorno_1200_2000 5919 5919 0 0.00 3 96578 3225 0 0
x_make_qstorno_2100_3300 480117 480117 0 0.00 0 58350 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-09-2021 13:39:09 to 01-09-2021 13:39:13.

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
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 21644 8192000000 9719713584 10016616448 797109152 8.867 100 86 172 0 825711344 7640000 20962192
11:09:41 10869 8192000000 9766780240 10077069312 846394416 9.364 100 82 164 0 876386960 10942128 19050416
11:10:55 20962 8192000000 9815430816 10137890816 884922208 9.749 100 78 157 0 920442512 17880704 17639600
11:12:22 17692 8192000000 9875178160 10208817152 920325344 10.099 100 82 164 0 974729536 36437392 17966800
11:13:44 9445 8192000000 9921274208 10265489408 959042640 10.480 100 79 158 1 1030096480 54840080 16213760
11:14:57 5665 8192000000 9956045920 10306252800 994896960 10.829 100 83 164 1 1063733680 52106320 16730400
11:16:04 2735 8192000000 9990969104 10348683264 1018241632 11.055 100 84 166 1 1105259616 70630160 16387824
11:17:09 3163 8192000000 10015109376 10382655488 1042634304 11.290 100 81 161 1 1128875200 69799808 16441088
11:18:13 1471 8192000000 10037563920 10409136128 1063443968 11.489 100 93 186 0 1153190528 70522752 19223808
11:19:16 4108 8192000000 10009158816 10388762624 1068256848 11.535 100 70 139 0 1125479424 42560832 14661744
11:20:21 9996 8192000000 9991744784 10398593024 1074892480 11.599 100 31 61 2 1108265488 26990112 6382896
11:21:35 66782 8192000000 10009256512 10421350400 1086060256 11.705 100 32 63 2 1109914880 17161104 6693520
11:23:43 10221 8192000000 10023476640 10436947968 1090434384 11.747 100 31 62 0 1129191808 32322256 6435168
11:24:54 5314 8192000000 9999891808 10403082240 1092681760 11.768 100 39 79 0 1118081168 16570784 8828624
11:26:00 12025 8192000000 10016932768 10424606720 1078418736 11.632 98 30 60 1 1118060944 32520256 7121952
11:27:19 31429 8192000000 9963405552 10372141056 1005439904 10.931 92 23 46 0 1032608736 22614832 4554000
11:28:52 3160 8192000000 9986628032 10376126464 1052539728 11.385 98 64 129 3 1093909520 27109808 14259984
11:29:58 7128 8192000000 9964259824 10360176640 1033246320 11.200 99 45 87 1 1074079744 31175824 9657600
11:31:07 73873 8192000000 9910228144 10304057344 942146624 10.314 92 28 56 2 961160624 12958032 6055968
11:33:21 5288 8192000000 9989851792 10376757248 1036491632 11.231 100 48 93 1 1085161904 38568448 10101824
11:34:30 7388 8192000000 9963572368 10364485632 1051565376 11.376 100 38 76 1 1069141968 8787488 8789104
11:35:38 8492 8192000000 9973708976 10371502080 1061081216 11.467 100 24 48 1 1083915584 17249936 5584432
11:36:49 39893 8192000000 9991836240 10394619904 1064000368 11.495 100 38 74 0 1101626688 30665664 6960656
11:38:35 7958 8192000000 10044714608 10442457088 1085741824 11.702 100 63 126 0 1156919664 58002400 13175440
11:39:56 38154 8192000000 10077197888 10481192960 1097757712 11.816 100 53 106 1 1191391440 82196480 11437248
11:41:39 19725 8192000000 10104520784 10517667840 1105708144 11.892 99 39 78 1 1209186544 94387312 9091088
11:43:05 22108 8192000000 10172513104 10588205056 1124571104 12.070 100 51 101 0 1273733168 138169360 10992704
11:44:31 20993 8192000000 10159263936 10572587008 1132797648 12.148 100 39 78 0 1264461472 122731824 8932000
11:45:57 13629 8192000000 10204888368 10621083648 1147100192 12.282 100 51 102 0 1305609248 146715376 11793680
11:47:16 12396 8192000000 10196190480 10618310656 1153510640 12.342 100 53 106 1 1308048400 143638976 10898784
11:48:33 6869 8192000000 10274626816 10677596160 1157275120 12.378 100 80 159 0 1373691440 199261872 17154448
11:49:47 3570 8192000000 10288523984 10689683456 1160850560 12.411 100 84 165 1 1390046880 211611264 17585056
11:50:57 3899 8192000000 10322635728 10729558016 1179066864 12.581 100 85 169 1 1423511584 225442016 19002704
11:52:04 5686 8192000000 10311648720 10715291648 1160395328 12.407 100 84 167 1 1407246240 228265088 18585824
11:53:16 2632 8192000000 10360458832 10790899712 1163989584 12.441 100 77 154 0 1463617344 282121600 17506160
11:54:22 14147 8192000000 10325494400 10750205952 1138703520 12.203 98 41 81 0 1417964288 269153376 10107392
11:55:40 2967 8192000000 10337807408 10745352192 1150108320 12.311 100 70 137 0 1428746080 262357648 16280112
11:56:48 3800 8192000000 10332745664 10745323520 1132598784 12.146 98 60 119 0 1413355440 267086176 13670480
11:58:00 3063 8192000000 10291199104 10700460032 1146473632 12.276 100 65 132 1 1391149872 228684896 15991344
11:59:06 16396 8192000000 10276408208 10702299136 1110472400 11.937 99 39 78 0 1366660944 246028736 10159808
12:00:45 12360 8192000000 10248278832 10682519552 1125338400 12.077 100 35 70 0 1356233984 222815776 8079808
12:02:00 31162 8192000000 10190354528 10639810560 1130019360 12.122 99 18 36 0 1289549504 155182544 4347600
12:03:32 29053 8192000000 10226461680 10673397760 1127111936 12.094 100 18 36 0 1314821200 182970320 4738944
12:05:04 43296 8192000000 10098519360 10530525184 1115214752 11.982 98 30 57 1 1182624016 60911552 6497712
12:06:48 35235 8192000000 10070206224 10497089536 1132243440 12.143 100 34 67 3 1161060096 21608864 7207792
12:08:28 144481 8192000000 10071889840 10541293568 1130962800 12.130 99 16 32 0 1175223664 41298272 2962592
12:12:12 68364 8192000000 10069096400 10532835328 1110126416 11.934 97 28 54 1 1159886784 42814384 6945984
12:14:29 5986 8192000000 10062465440 10495991808 1124734464 12.072 99 52 104 3 1156072672 19996896 11341312
12:15:37 26813 8192000000 10090321504 10530131968 1124300688 12.068 98 37 72 1 1168901968 36165680 8435600
12:17:06 48789 8192000000 10097542832 10577444864 1137983888 12.197 99 25 50 1 1201062688 57474624 5604176
12:19:02 52374 8192000000 10095945792 10548846592 1133685264 12.156 98 17 33 0 1164251664 27466560 3099840
12:20:55 37897 8192000000 10166379616 10640879616 1151562016 12.324 99 14 28 0 1247769232 93003536 3203680
12:22:57 66674 8192000000 10085554976 10611060736 1128884208 12.111 98 16 33 0 1237351296 104797872 3669216
12:25:08 45877 8192000000 10099242576 10548465664 1133252912 12.152 98 31 62 0 1176614624 36767232 6594480
12:26:54 33141 8192000000 10131703072 10655084544 1156631888 12.372 100 39 78 0 1258152144 92709696 8810560
12:28:41 69790 8192000000 10153321472 10613522432 1154060032 12.348 100 29 58 1 1249228576 88687520 6481024
12:30:51 36730 8192000000 10094664480 10542092288 1133931152 12.158 98 16 31 0 1157765520 20852416 2981952
12:32:35 22816 8192000000 10112491296 10571771904 1147734048 12.288 100 25 50 1 1232241488 79353472 5153968
12:34:04 85662 8192000000 10114419824 10565640192 1107375408 11.908 96 27 53 1 1176422736 63411536 5635792
12:37:21 49579 8192000000 10078449728 10553229312 1087915616 11.723 95 28 56 0 1151761248 58524192 5321440
12:39:12 21632 8192000000 10061090720 10517102592 1117109488 12.000 99 7 14 0 1173269280 54740944 1418848
12:41:00 58546 8192000000 10080678944 10530394112 1124174080 12.066 99 13 26 2 1175547296 48761424 2611792
12:43:06 53951 8192000000 10086013888 10524315648 1103400064 11.870 97 20 40 2 1154035888 46086176 4549648
12:45:07 57863 8192000000 10114179904 10557808640 1101708720 11.854 97 20 40 0 1180330144 74370480 4250944
12:47:10 47667 8192000000 10140850720 10574467072 1088567936 11.729 95 35 70 2 1206078848 110022192 7488720
12:49:02 56648 8192000000 10085906384 10649776128 1104855776 11.884 99 16 32 2 1243041872 134524512 3661584
12:50:59 53467 8192000000 10070911296 10502742016 1105495824 11.890 98 40 80 0 1148282528 34643664 8143040
12:52:53 30785 8192000000 10120611760 10578571264 1123412320 12.059 100 48 96 0 1220801648 88026016 9363312
12:54:27 40160 8192000000 10127814464 10564960256 1139301584 12.209 100 51 103 0 1205943680 56734208 9907888
12:56:09 86362 8192000000 10046279184 10499936256 1117208704 12.001 99 26 51 2 1146458960 23446768 5803488
12:58:40 49585 8192000000 10106638368 10558382080 1131611632 12.137 99 27 54 0 1205909312 68125568 6172112
13:00:31 47285 8192000000 10087926480 10560598016 1139411568 12.210 100 12 24 0 1210951136 68869616 2669952
13:02:23 30706 8192000000 10099028208 10581397504 1133329424 12.153 99 17 34 0 1204694464 67634032 3731008
13:04:08 87333 8192000000 10091645632 10541654016 1138417664 12.201 99 18 36 0 1202455936 60239888 3798384
13:06:36 17883 8192000000 10075918192 10510036992 1123730784 12.062 98 40 78 3 1157765744 25982016 8052944
13:07:57 34482 8192000000 10109648608 10559188992 1137913712 12.196 99 43 86 3 1194525488 47730848 8880928
13:09:53 61234 8192000000 10082658496 10530111488 1123087360 12.056 99 36 72 1 1159535520 28501808 7946352
13:12:18 61073 8192000000 10082594192 10580226048 1138986848 12.206 100 12 24 0 1207677088 65863040 2827200
13:14:19 134603 8192000000 10042134480 10503794688 1083953744 11.685 95 18 36 1 1153129088 65395584 3779760
13:18:04 69062 8192000000 10083807216 10584559616 1119867520 12.026 98 13 26 1 1186042176 63514128 2660528
13:20:15 46909 8192000000 10096588480 10647494656 1128993536 12.112 100 11 22 0 1223880208 92367648 2519024
13:22:06 79499 8192000000 10112150336 10589036544 1128590224 12.108 99 16 32 0 1169317856 37261072 3466560
13:24:35 43177 8192000000 10111470080 10559500288 1078364960 11.632 94 24 48 0 1152189248 68192528 5631760
13:26:20 36202 8192000000 10123459024 10567700480 1125018752 12.074 99 21 42 1 1214662432 85457472 4186208
13:27:58 53846 8192000000 10073013680 10532343808 1101566048 11.852 98 13 26 0 1167455920 63585296 2304576
13:29:58 80181 8192000000 10067207840 10507227136 1075777312 11.607 95 20 40 2 1109433984 29886112 3770560
13:32:20 38942 8192000000 10082690896 10569170944 1119515184 12.022 100 29 58 0 1171627936 46391568 5721184
13:34:03 61733 8192000000 10066905600 10533810176 1111561744 11.947 99 27 54 3 1148298256 31576720 5159792
13:36:05 74995 8192000000 9830284912 10270777344 886122736 9.761 79 13 26 1 924297968 35382736 2792496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-09-2021 13:39:15 to 01-09-2021 13:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-09-2021 13:39:31 to 01-09-2021 13:39:33.

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          245251
MON$OLDEST_ACTIVE               245252
MON$OLDEST_SNAPSHOT             245252
MON$NEXT_TRANSACTION            245252
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                       2062432
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             820
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2578 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2578 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2578 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2578 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Wed Sep  1 13:37:46 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
--- end of diff output ---

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

01.09.21 13:39:34. 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
2021-09-01 13:39:36 No severe PSQL-related problems occured
01.09.21 13:39:38. End of report.