Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2021 04: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 16138532 kB
MemAvailable 30822828 kB
Buffers 20 kB
Cached 15550544 kB
SwapCached 2460 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-11-05 04:37:09
DTS_END                         2021-11-05 04:37:09.9060
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/43504
TRN_ID                          235624
ATT_ID                          662
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: 9009.88 22518 1081185
customer order: creation 169.37 373 20324
customer order: refuse 14.38 312 1725
order to supplier: creation 45.98 1355 5518
order to supplier: removal 3.40 107335 408
invoice (draft): creation 45.80 3457 5496
invoice (draft): removal 50.41 943 6049
invoice accept: apply 41.96 20202 5035
invoice accept: cancel 45.23 774 5428
customer reserve: creation 3877.59 202 465311
customer reserve: removal 4416.50 65 529980
realization accept: apply 82.67 175 9920
realization accept: cancel 84.86 114 10183
payment from customer: creation 60.46 112 7255
payment from customer: removal 5.89 76 707
payment to supplier: creation 50.73 123 6088
payment to supplier: removal 3.75 90 450
service: total inventory turnovers 3.83 3369 459
service: total monetary turnovers 6.16 616 739
service: aggregate perf. data 0.87 19891 104
service: refresh index statistics 0.05 290783 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2021 04:38:37 to 05-11-2021 04:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6887
TEST_TIME 2 6780
TEST_TIME 3 6455
TEST_TIME 4 6462
TEST_TIME 5 5995
TEST_TIME 6 5586
TEST_TIME 7 5446
TEST_TIME 8 5553
TEST_TIME 9 5495
TEST_TIME 10 5637
TEST_TIME 11 6173
TEST_TIME 12 6765
TEST_TIME 13 6917
TEST_TIME 14 6920
TEST_TIME 15 6997
TEST_TIME 16 7798
TEST_TIME 17 7823
TEST_TIME 18 7839
TEST_TIME 19 8348
TEST_TIME 20 9229
TEST_TIME 21 9244
TEST_TIME 22 10205
TEST_TIME 23 10173
TEST_TIME 24 10706
TEST_TIME 25 10861
TEST_TIME 26 10697
TEST_TIME 27 10724
TEST_TIME 28 10533
TEST_TIME 29 10614
TEST_TIME 30 10413
TEST_TIME 31 10211
TEST_TIME 32 9910
TEST_TIME 33 9929
TEST_TIME 34 9855
TEST_TIME 35 9691
TEST_TIME 36 9546
TEST_TIME 37 9461
TEST_TIME 38 9380
TEST_TIME 39 9332
TEST_TIME 40 9362
TEST_TIME 41 9458
TEST_TIME 42 9525
TEST_TIME 43 9510
TEST_TIME 44 9410
TEST_TIME 45 9482
TEST_TIME 46 9560
TEST_TIME 47 9755
TEST_TIME 48 9717
TEST_TIME 49 9741
TEST_TIME 50 9791
TEST_TIME 51 9783
TEST_TIME 52 9697
TEST_TIME 53 9732
TEST_TIME 54 9748
TEST_TIME 55 9578
TEST_TIME 56 9418
TEST_TIME 57 9387
TEST_TIME 58 9411
TEST_TIME 59 9389
TEST_TIME 60 9397
TEST_TIME 61 9293
TEST_TIME 62 9278
TEST_TIME 63 9229
TEST_TIME 64 9208
TEST_TIME 65 9173
TEST_TIME 66 9206
TEST_TIME 67 9202
TEST_TIME 68 9231
TEST_TIME 69 9315
TEST_TIME 70 9261
TEST_TIME 71 9320
TEST_TIME 72 9312
TEST_TIME 73 9330
TEST_TIME 74 9387
TEST_TIME 75 9484
TEST_TIME 76 9464
TEST_TIME 77 9444
TEST_TIME 78 9366
TEST_TIME 79 9322
TEST_TIME 80 9287
TEST_TIME 81 9264
TEST_TIME 82 9216
TEST_TIME 83 9178
TEST_TIME 84 9169
TEST_TIME 85 9164
TEST_TIME 86 9148
TEST_TIME 87 9106
TEST_TIME 88 9146
TEST_TIME 89 9155
TEST_TIME 90 9213
TEST_TIME 91 9232
TEST_TIME 92 9233
TEST_TIME 93 9186
TEST_TIME 94 9232
TEST_TIME 95 9292
TEST_TIME 96 9240
TEST_TIME 97 9158
TEST_TIME 98 9166
TEST_TIME 99 9100
TEST_TIME 100 9085
TEST_TIME 101 9066
TEST_TIME 102 9084
TEST_TIME 103 9082
TEST_TIME 104 9065
TEST_TIME 105 9040
TEST_TIME 106 9061
TEST_TIME 107 9141
TEST_TIME 108 9144
TEST_TIME 109 9115
TEST_TIME 110 9145
TEST_TIME 111 9123
TEST_TIME 112 9181
TEST_TIME 113 9193
TEST_TIME 114 9173
TEST_TIME 115 9114
TEST_TIME 116 9118
TEST_TIME 117 9076
TEST_TIME 118 9048
TEST_TIME 119 9078
TEST_TIME 120 9010

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-11-2021 04:38:41 to 05-11-2021 04:38:44.

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 1510265 1510265 0 0.00 0 17712 7 0 0
doc_list_biud 1510249 1510249 0 0.00 0 80289 29 0 0
sp_add_invoice_to_stock 5147 5035 112 2.17 40 254907 20202 0 112
sp_cancel_adding_invoice 5428 5428 0 0.00 4 48841 774 0 0
sp_cancel_client_order 1725 1725 0 0.00 5 8748 312 0 0
sp_cancel_customer_reserve 529980 529980 0 0.00 1 23512 65 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 4004 77 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 9922 90 0 0
sp_cancel_supplier_invoice 6064 6049 15 0.24 2 80315 943 0 15
sp_cancel_supplier_order 408 408 0 0.00 10 1067239 107336 0 0
sp_cancel_write_off 10183 10183 0 0.00 1 16461 114 0 0
sp_client_order 20324 20324 0 0.00 4 25705 374 0 0
sp_customer_reserve 465311 465311 0 0.00 0 100968 202 0 0
sp_fill_shopping_cart 31327 31327 0 0.00 1 28762 52 0 0
sp_fill_shopping_cart_clo_res 465311 465311 0 0.00 0 22320 17 0 0
sp_get_clo_for_invoice 5034 5034 0 0.00 0 34584 375 0 0
sp_kill_cost_storno 16768 16768 0 0.00 0 5751 53 0 0
sp_kill_qstorno_ret_qs2qd 1076600 1076600 0 0.00 0 80286 36 0 0
sp_kill_qty_storno 568713 568713 0 0.00 0 80288 76 0 0
sp_lock_selected_doc 20726 20726 0 0.00 0 12026 5 0 0
sp_make_cost_storno 28296 28296 0 0.00 0 11205 49 0 0
sp_multiply_rows_for_pdistr 28280 28280 0 0.00 0 10318 32 0 0
sp_multiply_rows_for_qdistr 20324 20324 0 0.00 0 25699 203 0 0
sp_pay_from_customer 7255 7255 0 0.00 4 20966 112 0 0
sp_pay_to_supplier 6088 6088 0 0.00 4 12305 123 0 0
sp_payment_common 13340 13340 0 0.00 1 8930 24 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 8737 291 0 0
sp_qd_handle_on_invoice_upd_sts 10449 10449 0 0.00 0 29058 329 0 0
sp_qd_handle_on_reserve_upd_sts 9919 9919 0 0.00 0 12091 55 0 0
sp_reserve_write_off 9920 9920 0 0.00 7 25772 175 0 0
sp_supplier_invoice 5496 5496 0 0.00 7 105667 3458 0 0
sp_supplier_order 5518 5518 0 0.00 6 26098 1355 0 0
srv_aggregate_perf_data 104 104 0 0.00 3105 542762 19892 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1578 145661 44056 0 0
srv_find_qd_qs_mism 1032238 1032238 0 0.00 0 15991 7 0 0
srv_make_invnt_saldo 459 459 0 0.00 78 108737 3370 0 0
srv_make_money_saldo 739 739 0 0.00 1 26161 617 0 0
srv_recalc_idx_stat 6 6 0 0.00 115092 454745 290784 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5035 5035 0 0.00 0 37040 173 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 1 5988 64 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 3165 9 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 5703 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1175 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 566 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 2989 20 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 81587 1433 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 1655 5 0 0
t$perf-norm:sp_client_order 20324 20324 0 0.00 0 12829 15 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 4119 10 0 0
t$perf-norm:sp_pay_from_customer 7255 7255 0 0.00 0 6678 10 0 0
t$perf-norm:sp_pay_to_supplier 6088 6088 0 0.00 0 6637 7 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 5050 8 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 4953 14 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 3292 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 292 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 368 9 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 5750 17 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 1736 9 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10266 10042 224 2.18 0 20451 20 0 224
v_all_customers 37626 37626 0 0.00 0 254 1 0 0
v_all_suppliers 31070 31070 0 0.00 0 18 1 0 0
v_all_wares 40648 40648 0 0.00 0 60 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 61 5 0 0
v_cancel_client_order 3450 3450 0 0.00 0 4716 4 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 1061 3 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2594 5 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 9885 16 0 0
v_cancel_supplier_invoice 778 748 30 3.85 0 1999 16 0 30
v_cancel_supplier_order 816 816 0 0.00 0 649 3 0 0
v_cancel_write_off 2116 2116 0 0.00 0 2029 14 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 365 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 1621 17 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 7479 9 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 4298 5 0 0
v_max_non_paid_realizn 3732 3732 0 0.00 0 1036 3 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 2279 6 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 4980 20 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 8980 13 0 0
v_min_non_paid_invoice 6086 6086 0 0.00 0 8927 14 0 0
v_min_non_paid_realizn 7254 7254 0 0.00 0 6810 20 0 0
v_our_firm 10066 10066 0 0.00 0 13 1 0 0
v_reserve_write_off 19838 19838 0 0.00 0 21767 7 0 0
x_lock_depdocs_on_canc_invoice 11103 11103 0 0.00 0 10071 100 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 14809 308 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 2 26091 1274 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 3 105532 3249 0 0
x_make_qstorno_2100_3300 465311 465311 0 0.00 0 100940 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2021 04:38:45 to 05-11-2021 04:38:49.

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
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 23189 8192000000 9710737936 10006298624 785817200 8.752 100 85 170 0 814551216 7648176 21085840
02:09:44 16548 8192000000 9748402352 10054299648 824998448 9.149 100 84 168 0 857335040 11990608 20345984
02:11:07 14430 8192000000 9796496928 10113540096 862279584 9.523 100 86 170 1 903227584 20734336 20213664
02:12:28 15958 8192000000 9858286784 10192904192 915550672 10.052 100 79 158 0 965959472 32752704 17656096
02:13:50 9564 8192000000 9909349952 10254012416 956534624 10.455 100 76 152 0 1020847792 48383248 15929920
02:15:04 5051 8192000000 9957122112 10314407936 991284240 10.794 100 76 155 1 1071608944 64104144 16220560
02:16:10 2271 8192000000 9985968752 10350796800 1019683104 11.069 100 83 168 1 1102904336 66332064 16889168
02:17:14 1988 8192000000 10005405808 10374602752 1042931808 11.293 100 89 178 0 1121691840 60427568 18332464
02:18:17 2109 8192000000 10003768736 10374656000 1055128368 11.410 100 83 163 0 1120140368 48095152 16916848
02:19:21 4030 8192000000 10037079984 10422886400 1059949872 11.456 100 57 114 0 1155454592 84263328 11241392
02:20:25 4916 8192000000 9981388736 10374262784 1072106544 11.572 100 55 110 0 1104522672 20147328 12268800
02:21:31 75298 8192000000 9985909680 10398818304 1083091296 11.677 100 44 87 1 1118206768 25597792 9517680
02:23:47 6755 8192000000 10001957184 10400141312 1092615568 11.768 100 27 53 0 1113602144 15174560 5812016
02:24:54 10830 8192000000 10019456016 10417188864 1093118336 11.772 100 36 72 1 1122455696 21125936 8211424
02:26:07 8192 8192000000 10015687312 10420490240 1096827360 11.808 100 22 45 0 1121733440 20168656 4737424
02:27:15 28257 8192000000 10000592736 10409058304 1061003968 11.466 97 17 35 0 1087321136 22833296 3483872
02:28:44 6673 8192000000 9991118080 10385727488 1050634960 11.367 98 37 73 0 1084909872 26625872 7649040
02:29:52 7134 8192000000 9952006784 10342301696 1015759344 11.031 97 37 73 1 1043483376 19910656 7813376
02:31:01 60982 8192000000 9934507504 10332819456 1000056256 10.879 97 36 72 0 1029469824 21898432 7515136
02:33:04 5810 8192000000 9935697760 10327138304 1019430272 11.067 99 29 56 2 1052309312 26490960 6388080
02:34:10 3709 8192000000 9972853536 10352922624 1049304976 11.354 100 56 114 2 1074914992 13272880 12337136
02:35:17 3831 8192000000 9978364576 10367619072 1067181984 11.525 100 33 66 4 1093989376 19233952 7573440
02:36:24 51164 8192000000 9998895344 10399506432 1074921616 11.599 100 38 75 1 1102390944 19859648 7609680
02:38:39 18461 8192000000 10062489488 10453561344 1110153104 11.934 100 54 107 1 1166256240 45689632 10413504
02:40:02 18602 8192000000 10082148912 10486185984 1125165952 12.076 100 51 102 0 1196682256 60501312 11014992
02:41:44 9120 8192000000 10138501648 10550087680 1144340048 12.256 100 45 88 1 1255338000 101438832 9559120
02:43:02 24592 8192000000 10181809088 10590810112 1164064816 12.441 100 42 84 1 1300227376 126479120 9683440
02:44:33 8382 8192000000 10253450736 10671259648 1174624304 12.540 100 52 102 1 1367595392 181997136 10973952
02:45:45 1577 8192000000 10298394480 10702794752 1178441248 12.576 100 75 150 2 1404228912 208982320 16805344
02:46:51 20195 8192000000 10344316880 10755932160 1176832112 12.561 100 57 114 0 1438556016 248200800 13523104
02:48:17 4810 8192000000 10356578928 10781581312 1185339232 12.640 100 67 134 1 1466020944 265105120 15576592
02:49:26 5210 8192000000 10326303968 10741403648 1187202224 12.657 100 52 102 1 1432208336 233595952 11410160
02:50:39 4975 8192000000 10335450240 10743746560 1182378064 12.612 99 83 165 1 1436575552 234959920 19237568
02:51:49 2214 8192000000 10396607568 10808553472 1190849344 12.691 100 75 149 0 1499268896 290949360 17470192
02:52:55 3001 8192000000 10420360544 10823856128 1176416736 12.557 99 90 178 0 1510704256 314264384 20023136
02:54:00 11742 8192000000 10362625024 10797621248 1146127968 12.273 98 35 70 0 1450909216 295887728 8893520
02:55:13 3256 8192000000 10407879456 10819837952 1150890576 12.318 100 69 136 1 1498797888 331594448 16312864
02:56:21 11454 8192000000 10407239280 10848309248 1145610864 12.268 100 46 92 0 1511403616 353665984 12126768
02:57:34 8292 8192000000 10309857392 10740527104 1134129584 12.160 99 39 78 0 1398905664 255308912 9467168
02:58:45 7093 8192000000 10304101152 10728263680 1138378336 12.200 100 34 68 0 1400680576 253613248 8688992
02:59:53 42551 8192000000 10192168528 10657570816 1100591584 11.843 97 19 38 0 1298747536 193365376 4790576
03:01:41 42259 8192000000 10060576976 10528219136 1101470192 11.852 99 29 58 0 1219808336 111838832 6499312
03:03:27 17572 8192000000 10074246992 10501115904 1092556400 11.767 99 48 96 2 1166837744 63794832 10486512
03:04:53 38454 8192000000 10083877968 10542895104 1114810240 11.978 100 16 32 1 1208640144 89723392 4106512
03:06:41 43021 8192000000 10017718624 10488094720 1089743024 11.740 99 4 7 0 1129952896 39551072 658800
03:10:17 53034 8192000000 10037086080 10467299328 1117844064 12.007 99 52 99 1 1137906848 9154976 10907808
03:12:10 89486 8192000000 10084399648 10515943424 1125970240 12.083 99 24 47 1 1174146048 43413760 4762048
03:14:40 43461 8192000000 10115713984 10548625408 1111303968 11.945 97 23 46 1 1195441776 79058528 5079280
03:16:30 58972 8192000000 10118592832 10591133696 1142447136 12.239 100 14 27 0 1233614912 87865648 3302128
03:18:43 41365 8192000000 10105465344 10563448832 1141535632 12.230 100 13 26 0 1195569376 51828080 2205664
03:20:26 18612 8192000000 10070659904 10490531840 1134351536 12.162 100 36 72 3 1157119200 15620448 7147216
03:21:45 34628 8192000000 10151315200 10574778368 1136974272 12.187 99 50 100 0 1227014560 79934816 10105472
03:23:22 71537 8192000000 10133444288 10669359104 1134763536 12.166 99 18 36 1 1261287168 122400848 4122784
03:25:35 47730 8192000000 10092994144 10520457216 1146717936 12.279 100 33 64 1 1190419232 37098816 6602480
03:27:24 59496 8192000000 10118003808 10582188032 1132271984 12.143 99 26 50 1 1193266144 56004384 4989776
03:30:17 36984 8192000000 10165078528 10630516736 1150211072 12.311 100 24 48 0 1256669280 101336304 5121904
03:31:54 145661 8192000000 10129573728 10565730304 1140732992 12.222 99 22 42 3 1203891840 58465936 4692912
03:35:22 84877 8192000000 10116277616 10613112832 1134004160 12.159 98 12 24 0 1219475200 82892080 2578960
03:38:36 49344 8192000000 10074192704 10529648640 1134649568 12.165 99 20 40 1 1175282768 36242816 4390384
03:40:25 38352 8192000000 10062376608 10515509248 1103148976 11.868 96 8 16 0 1124882928 20503280 1230672
03:42:05 59087 8192000000 10107517248 10543046656 1082682272 11.673 95 16 32 1 1151328240 64702608 3943360
03:44:05 53032 8192000000 10076566640 10503430144 1070484672 11.557 95 25 50 0 1150467056 74603328 5379056
03:45:58 76172 8192000000 10026938224 10452520960 1023725120 11.108 92 16 32 0 1061004144 34251632 3027392
03:48:15 49227 8192000000 10061966640 10533097472 1082574272 11.672 97 24 48 2 1162915552 75442768 4898512
03:50:05 51326 8192000000 10126080320 10542993408 1103392304 11.870 98 22 44 0 1181045616 72864512 4788800
03:52:00 89724 8192000000 10059967040 10489987072 1127340896 12.096 100 20 40 1 1200133728 68966848 3825984
03:54:30 111876 8192000000 10046493536 10476986368 1090816528 11.750 96 25 50 0 1128840832 33250016 4774288
03:57:31 6765 8192000000 10054434640 10488750080 1108196656 11.915 99 41 83 1 1147709696 30758848 8754192
03:58:41 40567 8192000000 10047154624 10499571712 1110123248 11.934 99 9 16 1 1130188432 18531856 1533328
04:00:22 47161 8192000000 10098487552 10560749568 1144509808 12.258 100 11 22 1 1206084176 59240736 2333632
04:02:14 35211 8192000000 10041267696 10502471680 1105398880 11.889 97 14 29 0 1146204880 37775456 3030544
04:03:50 41764 8192000000 10112161520 10551500800 1131865584 12.139 99 28 53 3 1179725648 41920160 5939904
04:05:37 55687 8192000000 10144110256 10590597120 1147590928 12.287 99 21 42 0 1223338064 71338016 4409120
04:07:50 79436 8192000000 10082982160 10530340864 1126091056 12.084 98 23 46 1 1176355936 45449136 4815744
04:10:11 108910 8192000000 10124460256 10578530304 1130205568 12.123 98 19 38 1 1210973600 76673200 4094832
04:13:11 16957 8192000000 10097143904 10519965696 1137388624 12.191 99 33 66 0 1165175968 21613472 6173872
04:14:29 76195 8192000000 10091320672 10550681600 1118320864 12.011 98 41 80 0 1207019024 80524048 8174112
04:16:55 68086 8192000000 10090293120 10579828736 1114745536 11.977 98 8 16 0 1185338640 69051136 1541968
04:19:09 48243 8192000000 10071387104 10540056576 1115336784 11.983 98 13 26 0 1172720416 54300944 3082688
04:21:07 54097 8192000000 10092389824 10543525888 1112001488 11.951 97 18 36 0 1172134016 56547008 3585520
04:23:03 31070 8192000000 10144383184 10584649728 1119613056 12.023 98 31 62 0 1208751280 82825328 6312896
04:24:34 70793 8192000000 10077516128 10581176320 1104745472 11.883 97 17 34 0 1168591568 59840224 4005872
04:26:45 28266 8192000000 10132780032 10558877696 1135896832 12.177 99 55 110 0 1197910816 51239072 10774912
04:28:18 35238 8192000000 10135705104 10596036608 1137474848 12.192 99 26 52 1 1221690960 79194064 5022048
04:29:54 83964 8192000000 10105590592 10524663808 1088703840 11.730 95 28 56 0 1140355200 46232688 5418672
04:32:21 129077 8192000000 10079688240 10557521920 1133954192 12.159 99 19 38 1 1182637328 44637408 4045728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 04:38:51 to 05-11-2021 04:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2021 04:39:07 to 05-11-2021 04:39:09.

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          235785
MON$OLDEST_ACTIVE               235786
MON$OLDEST_SNAPSHOT             235786
MON$NEXT_TRANSACTION            235786
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                       2021760
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2652 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2652 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2652 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2652 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Nov  5 04:37:12 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Fri Nov  5 04:37:12 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Fri Nov  5 04:37:15 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

05.11.21 04:39:10. 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-11-05 04:39:12 No severe PSQL-related problems occured
05.11.21 04:39:13. End of report.