Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2021 07:48
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 15536556 kB
MemAvailable 30672324 kB
Buffers 0 kB
Cached 16193436 kB
SwapCached 3704 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-10-22 07:47:02
DTS_END                         2021-10-22 07:47:02.6510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52132
TRN_ID                          229853
ATT_ID                          677
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: 9259.38 23414 1111125
customer order: creation 166.64 430 19997
customer order: refuse 14.64 383 1757
order to supplier: creation 44.54 1542 5345
order to supplier: removal 3.49 121046 419
invoice (draft): creation 45.37 3497 5444
invoice (draft): removal 53.92 994 6470
invoice accept: apply 40.48 18853 4857
invoice accept: cancel 48.92 837 5870
customer reserve: creation 3768.68 184 452242
customer reserve: removal 4781.74 68 573809
realization accept: apply 81.37 208 9764
realization accept: cancel 82.79 130 9935
payment from customer: creation 58.06 140 6967
payment from customer: removal 5.82 106 698
payment to supplier: creation 48.88 153 5866
payment to supplier: removal 3.04 76 365
service: total inventory turnovers 4.08 3385 490
service: total monetary turnovers 5.91 559 709
service: aggregate perf. data 0.98 12979 117
service: refresh index statistics 0.03 302712 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2021 07:48:49 to 22-10-2021 07:48:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6465
TEST_TIME 2 4432
TEST_TIME 3 5071
TEST_TIME 4 5582
TEST_TIME 5 6001
TEST_TIME 6 6081
TEST_TIME 7 6211
TEST_TIME 8 6309
TEST_TIME 9 6109
TEST_TIME 10 6028
TEST_TIME 11 6451
TEST_TIME 12 6817
TEST_TIME 13 6935
TEST_TIME 14 6868
TEST_TIME 15 6992
TEST_TIME 16 7152
TEST_TIME 17 7175
TEST_TIME 18 7347
TEST_TIME 19 8144
TEST_TIME 20 8276
TEST_TIME 21 8914
TEST_TIME 22 8914
TEST_TIME 23 9074
TEST_TIME 24 9742
TEST_TIME 25 9739
TEST_TIME 26 11532
TEST_TIME 27 11294
TEST_TIME 28 11370
TEST_TIME 29 11450
TEST_TIME 30 11327
TEST_TIME 31 11108
TEST_TIME 32 11092
TEST_TIME 33 11055
TEST_TIME 34 10986
TEST_TIME 35 11111
TEST_TIME 36 11122
TEST_TIME 37 11154
TEST_TIME 38 11104
TEST_TIME 39 11010
TEST_TIME 40 10990
TEST_TIME 41 10905
TEST_TIME 42 10716
TEST_TIME 43 10533
TEST_TIME 44 10487
TEST_TIME 45 10333
TEST_TIME 46 10324
TEST_TIME 47 10237
TEST_TIME 48 10234
TEST_TIME 49 10167
TEST_TIME 50 10255
TEST_TIME 51 10136
TEST_TIME 52 10035
TEST_TIME 53 9984
TEST_TIME 54 9987
TEST_TIME 55 9967
TEST_TIME 56 9956
TEST_TIME 57 9953
TEST_TIME 58 9976
TEST_TIME 59 9919
TEST_TIME 60 9945
TEST_TIME 61 9970
TEST_TIME 62 10002
TEST_TIME 63 10062
TEST_TIME 64 10048
TEST_TIME 65 10121
TEST_TIME 66 10107
TEST_TIME 67 10174
TEST_TIME 68 10107
TEST_TIME 69 10140
TEST_TIME 70 10018
TEST_TIME 71 9940
TEST_TIME 72 9873
TEST_TIME 73 9843
TEST_TIME 74 9840
TEST_TIME 75 9778
TEST_TIME 76 9713
TEST_TIME 77 9669
TEST_TIME 78 9608
TEST_TIME 79 9559
TEST_TIME 80 9587
TEST_TIME 81 9611
TEST_TIME 82 9703
TEST_TIME 83 9652
TEST_TIME 84 9647
TEST_TIME 85 9761
TEST_TIME 86 9738
TEST_TIME 87 9692
TEST_TIME 88 9811
TEST_TIME 89 9852
TEST_TIME 90 9800
TEST_TIME 91 9740
TEST_TIME 92 9697
TEST_TIME 93 9673
TEST_TIME 94 9683
TEST_TIME 95 9630
TEST_TIME 96 9611
TEST_TIME 97 9566
TEST_TIME 98 9503
TEST_TIME 99 9489
TEST_TIME 100 9511
TEST_TIME 101 9468
TEST_TIME 102 9434
TEST_TIME 103 9509
TEST_TIME 104 9484
TEST_TIME 105 9482
TEST_TIME 106 9529
TEST_TIME 107 9516
TEST_TIME 108 9533
TEST_TIME 109 9571
TEST_TIME 110 9579
TEST_TIME 111 9604
TEST_TIME 112 9573
TEST_TIME 113 9493
TEST_TIME 114 9436
TEST_TIME 115 9435
TEST_TIME 116 9399
TEST_TIME 117 9348
TEST_TIME 118 9333
TEST_TIME 119 9320
TEST_TIME 120 9266

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 07:48:53 to 22-10-2021 07:48:56.

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 1529965 1529965 0 0.00 0 22137 7 0 0
doc_list_biud 1529963 1529963 0 0.00 0 51561 33 0 0
sp_add_invoice_to_stock 4950 4857 93 1.87 51 178302 18853 0 93
sp_cancel_adding_invoice 5870 5870 0 0.00 6 58881 838 0 0
sp_cancel_client_order 1757 1757 0 0.00 5 12953 384 0 0
sp_cancel_customer_reserve 573809 573809 0 0.00 1 51563 69 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 6316 106 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 2962 76 0 0
sp_cancel_supplier_invoice 6474 6470 4 0.06 3 21743 994 0 4
sp_cancel_supplier_order 419 419 0 0.00 6 1242869 121046 0 0
sp_cancel_write_off 9935 9935 0 0.00 1 19900 130 0 0
sp_client_order 19997 19997 0 0.00 4 35560 431 0 0
sp_customer_reserve 452242 452242 0 0.00 0 69316 184 0 0
sp_fill_shopping_cart 30767 30767 0 0.00 1 12564 45 0 0
sp_fill_shopping_cart_clo_res 452232 452232 0 0.00 0 17230 17 0 0
sp_get_clo_for_invoice 4844 4844 0 0.00 0 22089 378 0 0
sp_kill_cost_storno 16868 16868 0 0.00 0 9226 66 0 0
sp_kill_qstorno_ret_qs2qd 1164442 1164442 0 0.00 0 51546 37 0 0
sp_kill_qty_storno 612867 612867 0 0.00 0 51560 80 0 0
sp_lock_selected_doc 20334 20334 0 0.00 0 12984 8 0 0
sp_make_cost_storno 27441 27441 0 0.00 0 17874 67 0 0
sp_multiply_rows_for_pdistr 27440 27440 0 0.00 0 9543 36 0 0
sp_multiply_rows_for_qdistr 19997 19997 0 0.00 0 18559 203 0 0
sp_pay_from_customer 6967 6967 0 0.00 4 21739 141 0 0
sp_pay_to_supplier 5866 5866 0 0.00 5 16266 154 0 0
sp_payment_common 12833 12833 0 0.00 1 7509 25 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 12894 340 0 0
sp_qd_handle_on_invoice_upd_sts 10713 10713 0 0.00 0 19560 374 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 35064 59 0 0
sp_reserve_write_off 9764 9764 0 0.00 7 35081 209 0 0
sp_supplier_invoice 5444 5444 0 0.00 9 84053 3498 0 0
sp_supplier_order 5345 5345 0 0.00 6 75806 1543 0 0
srv_aggregate_perf_data 117 117 0 0.00 2787 136595 12979 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 844 107389 41889 0 0
srv_find_qd_qs_mism 1064478 1064478 0 0.00 0 22135 6 0 0
srv_make_invnt_saldo 490 490 0 0.00 51 75477 3386 0 0
srv_make_money_saldo 709 709 0 0.00 1 18099 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 145516 461460 302713 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 0 28130 152 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 5576 111 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 10454 16 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 488 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 7575 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 189 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 174 5 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 24059 1490 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 4503 14 0 0
t$perf-norm:sp_client_order 19997 19997 0 0.00 0 16433 20 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 3445 17 0 0
t$perf-norm:sp_pay_from_customer 6967 6967 0 0.00 0 6023 10 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 5229 9 0 0
t$perf-norm:sp_reserve_write_off 9764 9764 0 0.00 0 18876 13 0 0
t$perf-norm:sp_supplier_invoice 5444 5444 0 0.00 0 5500 19 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 3709 15 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 438 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2451 38 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 916 8 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 19775 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 101 28 0 0
v_add_invoice_to_stock 9870 9684 186 1.88 0 19716 21 0 186
v_all_customers 36710 36710 0 0.00 0 21 1 0 0
v_all_suppliers 30280 30280 0 0.00 0 26 1 0 0
v_all_wares 39992 39992 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 948 7 0 0
v_cancel_client_order 3514 3514 0 0.00 0 3132 11 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2922 11 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1548 5 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 2907 9 0 0
v_cancel_supplier_invoice 830 822 8 0.96 0 1981 17 0 8
v_cancel_supplier_order 838 838 0 0.00 0 935 5 0 0
v_cancel_write_off 2048 2048 0 0.00 0 16100 22 0 0
v_max_id_clo_ord 5337 5337 0 0.00 0 1459 2 0 0
v_max_id_clo_res 916 916 0 0.00 0 6534 24 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 4227 6 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 208 2 0 0
v_max_non_paid_realizn 3619 3619 0 0.00 0 2579 4 0 0
v_min_id_clo_ord 5337 5337 0 0.00 0 3154 7 0 0
v_min_id_clo_res 916 916 0 0.00 0 4273 8 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 6600 15 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 6111 15 0 0
v_min_non_paid_realizn 6967 6967 0 0.00 0 5977 19 0 0
v_our_firm 9978 9978 0 0.00 0 17 1 0 0
v_reserve_write_off 19528 19528 0 0.00 0 7729 9 0 0
x_lock_depdocs_on_canc_invoice 11929 11929 0 0.00 0 13738 108 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5826 317 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 2 75799 1456 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 4 84043 3330 0 0
x_make_qstorno_2100_3300 452242 452242 0 0.00 0 69314 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2021 07:48:57 to 22-10-2021 07:49:01.

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
05:17:06 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:06 37409 8192000000 9734058864 10030800896 805013824 8.947 100 96 192 0 836755200 8031728 23709648
05:19:50 30117 8192000000 9767013824 10076471296 839758976 9.297 100 89 178 0 874446240 13230000 21457264
05:21:23 19137 8192000000 9828900240 10152169472 883649312 9.736 100 83 165 0 928738032 26913344 18175376
05:22:47 14216 8192000000 9890460240 10227793920 937189968 10.265 100 77 154 0 1000252752 46563856 16498928
05:24:02 6309 8192000000 9946551312 10296217600 978097056 10.666 100 81 161 1 1064842016 70067936 16677024
05:25:13 5517 8192000000 9970960368 10332368896 1005342912 10.930 100 71 143 2 1100144080 80198384 14602784
05:26:21 3070 8192000000 10005056896 10374713344 1038852224 11.254 100 74 148 1 1115131744 61489840 14789680
05:27:26 1930 8192000000 10026648304 10395668480 1056002416 11.418 100 88 176 0 1136893696 62941920 17949360
05:28:31 1756 8192000000 10047296416 10422435840 1068645920 11.539 100 87 174 0 1153553152 66146992 18760240
05:29:34 2452 8192000000 10016094192 10388963328 1073878976 11.589 100 89 177 1 1132358080 39159840 19319264
05:30:38 8367 8192000000 10004472880 10405289984 1075164304 11.601 100 31 61 1 1130614528 49010704 6439520
05:31:46 8957 8192000000 9994155168 10398232576 1084008960 11.686 100 45 88 1 1117608720 23474224 10125536
05:32:57 3859 8192000000 10021219008 10407456768 1091028112 11.752 100 55 108 1 1128534576 26198080 11308384
05:34:02 21916 8192000000 10034046160 10417520640 1096572512 11.805 100 83 169 1 1138482672 23332432 18577728
05:35:24 6086 8192000000 10020524192 10421719040 1098626880 11.825 100 36 72 0 1136909952 31749984 6533088
05:36:31 7982 8192000000 10022934064 10430906368 1097264336 11.812 100 37 70 2 1126838176 22073168 7500672
05:37:42 6274 8192000000 10026790352 10432528384 1081417600 11.661 99 28 55 4 1109887936 22307456 6162880
05:38:49 59011 8192000000 9975080656 10382307328 1006625232 10.943 94 22 44 1 1041726976 29988800 5112944
05:40:55 6246 8192000000 9945686864 10326671360 1003846192 10.916 97 38 75 1 1039755312 27551248 8357872
05:42:02 6586 8192000000 9944115520 10322128896 1016967456 11.043 99 48 94 4 1050626928 22660704 10998768
05:43:11 4620 8192000000 9940779264 10318155776 1009885664 10.974 98 50 102 1 1036013152 14566432 11561056
05:44:18 24738 8192000000 9974474608 10369585152 1044742752 11.310 100 27 52 3 1073568576 23319040 5506784
05:45:45 5061 8192000000 9985771200 10371244032 1060685328 11.463 100 62 126 0 1097251040 22632032 13933680
05:46:52 5816 8192000000 9998310880 10403053568 1068931408 11.542 100 30 61 1 1109359056 34068976 6358672
05:48:04 7478 8192000000 10015978720 10405294080 1089381504 11.737 100 46 91 0 1119883536 21446448 9055584
05:49:13 62857 8192000000 10072422496 10481319936 1104879184 11.884 100 36 72 1 1201318416 89006736 7432496
05:51:20 39847 8192000000 10124187968 10552315904 1115480416 11.984 100 49 98 1 1254024832 127839360 10705056
05:53:07 8874 8192000000 10145810432 10550693888 1136693376 12.184 100 55 109 2 1248756784 99770528 12292880
05:54:19 5454 8192000000 10192520496 10599276544 1152129744 12.329 100 68 135 1 1304758208 137993600 14634864
05:55:28 15644 8192000000 10222318960 10632970240 1162777072 12.429 100 69 136 0 1327016336 149077184 15162080
05:56:51 14247 8192000000 10279145104 10696085504 1179223344 12.583 100 54 107 2 1389191632 197938208 12030080
05:58:08 2782 8192000000 10298287888 10713407488 1184410864 12.631 100 65 129 1 1405937776 207325248 14201664
05:59:16 2034 8192000000 10350048192 10759393280 1195286688 12.733 100 70 137 1 1451079952 239964176 15829088
06:00:22 4205 8192000000 10395869072 10808827904 1202480448 12.799 100 67 133 0 1498358976 280053232 15825296
06:01:30 2118 8192000000 10427392896 10844352512 1202904416 12.803 100 80 155 1 1534159264 312914144 18340704
06:02:37 5017 8192000000 10500285920 10904330240 1212651104 12.894 100 92 179 2 1592288576 358583680 21053792
06:03:45 841 8192000000 10536759760 10940125184 1194862416 12.729 100 89 179 2 1633986624 418720464 20403744
06:04:52 3121 8192000000 10580966192 10990579712 1180857424 12.598 99 78 156 0 1674602704 474389776 19355504
06:06:01 2228 8192000000 10626548240 11038277632 1188639792 12.671 100 86 172 0 1719880112 509672464 21567856
06:07:10 2681 8192000000 10526400080 10947063808 1164339536 12.444 99 60 120 0 1631585808 452160720 15085552
06:08:16 17770 8192000000 10479306064 10921480192 1153994240 12.347 100 37 74 1 1603748576 440000560 9753776
06:09:36 16280 8192000000 10487906320 10920755200 1141165536 12.226 100 31 62 0 1582580928 432631904 8783488
06:11:05 29976 8192000000 10448833056 10952855552 1136427456 12.182 100 26 52 0 1558455104 414964784 7062864
06:13:13 7798 8192000000 10287311488 10815717376 1136400992 12.182 100 28 56 0 1430498160 286909008 7188160
06:14:21 16906 8192000000 10140124176 10569719808 1137991904 12.197 100 52 104 0 1232942256 82967696 11982656
06:15:40 94376 8192000000 10077129424 10585137152 1104160288 11.877 97 3 6 0 1138513584 34077200 276096
06:18:15 51118 8192000000 10150747040 10578714624 1138195200 12.199 99 37 72 3 1216269744 69907792 8166752
06:20:13 17214 8192000000 10125052832 10543616000 1122345056 12.049 98 46 87 0 1198842688 68097584 8400048
06:21:31 82563 8192000000 10075989040 10561515520 1077477744 11.623 95 7 15 0 1149076560 70358544 1240272
06:23:56 43331 8192000000 10093003216 10559406080 1123954768 12.064 100 6 12 3 1179011072 54149664 906640
06:25:46 81486 8192000000 10090025664 10530734080 1082490864 11.671 95 9 18 0 1136641424 52464128 1686432
06:28:14 63832 8192000000 10061786752 10498174976 1116427072 11.993 98 26 50 1 1144273744 22246160 5600512
06:31:05 107389 8192000000 10068308448 10503278592 1070578480 11.558 94 28 56 0 1117912960 41389664 5944816
06:33:55 5382 8192000000 10086059680 10507075584 1104377280 11.879 98 61 122 3 1161398528 43329360 13691888
06:35:08 35074 8192000000 10126926784 10565124096 1112261616 11.954 98 45 89 1 1190342256 68373824 9706816
06:36:47 73681 8192000000 10080361632 10556182528 1129088992 12.113 99 26 52 1 1218497312 83387584 6020736
06:39:08 36329 8192000000 10103536016 10548137984 1133835456 12.158 100 12 24 0 1208020112 71587696 2596960
06:40:47 64296 8192000000 10077898416 10565853184 1123257808 12.058 99 6 12 0 1188275568 63768576 1249184
06:43:05 28425 8192000000 10090381536 10518155264 1135287808 12.171 100 23 47 0 1178298912 37993872 5017232
06:44:36 31379 8192000000 10076756288 10502307840 1111045488 11.942 98 37 74 0 1147214608 27971024 8198096
06:46:11 50117 8192000000 10089681072 10523721728 1125091136 12.075 99 16 32 0 1161880864 33593984 3195744
06:48:01 56612 8192000000 10070414368 10498732032 1109687472 11.929 97 13 27 1 1145868640 33730832 2450336
06:49:58 75727 8192000000 10094619072 10544775168 1102369824 11.860 97 12 24 0 1147242608 42615168 2257616
06:52:17 35268 8192000000 10090543808 10573983744 1129115504 12.113 99 16 32 0 1188937008 56485216 3336288
06:53:52 46709 8192000000 10093618544 10586472448 1128214080 12.105 99 13 26 1 1210883216 79838720 2830416
06:55:39 40977 8192000000 10081938992 10540273664 1111178896 11.944 98 23 46 0 1168920224 53249280 4492048
06:57:26 82717 8192000000 10050452704 10482978816 1110520832 11.937 98 21 42 0 1146488256 31357376 4610048
06:59:51 17724 8192000000 10073899920 10525138944 1136515376 12.183 100 29 58 0 1174036576 31970864 5550336
07:01:33 66820 8192000000 10062697952 10516094976 1076964592 11.619 95 14 28 1 1125068832 45349456 2754784
07:03:45 40275 8192000000 10133979120 10578046976 1128347760 12.106 99 44 88 0 1214926736 76747392 9831584
07:05:30 53444 8192000000 10156396800 10627457024 1130782496 12.129 99 15 30 0 1251125920 116872016 3471408
07:08:04 50049 8192000000 10140273840 10582933504 1123900880 12.064 98 22 44 0 1220018368 91446480 4671008
07:09:55 40931 8192000000 10102625648 10549391360 1136989424 12.187 100 25 50 0 1196613856 54007664 5616768
07:11:37 56956 8192000000 10110387056 10574729216 1103241008 11.868 97 20 40 0 1213294480 105229968 4823504
07:13:35 45170 8192000000 10125778128 10573578240 1095510976 11.795 97 22 44 1 1177573360 77568928 4493456
07:15:22 46260 8192000000 10078955824 10570514432 1117110064 12.000 99 14 28 0 1201946256 82001072 2835120
07:17:09 66099 8192000000 10093965680 10516434944 1103788320 11.874 97 38 76 0 1156977232 45177664 8011248
07:19:23 87024 8192000000 10103825600 10554847232 1124211264 12.067 99 27 54 0 1198233536 68471056 5551216
07:21:54 63130 8192000000 10053079904 10515681280 1093376704 11.775 97 11 22 1 1151541488 55996672 2168112
07:24:02 55169 8192000000 10077290880 10526654464 1104262128 11.878 98 14 28 0 1147376928 40272080 2842720
07:25:58 104128 8192000000 10110116000 10592665600 1139960880 12.215 100 16 32 1 1222999840 78826224 4212736
07:28:42 30563 8192000000 10111845520 10553225216 1113160480 11.962 98 24 49 0 1184690656 66253168 5277008
07:30:26 36252 8192000000 10067528960 10498035712 1111223936 11.944 98 26 52 0 1167569248 51203920 5141392
07:32:05 64667 8192000000 10116590032 10612953088 1140568176 12.221 100 22 44 1 1246527184 101269072 4689936
07:34:17 32518 8192000000 10085075488 10510204928 1113026512 11.961 99 38 76 0 1151702848 31103744 7572592
07:35:57 42272 8192000000 10106081312 10560749568 1132146096 12.142 100 31 62 0 1188353600 49925040 6282464
07:37:41 99299 8192000000 10073313280 10548449280 1123511120 12.060 98 17 34 1 1173678528 46657392 3510016
07:40:57 58247 8192000000 10096001072 10527088640 1115496960 11.984 98 38 76 0 1167812944 44539504 7776480
07:42:56 68756 8192000000 10083440304 10550394880 1145189488 12.264 100 9 18 0 1186334064 39198432 1946144
07:45:09 66599 8192000000 10077375440 10553720832 1113074064 11.962 98 24 42 2 1164038112 46541424 4422624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-10-2021 07:49:03 to 22-10-2021 07:49:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2021 07:49:19 to 22-10-2021 07:49:21.

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          229541
MON$OLDEST_ACTIVE               230271
MON$OLDEST_SNAPSHOT             230271
MON$NEXT_TRANSACTION            230271
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                       2073120
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2637 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2637 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2637 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2637 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Oct 22 07:47:21 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

22.10.21 07:49:22. 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-10-22 07:49:25 No severe PSQL-related problems occured
22.10.21 07:49:26. End of report.