Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.09.2021 10: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 15889756 kB
MemAvailable 30903728 kB
Buffers 0 kB
Cached 15825832 kB
SwapCached 340 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-08 10:37:07
DTS_END                         2021-09-08 10:37:07.1040
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/45412
TRN_ID                          243638
ATT_ID                          634
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: 9141.54 20530 1096985
customer order: creation 180.46 376 21655
customer order: refuse 14.94 349 1793
order to supplier: creation 48.52 1413 5822
order to supplier: removal 3.50 107774 420
invoice (draft): creation 47.88 3721 5745
invoice (draft): removal 50.74 1112 6089
invoice accept: apply 43.19 20103 5183
invoice accept: cancel 44.83 902 5379
customer reserve: creation 4021.04 198 482525
customer reserve: removal 4382.58 65 525910
realization accept: apply 88.04 206 10565
realization accept: cancel 82.35 116 9882
payment from customer: creation 61.58 149 7390
payment from customer: removal 5.88 85 706
payment to supplier: creation 51.48 134 6177
payment to supplier: removal 3.43 61 412
service: total inventory turnovers 3.76 3868 451
service: total monetary turnovers 6.36 508 763
service: aggregate perf. data 0.95 12201 114
service: refresh index statistics 0.03 257268 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-09-2021 10:38:39 to 08-09-2021 10:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5780
TEST_TIME 2 4722
TEST_TIME 3 5143
TEST_TIME 4 5348
TEST_TIME 5 5956
TEST_TIME 6 6192
TEST_TIME 7 6043
TEST_TIME 8 6241
TEST_TIME 9 6106
TEST_TIME 10 6466
TEST_TIME 11 6897
TEST_TIME 12 6991
TEST_TIME 13 7807
TEST_TIME 14 8393
TEST_TIME 15 8317
TEST_TIME 16 8167
TEST_TIME 17 8668
TEST_TIME 18 9090
TEST_TIME 19 9228
TEST_TIME 20 9455
TEST_TIME 21 9410
TEST_TIME 22 9526
TEST_TIME 23 9329
TEST_TIME 24 9551
TEST_TIME 25 9672
TEST_TIME 26 10987
TEST_TIME 27 11471
TEST_TIME 28 11101
TEST_TIME 29 10804
TEST_TIME 30 10982
TEST_TIME 31 10711
TEST_TIME 32 10427
TEST_TIME 33 10878
TEST_TIME 34 10858
TEST_TIME 35 10855
TEST_TIME 36 10756
TEST_TIME 37 10606
TEST_TIME 38 10716
TEST_TIME 39 10711
TEST_TIME 40 10568
TEST_TIME 41 10612
TEST_TIME 42 10530
TEST_TIME 43 10642
TEST_TIME 44 10606
TEST_TIME 45 10520
TEST_TIME 46 10339
TEST_TIME 47 10199
TEST_TIME 48 10146
TEST_TIME 49 10101
TEST_TIME 50 10032
TEST_TIME 51 10044
TEST_TIME 52 9954
TEST_TIME 53 9909
TEST_TIME 54 9908
TEST_TIME 55 9937
TEST_TIME 56 9842
TEST_TIME 57 9802
TEST_TIME 58 9663
TEST_TIME 59 9641
TEST_TIME 60 9735
TEST_TIME 61 9950
TEST_TIME 62 9905
TEST_TIME 63 9943
TEST_TIME 64 9884
TEST_TIME 65 9869
TEST_TIME 66 9995
TEST_TIME 67 9944
TEST_TIME 68 9950
TEST_TIME 69 9985
TEST_TIME 70 9944
TEST_TIME 71 10011
TEST_TIME 72 9983
TEST_TIME 73 9874
TEST_TIME 74 9851
TEST_TIME 75 9795
TEST_TIME 76 9748
TEST_TIME 77 9757
TEST_TIME 78 9701
TEST_TIME 79 9647
TEST_TIME 80 9597
TEST_TIME 81 9538
TEST_TIME 82 9522
TEST_TIME 83 9511
TEST_TIME 84 9502
TEST_TIME 85 9509
TEST_TIME 86 9510
TEST_TIME 87 9535
TEST_TIME 88 9550
TEST_TIME 89 9527
TEST_TIME 90 9579
TEST_TIME 91 9608
TEST_TIME 92 9597
TEST_TIME 93 9614
TEST_TIME 94 9576
TEST_TIME 95 9524
TEST_TIME 96 9540
TEST_TIME 97 9491
TEST_TIME 98 9485
TEST_TIME 99 9415
TEST_TIME 100 9373
TEST_TIME 101 9332
TEST_TIME 102 9318
TEST_TIME 103 9308
TEST_TIME 104 9282
TEST_TIME 105 9315
TEST_TIME 106 9305
TEST_TIME 107 9337
TEST_TIME 108 9347
TEST_TIME 109 9339
TEST_TIME 110 9327
TEST_TIME 111 9329
TEST_TIME 112 9320
TEST_TIME 113 9326
TEST_TIME 114 9327
TEST_TIME 115 9277
TEST_TIME 116 9290
TEST_TIME 117 9240
TEST_TIME 118 9222
TEST_TIME 119 9218
TEST_TIME 120 9145

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-09-2021 10:38:43 to 08-09-2021 10:38:46.

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 1543215 1543215 0 0.00 0 16112 7 0 0
doc_list_biud 1543215 1543215 0 0.00 0 41052 29 0 0
sp_add_invoice_to_stock 5297 5183 114 2.15 44 269775 20104 0 114
sp_cancel_adding_invoice 5379 5379 0 0.00 6 76359 902 0 0
sp_cancel_client_order 1793 1793 0 0.00 5 18385 350 0 0
sp_cancel_customer_reserve 525910 525910 0 0.00 1 28301 66 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 6763 85 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 1482 61 0 0
sp_cancel_supplier_invoice 6102 6089 13 0.21 3 30550 1112 0 13
sp_cancel_supplier_order 420 420 0 0.00 7 1032063 107774 0 0
sp_cancel_write_off 9882 9882 0 0.00 1 10650 117 0 0
sp_client_order 21655 21655 0 0.00 4 19608 376 0 0
sp_customer_reserve 482525 482525 0 0.00 0 47366 198 0 0
sp_fill_shopping_cart 33222 33222 0 0.00 1 15458 48 0 0
sp_fill_shopping_cart_clo_res 482516 482516 0 0.00 0 12869 14 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 30552 333 0 0
sp_kill_cost_storno 16379 16379 0 0.00 0 10122 65 0 0
sp_kill_qstorno_ret_qs2qd 1068211 1068211 0 0.00 0 30516 37 0 0
sp_kill_qty_storno 565204 565204 0 0.00 0 41052 79 0 0
sp_lock_selected_doc 21775 21775 0 0.00 0 13219 5 0 0
sp_make_cost_storno 29298 29298 0 0.00 0 12625 56 0 0
sp_multiply_rows_for_pdistr 29298 29298 0 0.00 0 10685 33 0 0
sp_multiply_rows_for_qdistr 21655 21655 0 0.00 0 12281 178 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 27786 149 0 0
sp_pay_to_supplier 6177 6177 0 0.00 5 17372 134 0 0
sp_payment_common 13567 13567 0 0.00 1 10861 32 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 11834 293 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 41051 366 0 0
sp_qd_handle_on_reserve_upd_sts 10565 10565 0 0.00 0 15157 60 0 0
sp_reserve_write_off 10565 10565 0 0.00 7 18301 206 0 0
sp_supplier_invoice 5745 5745 0 0.00 9 81712 3721 0 0
sp_supplier_order 5822 5822 0 0.00 5 31541 1414 0 0
srv_aggregate_perf_data 114 114 0 0.00 3145 71521 12202 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1602 144977 40016 0 0
srv_find_qd_qs_mism 1046869 1046869 0 0.00 0 14808 7 0 0
srv_make_invnt_saldo 451 451 0 0.00 44 77430 3869 0 0
srv_make_money_saldo 763 763 0 0.00 1 13797 509 0 0
srv_recalc_idx_stat 4 4 0 0.00 170319 466430 257269 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5183 5183 0 0.00 0 13171 157 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 3510 79 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 582 6 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 436 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 5282 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 367 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 459 459 0 0.00 0 1082 16 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 19321 1436 0 0
t$perf-norm:sp_cancel_write_off 1107 1107 0 0.00 0 1069 6 0 0
t$perf-norm:sp_client_order 21655 21655 0 0.00 0 12818 17 0 0
t$perf-norm:sp_customer_reserve 1120 1120 0 0.00 0 842 4 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 8049 12 0 0
t$perf-norm:sp_pay_to_supplier 6177 6177 0 0.00 0 7527 10 0 0
t$perf-norm:sp_reserve_write_off 10565 10565 0 0.00 0 11614 9 0 0
t$perf-norm:sp_supplier_invoice 5745 5745 0 0.00 0 7294 15 0 0
t$perf-norm:sp_supplier_order 5822 5822 0 0.00 0 9154 14 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1082 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 140 3 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 2059 10 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 646 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 155 41 0 0
v_add_invoice_to_stock 10560 10332 228 2.15 0 17919 22 0 228
v_all_customers 39560 39560 0 0.00 0 18 1 0 0
v_all_suppliers 32240 32240 0 0.00 0 20 1 0 0
v_all_wares 43310 43310 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 3671 20 0 0
v_cancel_client_order 3586 3586 0 0.00 0 17806 20 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 6618 12 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 3789 15 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1446 5 0 0
v_cancel_supplier_invoice 944 918 26 2.75 0 2826 17 0 26
v_cancel_supplier_order 840 840 0 0.00 0 4116 12 0 0
v_cancel_write_off 2214 2214 0 0.00 0 5924 17 0 0
v_max_id_clo_ord 5822 5822 0 0.00 0 7576 4 0 0
v_max_id_clo_res 1120 1120 0 0.00 0 2091 15 0 0
v_max_id_ord_sup 5745 5745 0 0.00 0 7530 6 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 921 2 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 10268 8 0 0
v_min_id_clo_ord 5822 5822 0 0.00 0 9501 13 0 0
v_min_id_clo_res 1120 1120 0 0.00 0 6230 28 0 0
v_min_id_ord_sup 5745 5745 0 0.00 0 8444 13 0 0
v_min_non_paid_invoice 6177 6177 0 0.00 0 6954 18 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 10621 28 0 0
v_our_firm 10768 10768 0 0.00 0 16 1 0 0
v_reserve_write_off 21130 21130 0 0.00 0 18050 14 0 0
x_lock_depdocs_on_canc_invoice 11009 11009 0 0.00 0 14336 117 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 9679 305 0 0
x_make_qstorno_1000_1200 5822 5822 0 0.00 1 29309 1325 0 0
x_make_qstorno_1200_2000 5745 5745 0 0.00 5 80746 3541 0 0
x_make_qstorno_2100_3300 482525 482525 0 0.00 0 47365 181 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-09-2021 10:38:47 to 08-09-2021 10:38:52.

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
08:07:10 1148 8192000000 8963067744 9028337664 10842816 0.132 3 1 2 0 10980080 101488 35776
08:08:12 25489 8192000000 9730901200 10030972928 805408064 8.951 100 86 172 0 834416848 7517728 21491056
08:09:44 17798 8192000000 9780994880 10089783296 850868768 9.409 100 91 182 0 885040592 12412848 21758976
08:11:17 14908 8192000000 9818464832 10142736384 889875056 9.798 100 85 170 0 933531904 24464272 19192576
08:12:36 13968 8192000000 9889367152 10224947200 933314784 10.227 100 83 166 0 1001250272 49873392 18062096
08:13:53 6985 8192000000 9937959936 10283999232 966934400 10.557 100 75 150 0 1044732256 61942464 15855392
08:15:07 5810 8192000000 9957599456 10312400896 1010775360 10.983 100 73 143 2 1067275232 42155136 14344736
08:16:14 2045 8192000000 10037879536 10401161216 1035754448 11.224 100 92 184 2 1146088144 92423952 17909744
08:17:17 1221 8192000000 10027807824 10391900160 1053096224 11.390 100 94 185 0 1133854640 61537120 19221296
08:18:20 4466 8192000000 10016661616 10393608192 1069832432 11.550 100 71 140 4 1134382336 49683184 14866720
08:19:24 6981 8192000000 9983411536 10375315456 1074709040 11.597 100 26 52 2 1096981312 16680544 5591728
08:20:37 6194 8192000000 9985789232 10390032384 1082325328 11.670 100 31 62 0 1110937552 21706288 6905936
08:21:46 5543 8192000000 10003632112 10401046528 1088684992 11.730 100 39 79 0 1116696848 19436704 8575152
08:22:53 30155 8192000000 9996937744 10416136192 1088348864 11.727 100 34 68 0 1124665440 28729792 7586784
08:24:25 4883 8192000000 10008701408 10408644608 1090140960 11.744 100 35 70 0 1130347088 32994816 7211312
08:25:31 5093 8192000000 10007072560 10412015616 1084602480 11.691 99 35 67 2 1115926144 23080112 8243552
08:26:38 8684 8192000000 10004987120 10409848832 1088581744 11.729 100 39 78 2 1130083088 32588752 8912592
08:27:49 28191 8192000000 10011912608 10415616000 1059602080 11.453 97 37 74 1 1083064128 15799200 7662848
08:29:22 3237 8192000000 9950716688 10316918784 1013499472 11.009 97 66 133 2 1047590736 19485680 14605584
08:30:28 5459 8192000000 9939942352 10321195008 987228672 10.755 96 39 78 1 1015566848 20138704 8199472
08:31:36 80974 8192000000 9956144736 10351026176 985845184 10.741 96 32 64 1 1008912848 16087504 6980160
08:33:58 4214 8192000000 9969483104 10362638336 1048590416 11.347 100 62 121 2 1090989968 28559328 13840224
08:35:03 5154 8192000000 9990273952 10381402112 1062163296 11.477 100 66 130 1 1098235776 20976368 15096112
08:36:09 110437 8192000000 10031789616 10428923904 1076477568 11.614 100 52 105 0 1110512432 22468896 11565968
08:39:04 32057 8192000000 10066630768 10465849344 1109773312 11.930 100 34 68 1 1167817360 50926000 7118048
08:40:38 9605 8192000000 10120237040 10524856320 1131425296 12.135 100 56 112 1 1230113312 86834224 11853792
08:41:52 12900 8192000000 10151594768 10562568192 1140216160 12.218 100 50 100 1 1259231008 107381152 11633696
08:43:13 13734 8192000000 10181732000 10601553920 1145495136 12.267 100 61 121 0 1280769008 121557584 13716288
08:44:30 9700 8192000000 10188600816 10604105728 1153768304 12.345 100 54 103 4 1291898304 126813024 11316976
08:45:45 6214 8192000000 10199696208 10614837248 1152899008 12.337 100 59 118 0 1304753424 137954336 13900080
08:46:57 8706 8192000000 10217748608 10630062080 1162672336 12.428 100 58 116 0 1325466400 150343120 12450944
08:48:10 1808 8192000000 10285933792 10696953856 1173963440 12.534 100 89 176 1 1388838240 194982256 19892544
08:49:14 4293 8192000000 10313744816 10716585984 1159508528 12.399 99 82 162 1 1400175280 222139088 18527664
08:50:26 1600 8192000000 10303275840 10706915328 1166455664 12.464 100 88 175 0 1398827504 212808976 19562864
08:51:31 11498 8192000000 10305101216 10746458112 1157252176 12.378 100 49 98 1 1420650016 251420992 11976848
08:52:53 4452 8192000000 10308418160 10714361856 1134438288 12.163 100 56 111 1 1400489728 253268496 12782944
08:54:06 1937 8192000000 10382698928 10780110848 1142400288 12.238 100 88 175 2 1474666016 311356016 20909712
08:55:12 3123 8192000000 10350515040 10789138432 1153175712 12.339 100 64 128 0 1462829680 293713376 15940592
08:56:17 4845 8192000000 10335274160 10748329984 1152941472 12.337 100 62 124 1 1430107168 262637168 14528528
08:57:32 11252 8192000000 10313392096 10770722816 1144735264 12.260 99 34 68 1 1409109488 256041760 8332464
08:58:49 9429 8192000000 10345948368 10781585408 1152645216 12.334 100 36 72 1 1429991088 268419824 8926048
09:00:01 21597 8192000000 10380954288 10814799872 1149557920 12.305 100 30 60 1 1470161456 312637808 7965728
09:01:24 18679 8192000000 10383644544 10834792448 1116929632 11.998 98 36 72 0 1486488240 359412080 10146528
09:02:44 19062 8192000000 10388531344 10840514560 1129855952 12.120 100 42 83 3 1491154304 350199056 11099296
09:04:12 32407 8192000000 10128341136 10672119808 1131917568 12.139 100 24 48 0 1237785408 100908480 4959360
09:06:05 50392 8192000000 10137046928 10561470464 1096416720 11.804 97 36 72 1 1192234736 87601472 8216544
09:08:03 50839 8192000000 10170214736 10613026816 1121704784 12.043 100 13 26 1 1273025392 148705104 2615504
09:09:55 57782 8192000000 10137598064 10615988224 1128304928 12.105 99 31 61 1 1285620224 150637840 6677456
09:12:51 16188 8192000000 10088876592 10524667904 1132143472 12.142 100 41 83 0 1163872640 23592656 8136512
09:14:21 79863 8192000000 10095267216 10517843968 1130662800 12.128 99 16 32 0 1226207504 91873392 3671312
09:16:44 55492 8192000000 10079369664 10501636096 1129401024 12.116 99 38 76 1 1158291168 21287776 7602368
09:18:40 144977 8192000000 10088106272 10579890176 1117756112 12.006 97 6 12 0 1188433472 69460688 1216672
09:22:06 36521 8192000000 10110144112 10539876352 1137097520 12.188 99 46 92 0 1175918864 29178768 9642576
09:23:52 66752 8192000000 10089352240 10534813696 1148384464 12.294 100 24 48 0 1190343056 37067744 4890848
09:26:04 16031 8192000000 10106387808 10539954176 1157682288 12.382 100 77 154 0 1203299632 28539264 17078080
09:27:23 39355 8192000000 10081567984 10510991360 1122999440 12.055 97 41 82 1 1161470720 30103248 8368032
09:29:04 46181 8192000000 10124119872 10556825600 1157619344 12.381 100 43 86 0 1202323296 34992432 9711520
09:30:53 59538 8192000000 10128783744 10591219712 1133470016 12.154 99 20 40 0 1218086832 80012848 4603968
09:32:58 66659 8192000000 10135666240 10593419264 1117078688 11.999 97 13 26 1 1174186304 54102576 3005040
09:35:58 54717 8192000000 10205132896 10692558848 1158011776 12.385 100 31 62 1 1313898672 149049360 6837536
09:38:00 64294 8192000000 10107173408 10674667520 1141547472 12.230 99 15 30 0 1265486272 120365344 3573456
09:40:05 26763 8192000000 10052408320 10519986176 1077348528 11.622 95 18 36 0 1131717952 50089552 4279872
09:41:34 20113 8192000000 10110314528 10551341056 1126760672 12.091 100 56 112 0 1190415488 52124576 11530240
09:42:58 30797 8192000000 10124341904 10573197312 1104596400 11.881 98 52 105 1 1213875152 97678848 11599904
09:44:30 18622 8192000000 10075340656 10501132288 1105369328 11.889 99 31 60 1 1149229664 37334816 6525520
09:45:50 49833 8192000000 10060926272 10531045376 1097446928 11.813 99 28 56 1 1171703040 68520240 5735872
09:47:50 23492 8192000000 10100635056 10532298752 1109200800 11.925 99 46 91 2 1176836608 58441568 9194240
09:49:14 50402 8192000000 10070734336 10513203200 1121778720 12.044 100 30 60 0 1156799152 29555232 5465200
09:51:04 45731 8192000000 10074261616 10530181120 1113287616 11.964 99 48 97 1 1169866208 45837872 10740720
09:52:53 31389 8192000000 10067420368 10509058048 1101722576 11.854 98 35 70 1 1157952048 48974800 7254672
09:54:26 97926 8192000000 10032774800 10501021696 1085017840 11.695 98 7 14 0 1112568304 26096560 1453904
09:57:04 52884 8192000000 10033849328 10498867200 1081999200 11.667 98 12 24 0 1122560672 37732752 2828720
09:59:16 60315 8192000000 10045711600 10556362752 1101984640 11.856 99 10 20 1 1161236416 57261696 1990080
10:01:17 33775 8192000000 10117737808 10567016448 1105888400 11.893 98 21 42 1 1192386960 81509136 4989424
10:02:54 60942 8192000000 10093652752 10538049536 1120896896 12.035 100 15 30 3 1182877008 58865760 3114352
10:05:01 50114 8192000000 10056542224 10499039232 1118885424 12.016 99 13 26 1 1178835904 57356400 2594080
10:06:51 32573 8192000000 10141656448 10601717760 1137822928 12.195 100 38 75 1 1234859232 88648304 8388000
10:08:37 22046 8192000000 10104834448 10554220544 1119141904 12.019 98 42 84 1 1181421104 54303264 7975936
10:10:00 57535 8192000000 10047942320 10477371392 1096459376 11.804 97 17 34 0 1147908736 48186416 3262944
10:12:01 140315 8192000000 10068881008 10520510464 1130903776 12.130 99 26 52 1 1204810912 67984160 5922976
10:15:35 52281 8192000000 10062844832 10534391808 1136190640 12.180 99 12 24 1 1179668336 41069184 2408512
10:17:27 22143 8192000000 10065155424 10523914240 1139234224 12.208 99 5 10 0 1171037792 30530544 1273024
10:18:49 69694 8192000000 10075958560 10560557056 1116623696 11.995 97 10 20 0 1179185376 60352720 2208960
10:21:00 52005 8192000000 10119087808 10581401600 1152878464 12.337 100 25 50 1 1224625088 65381088 6365536
10:23:15 37780 8192000000 10146825840 10606981120 1151682864 12.325 99 31 61 0 1219616464 61229248 6704352
10:25:18 70248 8192000000 10087154992 10537005056 1106109888 11.896 96 20 40 0 1136644032 26532560 4001584
10:27:51 32523 8192000000 10086898032 10517061632 1090080768 11.743 96 40 80 0 1139714192 41895856 7737568
10:29:30 36307 8192000000 10092400896 10531848192 1074660288 11.597 96 44 88 2 1133684480 50310944 8713248
10:31:09 41835 8192000000 10099809568 10534236160 1076082304 11.610 96 29 58 1 1156392528 74516848 5793376
10:33:51 86806 8192000000 10080778336 10540302336 1129838480 12.120 100 12 22 1 1181536896 49364368 2334048
10:36:24 58883 8192000000 9195004336 9489973248 338123664 3.963 31 1 2 0 352327456 14168384 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-09-2021 10:38:53 to 08-09-2021 10:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          244170
MON$OLDEST_ACTIVE               244171
MON$OLDEST_SNAPSHOT             244171
MON$NEXT_TRANSACTION            244171
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                       2069280
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             817
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2585 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2585 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2585 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2585 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 ---
--- end of diff output ---

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

08.09.21 10:39:12. 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-08 10:39:15 No severe PSQL-related problems occured
08.09.21 10:39:16. End of report.