Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.08.2021 10:39
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 16105988 kB
MemAvailable 31031516 kB
Buffers 0 kB
Cached 15615620 kB
SwapCached 2600 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-08-18 10:37:06
DTS_END                         2021-08-18 10:37:06.0580
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/51738
TRN_ID                          229800
ATT_ID                          600
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: 9554.24 22928 1146509
customer order: creation 164.34 423 19721
customer order: refuse 14.06 347 1687
order to supplier: creation 43.01 1353 5161
order to supplier: removal 3.54 113970 425
invoice (draft): creation 42.48 3249 5097
invoice (draft): removal 56.85 863 6822
invoice accept: apply 40.23 18969 4828
invoice accept: cancel 51.98 809 6238
customer reserve: creation 3770.73 185 452487
customer reserve: removal 5080.92 63 609710
realization accept: apply 78.68 175 9442
realization accept: cancel 86.68 118 10402
payment from customer: creation 55.86 107 6703
payment from customer: removal 5.45 87 654
payment to supplier: creation 45.83 151 5499
payment to supplier: removal 3.35 72 402
service: total inventory turnovers 3.60 3377 432
service: total monetary turnovers 5.77 597 692
service: aggregate perf. data 0.86 20942 103
service: refresh index statistics 0.03 292709 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2021 10:39:30 to 18-08-2021 10:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8344
TEST_TIME 2 8382
TEST_TIME 3 7321
TEST_TIME 4 6664
TEST_TIME 5 6420
TEST_TIME 6 6111
TEST_TIME 7 6142
TEST_TIME 8 6755
TEST_TIME 9 6536
TEST_TIME 10 6440
TEST_TIME 11 6752
TEST_TIME 12 6979
TEST_TIME 13 7150
TEST_TIME 14 7697
TEST_TIME 15 8129
TEST_TIME 16 7965
TEST_TIME 17 8245
TEST_TIME 18 9140
TEST_TIME 19 9098
TEST_TIME 20 9295
TEST_TIME 21 9309
TEST_TIME 22 10061
TEST_TIME 23 9778
TEST_TIME 24 10610
TEST_TIME 25 10651
TEST_TIME 26 11861
TEST_TIME 27 11810
TEST_TIME 28 11863
TEST_TIME 29 11958
TEST_TIME 30 11816
TEST_TIME 31 11615
TEST_TIME 32 11348
TEST_TIME 33 11070
TEST_TIME 34 10972
TEST_TIME 35 10889
TEST_TIME 36 10893
TEST_TIME 37 10768
TEST_TIME 38 10777
TEST_TIME 39 10604
TEST_TIME 40 10613
TEST_TIME 41 10495
TEST_TIME 42 10537
TEST_TIME 43 10351
TEST_TIME 44 10459
TEST_TIME 45 10409
TEST_TIME 46 10387
TEST_TIME 47 10269
TEST_TIME 48 10262
TEST_TIME 49 10246
TEST_TIME 50 10343
TEST_TIME 51 10321
TEST_TIME 52 10287
TEST_TIME 53 10427
TEST_TIME 54 10348
TEST_TIME 55 10294
TEST_TIME 56 10334
TEST_TIME 57 10368
TEST_TIME 58 10238
TEST_TIME 59 10138
TEST_TIME 60 10173
TEST_TIME 61 10116
TEST_TIME 62 10032
TEST_TIME 63 9975
TEST_TIME 64 9977
TEST_TIME 65 9932
TEST_TIME 66 9888
TEST_TIME 67 9929
TEST_TIME 68 9900
TEST_TIME 69 9997
TEST_TIME 70 9936
TEST_TIME 71 9973
TEST_TIME 72 9963
TEST_TIME 73 9992
TEST_TIME 74 10059
TEST_TIME 75 10048
TEST_TIME 76 10102
TEST_TIME 77 10148
TEST_TIME 78 10097
TEST_TIME 79 10002
TEST_TIME 80 9993
TEST_TIME 81 9954
TEST_TIME 82 9873
TEST_TIME 83 9877
TEST_TIME 84 9824
TEST_TIME 85 9770
TEST_TIME 86 9783
TEST_TIME 87 9720
TEST_TIME 88 9678
TEST_TIME 89 9695
TEST_TIME 90 9653
TEST_TIME 91 9641
TEST_TIME 92 9736
TEST_TIME 93 9803
TEST_TIME 94 9759
TEST_TIME 95 9786
TEST_TIME 96 9812
TEST_TIME 97 9822
TEST_TIME 98 9850
TEST_TIME 99 9860
TEST_TIME 100 9854
TEST_TIME 101 9772
TEST_TIME 102 9721
TEST_TIME 103 9704
TEST_TIME 104 9653
TEST_TIME 105 9613
TEST_TIME 106 9596
TEST_TIME 107 9540
TEST_TIME 108 9506
TEST_TIME 109 9532
TEST_TIME 110 9525
TEST_TIME 111 9571
TEST_TIME 112 9570
TEST_TIME 113 9550
TEST_TIME 114 9595
TEST_TIME 115 9588
TEST_TIME 116 9612
TEST_TIME 117 9637
TEST_TIME 118 9612
TEST_TIME 119 9631
TEST_TIME 120 9561

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-08-2021 10:39:33 to 18-08-2021 10:39:37.

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 1566244 1566244 0 0.00 0 23525 7 0 0
doc_list_biud 1566244 1566244 0 0.00 0 64174 31 0 0
sp_add_invoice_to_stock 4962 4828 134 2.70 32 234116 18969 0 134
sp_cancel_adding_invoice 6238 6238 0 0.00 5 84472 810 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 17551 347 0 0
sp_cancel_customer_reserve 609710 609710 0 0.00 1 64177 63 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 9099 88 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 3442 73 0 0
sp_cancel_supplier_invoice 6829 6822 7 0.10 2 42306 863 0 7
sp_cancel_supplier_order 425 425 0 0.00 6 1082205 113971 0 0
sp_cancel_write_off 10402 10402 0 0.00 1 23526 118 0 0
sp_client_order 19721 19721 0 0.00 4 41588 424 0 0
sp_customer_reserve 452487 452487 0 0.00 0 95326 185 0 0
sp_fill_shopping_cart 29979 29979 0 0.00 1 12233 38 0 0
sp_fill_shopping_cart_clo_res 452484 452484 0 0.00 0 16956 13 0 0
sp_get_clo_for_invoice 4807 4807 0 0.00 0 33578 358 0 0
sp_kill_cost_storno 17696 17696 0 0.00 0 23523 53 0 0
sp_kill_qstorno_ret_qs2qd 1237069 1237069 0 0.00 0 64167 34 0 0
sp_kill_qty_storno 649529 649529 0 0.00 0 64173 73 0 0
sp_lock_selected_doc 19804 19804 0 0.00 0 9086 5 0 0
sp_make_cost_storno 26447 26447 0 0.00 0 15050 54 0 0
sp_multiply_rows_for_pdistr 26447 26447 0 0.00 0 7786 29 0 0
sp_multiply_rows_for_qdistr 19721 19721 0 0.00 0 41575 212 0 0
sp_pay_from_customer 6703 6703 0 0.00 4 17086 107 0 0
sp_pay_to_supplier 5499 5499 0 0.00 5 16642 152 0 0
sp_payment_common 12202 12202 0 0.00 1 9949 24 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 17541 310 0 0
sp_qd_handle_on_invoice_upd_sts 11041 11041 0 0.00 0 34246 348 0 0
sp_qd_handle_on_reserve_upd_sts 9442 9442 0 0.00 0 17623 54 0 0
sp_reserve_write_off 9442 9442 0 0.00 6 18214 176 0 0
sp_supplier_invoice 5097 5097 0 0.00 6 130396 3249 0 0
sp_supplier_order 5161 5161 0 0.00 6 56630 1353 0 0
srv_aggregate_perf_data 103 103 0 0.00 2998 533124 20943 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2435 196000 48753 0 0
srv_find_qd_qs_mism 1101259 1101259 0 0.00 0 14690 6 0 0
srv_make_invnt_saldo 432 432 0 0.00 49 88003 3377 0 0
srv_make_money_saldo 692 692 0 0.00 1 23912 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 128905 491198 292709 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 0 15369 169 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 6849 89 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 4740 10 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 916 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 9884 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2201 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 660 15 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 34869 1606 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 2778 12 0 0
t$perf-norm:sp_client_order 19721 19721 0 0.00 0 6114 16 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 902 4 0 0
t$perf-norm:sp_pay_from_customer 6703 6703 0 0.00 0 6314 9 0 0
t$perf-norm:sp_pay_to_supplier 5499 5499 0 0.00 0 5833 10 0 0
t$perf-norm:sp_reserve_write_off 9442 9442 0 0.00 0 5745 10 0 0
t$perf-norm:sp_supplier_invoice 5097 5097 0 0.00 0 12931 23 0 0
t$perf-norm:sp_supplier_order 5161 5161 0 0.00 0 5838 12 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 79 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 260 4 0 0
t$perf-norm:srv_make_money_saldo 692 692 0 0.00 0 1479 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9874 9606 268 2.71 0 10561 19 0 268
v_all_customers 36476 36476 0 0.00 0 29 1 0 0
v_all_suppliers 28562 28562 0 0.00 0 15 1 0 0
v_all_wares 39442 39442 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1493 7 0 0
v_cancel_client_order 3374 3374 0 0.00 0 14440 10 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 1333 6 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 3091 7 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 3434 11 0 0
v_cancel_supplier_invoice 788 774 14 1.77 0 2213 18 0 14
v_cancel_supplier_order 850 850 0 0.00 0 10651 31 0 0
v_cancel_write_off 1900 1900 0 0.00 0 8641 18 0 0
v_max_id_clo_ord 5161 5161 0 0.00 0 1788 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 6648 29 0 0
v_max_id_ord_sup 5097 5097 0 0.00 0 6171 6 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 1557 3 0 0
v_max_non_paid_realizn 3365 3365 0 0.00 0 696 2 0 0
v_min_id_clo_ord 5161 5161 0 0.00 0 1052 3 0 0
v_min_id_clo_res 942 942 0 0.00 0 2263 14 0 0
v_min_id_ord_sup 5097 5097 0 0.00 0 5264 8 0 0
v_min_non_paid_invoice 5499 5499 0 0.00 0 6245 13 0 0
v_min_non_paid_realizn 6703 6703 0 0.00 0 6271 17 0 0
v_our_firm 9642 9642 0 0.00 0 51 1 0 0
v_reserve_write_off 18884 18884 0 0.00 0 10629 8 0 0
x_lock_depdocs_on_canc_invoice 12673 12673 0 0.00 0 21012 94 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6475 338 0 0
x_make_qstorno_1000_1200 5161 5161 0 0.00 2 56585 1285 0 0
x_make_qstorno_1200_2000 5097 5097 0 0.00 3 130384 3107 0 0
x_make_qstorno_2100_3300 452487 452487 0 0.00 0 95261 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 10:39:38 to 18-08-2021 10:39:42.

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:09 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 30810 8192000000 9703786784 9998286848 784303424 8.737 100 88 176 0 813818096 7895792 21618880
08:09:46 9920 8192000000 9750261248 10052530176 828742192 9.187 100 91 181 0 862514480 11929008 21843280
08:10:59 17860 8192000000 9798026208 10111225856 862957904 9.530 100 88 176 0 901681744 18686896 20036944
08:12:25 19979 8192000000 9872829936 10207129600 926289264 10.158 100 76 152 0 983656320 40860272 16506784
08:13:48 5510 8192000000 9932671888 10279358464 974091968 10.627 100 79 157 2 1039613792 49138048 16383776
08:14:57 4098 8192000000 9992144832 10346029056 1003845344 10.916 100 82 164 1 1099618720 78998032 16775344
08:16:02 2964 8192000000 9999962000 10366263296 1031049296 11.179 100 73 145 0 1115347136 70014608 14283232
08:17:07 2270 8192000000 10035458160 10406797312 1051792928 11.378 100 91 182 0 1145907312 74722480 19391904
08:18:11 1234 8192000000 10031193504 10405457920 1070789920 11.560 100 89 178 1 1151482016 61894144 18797952
08:19:13 4201 8192000000 10026744560 10407837696 1071852832 11.570 100 62 125 1 1123020992 38573632 12594528
08:20:19 4443 8192000000 10016828800 10415738880 1080842928 11.656 100 50 100 1 1135260240 44124192 10293120
08:21:24 6673 8192000000 10012697904 10409201664 1083540400 11.681 100 38 76 0 1119409120 27864608 8004112
08:22:31 28289 8192000000 9999031088 10419879936 1085410512 11.699 100 29 57 1 1108432976 17342192 5680272
08:24:02 2847 8192000000 10021105184 10417307648 1096973232 11.809 100 47 93 2 1140444512 33559504 9911776
08:25:05 6564 8192000000 10002533024 10403459072 1094609504 11.786 100 41 79 3 1119260912 14839104 9812304
08:26:12 8107 8192000000 10005235120 10415341568 1067776672 11.531 98 34 69 2 1102656544 27527392 7352480
08:27:21 23670 8192000000 10015425600 10419675136 1082440592 11.671 99 42 85 2 1121855648 30739040 8676016
08:28:51 5167 8192000000 9978380208 10360614912 1046060352 11.323 100 48 92 1 1086887520 30908720 9918448
08:29:57 8207 8192000000 9917582944 10298707968 994810784 10.828 97 30 60 1 1017632432 15539440 7282208
08:31:06 11238 8192000000 9916200592 10299858944 969906816 10.586 94 37 70 1 996071776 18883808 7281152
08:32:17 22219 8192000000 9943373152 10339639296 1011652272 10.991 98 23 46 2 1049571472 33347456 4571744
08:33:41 4762 8192000000 10012920080 10398756864 1049737200 11.358 100 60 120 2 1118468672 56431872 12299600
08:34:47 5820 8192000000 9980315024 10376728576 1062785456 11.483 100 64 128 5 1089731696 12960240 13986000
08:35:53 73089 8192000000 9988647216 10395619328 1073295696 11.584 100 32 64 2 1088122688 7889728 6937264
08:38:11 18338 8192000000 10047237792 10448420864 1105747280 11.892 100 48 96 1 1150902976 35705408 9450288
08:39:36 14685 8192000000 10089899264 10493394944 1121360656 12.040 100 54 106 0 1192298176 60273616 10663904
08:40:54 10852 8192000000 10107141888 10512977920 1140128832 12.217 100 45 89 0 1214754320 65207056 9418432
08:42:12 10879 8192000000 10159682144 10567634944 1153956752 12.347 100 44 88 2 1266166352 102721984 9487616
08:43:28 7112 8192000000 10200303744 10606899200 1167810848 12.476 100 58 110 0 1305222736 125369648 12042240
08:44:39 2445 8192000000 10244254848 10655002624 1168273840 12.481 100 77 153 3 1357838080 171771344 17792896
08:45:45 3210 8192000000 10262471568 10670497792 1171904096 12.515 100 62 123 0 1363593680 178662848 13026736
08:46:55 7246 8192000000 10267780864 10684051456 1177841440 12.570 100 49 98 0 1364273552 174975552 11456560
08:48:06 8693 8192000000 10315822160 10731368448 1176469248 12.557 100 55 109 2 1416042752 226237744 13335760
08:49:17 2432 8192000000 10348787072 10752589824 1159730992 12.401 99 83 162 0 1434137808 256003552 18403264
08:50:23 4583 8192000000 10330728784 10742194176 1148979840 12.300 99 74 147 1 1426794224 260040720 17773664
08:51:41 3674 8192000000 10334184768 10756702208 1161395632 12.416 100 71 139 1 1438907168 261268256 16243280
08:52:46 4493 8192000000 10306962864 10736713728 1138153280 12.198 99 38 76 0 1406545888 258456032 9936576
08:53:57 3720 8192000000 10398677008 10816602112 1169308560 12.490 100 75 151 1 1501714112 314264016 18141536
08:55:05 7905 8192000000 10399382512 10835988480 1142363792 12.238 98 55 110 0 1507184896 350449920 14371184
08:56:17 8818 8192000000 10398608112 10837827584 1165555072 12.455 100 46 92 0 1496431488 318883360 11993056
08:57:33 13243 8192000000 10373146144 10795749376 1135714144 12.175 99 37 73 1 1474923840 328998960 10210736
08:58:48 4417 8192000000 10419746832 10839474176 1141222400 12.227 100 55 107 2 1515244896 360597696 13424800
08:59:56 23886 8192000000 10337940768 10763644928 1121060816 12.037 99 33 66 2 1432699536 302846912 8791808
09:01:30 19536 8192000000 10269476560 10810130432 1113233888 11.963 99 19 38 0 1374051728 255485984 5331856
09:04:46 88919 8192000000 10061546336 10614362112 1048823168 11.349 94 7 14 0 1144353568 93891120 1639280
09:07:16 46057 8192000000 10037786384 10470248448 1091554640 11.757 98 38 77 0 1114398848 14982480 7861728
09:09:18 195999 8192000000 10078899152 10520121344 1071448016 11.566 96 24 48 1 1139223440 62734976 5040448
09:13:35 60531 8192000000 10103375344 10543038464 1118170176 12.010 99 44 87 0 1183276144 56374912 8731056
09:15:46 144014 8192000000 10136191344 10603536384 1150282096 12.312 100 21 42 0 1241225504 85497632 5445776
09:19:39 81595 8192000000 10116977408 10594545664 1126903920 12.092 99 13 26 1 1223102960 92960832 3238208
09:22:02 62508 8192000000 10070400752 10545348608 1135502720 12.173 100 7 14 0 1176681680 39741472 1437488
09:24:18 57144 8192000000 10106610720 10557640704 1136425152 12.182 99 14 28 0 1199144176 59802336 2916688
09:26:16 58898 8192000000 10133782928 10643537920 1137772640 12.195 99 20 40 1 1225528208 83467440 4288128
09:28:38 25271 8192000000 10119675424 10554785792 1138704944 12.203 99 53 104 2 1194358304 44377360 11276000
09:30:03 103655 8192000000 10081926624 10537762816 1138328752 12.200 99 9 17 1 1176393920 36208352 1856816
09:32:49 36743 8192000000 10115933296 10596929536 1120912688 12.036 98 38 76 1 1228256368 99491568 7852112
09:34:29 61902 8192000000 10122239584 10571833344 1137819520 12.195 99 28 56 0 1199225648 55892224 5513904
09:37:08 38132 8192000000 10124391024 10587627520 1144814016 12.261 100 32 64 0 1219005312 67759408 6431888
09:38:54 53821 8192000000 10095140464 10558181376 1133312256 12.153 99 10 20 1 1195253168 59527008 2413904
09:40:51 41834 8192000000 10068307216 10592460800 1123572400 12.061 98 5 10 0 1192754368 68437040 744928
09:42:43 51645 8192000000 10109294240 10557329408 1125336928 12.077 98 20 40 0 1180105552 50703776 4064848
09:44:35 75212 8192000000 10121772976 10589339648 1136429120 12.182 99 14 28 0 1232910608 93585568 2895920
09:46:51 29670 8192000000 10111929344 10549866496 1132068912 12.141 98 33 65 1 1185042416 45700960 7272544
09:48:23 36629 8192000000 10096510304 10548953088 1123523088 12.060 98 17 34 0 1187274880 60158416 3593376
09:50:02 84812 8192000000 10089844832 10630385664 1142556496 12.240 100 18 35 0 1232154160 86047344 3550320
09:52:27 33065 8192000000 10127023536 10578128896 1144386000 12.257 99 38 75 1 1200811504 49013296 7412208
09:54:04 140537 8192000000 10078534000 10557980672 1089327248 11.736 95 17 34 0 1167901088 75339168 3234672
09:58:33 45566 8192000000 10078038096 10541961216 1110511360 11.937 98 12 24 0 1172175968 59324832 2339776
10:00:19 92582 8192000000 10062739152 10498887680 1071635040 11.568 95 20 38 2 1168061072 91850464 4575568
10:02:56 57201 8192000000 10057428368 10553053184 1117832176 12.007 100 15 30 0 1148010784 27007568 3171040
10:04:56 53622 8192000000 10071995136 10512650240 1084089616 11.686 97 20 39 2 1131183888 42584352 4509920
10:06:54 49348 8192000000 10108204720 10543554560 1127891600 12.101 99 25 49 0 1195810400 63257824 4660976
10:08:53 77246 8192000000 10084536256 10617688064 1119458304 12.022 99 14 28 0 1251238384 128686144 3093936
10:11:24 29801 8192000000 10108020736 10540711936 1129795280 12.119 100 46 92 0 1196362368 57346592 9220496
10:12:54 33330 8192000000 10105463472 10620788736 1134450512 12.163 100 32 64 1 1222796304 82158208 6187584
10:14:27 20598 8192000000 10121311072 10551726080 1137848800 12.195 99 52 104 1 1200979072 52961072 10169200
10:15:49 85113 8192000000 10109659776 10623143936 1138673744 12.203 99 25 50 0 1212235440 68864208 4697488
10:18:18 117747 8192000000 10089108192 10542288896 1138154864 12.198 98 35 70 1 1175964240 30725312 7084064
10:21:33 84595 8192000000 10109834688 10580791296 1148575984 12.296 99 10 20 0 1212265408 61393232 2296192
10:24:00 68647 8192000000 10155953584 10619752448 1153524144 12.343 99 27 54 0 1240986752 81527696 5934912
10:26:18 55950 8192000000 10147521824 10623516672 1130486176 12.126 97 19 38 0 1239514720 104882752 4145792
10:28:15 49731 8192000000 10114072112 10585391104 1160610560 12.409 100 16 32 0 1240833472 76736288 3486624
10:30:05 56715 8192000000 10155629440 10607124480 1163397120 12.435 100 27 54 0 1216580128 47627808 5555200
10:32:04 110787 8192000000 10104137504 10572279808 1145723712 12.269 99 15 29 0 1222937696 74335680 2878304
10:35:00 72062 8192000000 10099427392 10555203584 1083588608 11.682 94 26 52 0 1125890128 37369392 4932128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-08-2021 10:39:44 to 18-08-2021 10:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-08-2021 10:39:59 to 18-08-2021 10:40:01.

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          227972
MON$OLDEST_ACTIVE               230685
MON$OLDEST_SNAPSHOT             230685
MON$NEXT_TRANSACTION            230685
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                       2055712
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:215: 
:216: mscs-test-161.budzdorov.ru    Wed Aug 18 10:37:12 2021
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Wed Aug 18 10:37:12 2021
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Wed Aug 18 10:37:18 2021
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

18.08.21 10:40:02. 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-08-18 10:40:05 No severe PSQL-related problems occured
18.08.21 10:40:06. End of report.