Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2021 16: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 15903820 kB
MemAvailable 31020996 kB
Buffers 0 kB
Cached 15720596 kB
SwapCached 2572 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-03 16:37:45
DTS_END                         2021-08-03 16:37:45.5250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39210
TRN_ID                          233532
ATT_ID                          632
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: 9368.16 23677 1124179
customer order: creation 167.48 360 20097
customer order: refuse 14.60 347 1752
order to supplier: creation 43.17 1384 5180
order to supplier: removal 3.52 113874 422
invoice (draft): creation 45.00 3477 5400
invoice (draft): removal 55.17 844 6620
invoice accept: apply 41.43 17727 4971
invoice accept: cancel 49.55 829 5946
customer reserve: creation 3836.41 175 460369
customer reserve: removal 4824.27 65 578913
realization accept: apply 79.38 177 9526
realization accept: cancel 84.38 117 10126
payment from customer: creation 55.82 123 6698
payment from customer: removal 5.58 76 670
payment to supplier: creation 47.33 125 5679
payment to supplier: removal 3.54 109 425
service: total inventory turnovers 4.53 1212 544
service: total monetary turnovers 6.23 33 748
service: aggregate perf. data 0.74 23530 89
service: refresh index statistics 0.03 308950 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2021 16:39:04 to 03-08-2021 16:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 788
TEST_TIME 2 1466
TEST_TIME 3 3215
TEST_TIME 4 4484
TEST_TIME 5 5836
TEST_TIME 6 5571
TEST_TIME 7 6099
TEST_TIME 8 5970
TEST_TIME 9 6341
TEST_TIME 10 6588
TEST_TIME 11 6840
TEST_TIME 12 6843
TEST_TIME 13 6960
TEST_TIME 14 6952
TEST_TIME 15 6846
TEST_TIME 16 6880
TEST_TIME 17 6931
TEST_TIME 18 7085
TEST_TIME 19 7216
TEST_TIME 20 7689
TEST_TIME 21 8198
TEST_TIME 22 8241
TEST_TIME 23 8246
TEST_TIME 24 8495
TEST_TIME 25 8759
TEST_TIME 26 9096
TEST_TIME 27 9325
TEST_TIME 28 10323
TEST_TIME 29 10571
TEST_TIME 30 11457
TEST_TIME 31 11463
TEST_TIME 32 11329
TEST_TIME 33 11190
TEST_TIME 34 11166
TEST_TIME 35 11195
TEST_TIME 36 11424
TEST_TIME 37 11370
TEST_TIME 38 11081
TEST_TIME 39 10982
TEST_TIME 40 10819
TEST_TIME 41 10662
TEST_TIME 42 10692
TEST_TIME 43 10514
TEST_TIME 44 10453
TEST_TIME 45 10353
TEST_TIME 46 10223
TEST_TIME 47 10295
TEST_TIME 48 10194
TEST_TIME 49 10128
TEST_TIME 50 10043
TEST_TIME 51 9855
TEST_TIME 52 9814
TEST_TIME 53 9837
TEST_TIME 54 9706
TEST_TIME 55 9660
TEST_TIME 56 9679
TEST_TIME 57 9662
TEST_TIME 58 9579
TEST_TIME 59 9550
TEST_TIME 60 9661
TEST_TIME 61 9643
TEST_TIME 62 9599
TEST_TIME 63 9658
TEST_TIME 64 9673
TEST_TIME 65 9789
TEST_TIME 66 9798
TEST_TIME 67 9859
TEST_TIME 68 9842
TEST_TIME 69 9767
TEST_TIME 70 9865
TEST_TIME 71 9939
TEST_TIME 72 9907
TEST_TIME 73 9863
TEST_TIME 74 9765
TEST_TIME 75 9800
TEST_TIME 76 9748
TEST_TIME 77 9699
TEST_TIME 78 9667
TEST_TIME 79 9601
TEST_TIME 80 9592
TEST_TIME 81 9587
TEST_TIME 82 9501
TEST_TIME 83 9476
TEST_TIME 84 9535
TEST_TIME 85 9474
TEST_TIME 86 9535
TEST_TIME 87 9561
TEST_TIME 88 9552
TEST_TIME 89 9529
TEST_TIME 90 9558
TEST_TIME 91 9598
TEST_TIME 92 9597
TEST_TIME 93 9597
TEST_TIME 94 9634
TEST_TIME 95 9658
TEST_TIME 96 9620
TEST_TIME 97 9696
TEST_TIME 98 9755
TEST_TIME 99 9773
TEST_TIME 100 9679
TEST_TIME 101 9655
TEST_TIME 102 9687
TEST_TIME 103 9644
TEST_TIME 104 9622
TEST_TIME 105 9651
TEST_TIME 106 9607
TEST_TIME 107 9591
TEST_TIME 108 9572
TEST_TIME 109 9559
TEST_TIME 110 9529
TEST_TIME 111 9501
TEST_TIME 112 9518
TEST_TIME 113 9487
TEST_TIME 114 9470
TEST_TIME 115 9418
TEST_TIME 116 9383
TEST_TIME 117 9390
TEST_TIME 118 9378
TEST_TIME 119 9371
TEST_TIME 120 9369

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-08-2021 16:39:07 to 03-08-2021 16:39:11.

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 1550408 1550408 0 0.00 0 24656 6 0 0
doc_list_biud 1550408 1550408 0 0.00 0 48119 30 0 0
sp_add_invoice_to_stock 5100 4971 129 2.52 27 188962 17727 0 129
sp_cancel_adding_invoice 5946 5946 0 0.00 5 68704 829 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 9416 348 0 0
sp_cancel_customer_reserve 578913 578913 0 0.00 1 48123 65 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 3116 76 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 5060 109 0 0
sp_cancel_supplier_invoice 6627 6620 7 0.10 1 15539 845 0 7
sp_cancel_supplier_order 422 422 0 0.00 7 1197369 113875 0 0
sp_cancel_write_off 10126 10126 0 0.00 1 15666 117 0 0
sp_client_order 20097 20097 0 0.00 3 37057 361 0 0
sp_customer_reserve 460369 460369 0 0.00 0 116428 175 0 0
sp_fill_shopping_cart 30662 30662 0 0.00 1 14034 37 0 0
sp_fill_shopping_cart_clo_res 460357 460357 0 0.00 0 39304 14 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 39326 324 0 0
sp_kill_cost_storno 17167 17167 0 0.00 0 15663 63 0 0
sp_kill_qstorno_ret_qs2qd 1174994 1174994 0 0.00 0 48108 35 0 0
sp_kill_qty_storno 618264 618264 0 0.00 0 48119 75 0 0
sp_lock_selected_doc 20248 20248 0 0.00 0 5700 3 0 0
sp_make_cost_storno 26862 26862 0 0.00 0 12247 51 0 0
sp_multiply_rows_for_pdistr 26862 26862 0 0.00 0 8249 29 0 0
sp_multiply_rows_for_qdistr 20097 20097 0 0.00 0 37044 152 0 0
sp_pay_from_customer 6698 6698 0 0.00 4 14934 123 0 0
sp_pay_to_supplier 5679 5679 0 0.00 5 9132 126 0 0
sp_payment_common 12377 12377 0 0.00 1 14904 27 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 9372 318 0 0
sp_qd_handle_on_invoice_upd_sts 10905 10905 0 0.00 0 29665 337 0 0
sp_qd_handle_on_reserve_upd_sts 9526 9526 0 0.00 0 17570 63 0 0
sp_reserve_write_off 9526 9526 0 0.00 6 17645 177 0 0
sp_supplier_invoice 5400 5400 0 0.00 7 96839 3478 0 0
sp_supplier_order 5180 5180 0 0.00 6 30448 1384 0 0
srv_aggregate_perf_data 89 89 0 0.00 3208 344990 23530 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2661 137190 45995 0 0
srv_find_qd_qs_mism 1077613 1077613 0 0.00 0 24655 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 48 30529 1213 0 0
srv_make_money_saldo 748 748 0 0.00 1 7074 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 154799 543799 308951 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4971 4971 0 0.00 0 18604 220 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 5944 92 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 7597 20 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 5112 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4858 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 727 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 3784 37 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 37084 1794 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 7324 16 0 0
t$perf-norm:sp_client_order 20097 20097 0 0.00 0 9365 22 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 5856 14 0 0
t$perf-norm:sp_pay_from_customer 6698 6698 0 0.00 0 9067 20 0 0
t$perf-norm:sp_pay_to_supplier 5679 5679 0 0.00 0 8308 14 0 0
t$perf-norm:sp_reserve_write_off 9526 9526 0 0.00 0 9549 18 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 8867 24 0 0
t$perf-norm:sp_supplier_order 5180 5180 0 0.00 0 8234 19 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 6949 81 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 7282 42 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 5689 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10176 9918 258 2.53 0 9235 20 0 258
v_all_customers 36314 36314 0 0.00 0 141 1 0 0
v_all_suppliers 29482 29482 0 0.00 0 20 1 0 0
v_all_wares 40194 40194 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 2624 10 0 0
v_cancel_client_order 3504 3504 0 0.00 0 5048 8 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2933 9 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 2587 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 5048 18 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 5664 25 0 14
v_cancel_supplier_order 844 844 0 0.00 0 1816 7 0 0
v_cancel_write_off 1968 1968 0 0.00 0 5424 23 0 0
v_max_id_clo_ord 5180 5180 0 0.00 0 392 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 1938 15 0 0
v_max_id_ord_sup 5385 5385 0 0.00 0 2460 3 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 559 3 0 0
v_max_non_paid_realizn 3540 3540 0 0.00 0 3152 5 0 0
v_min_id_clo_ord 5180 5180 0 0.00 0 3370 7 0 0
v_min_id_clo_res 994 994 0 0.00 0 959 3 0 0
v_min_id_ord_sup 5385 5385 0 0.00 0 3276 4 0 0
v_min_non_paid_invoice 5679 5679 0 0.00 0 4469 16 0 0
v_min_non_paid_realizn 6698 6698 0 0.00 0 14887 18 0 0
v_our_firm 10196 10196 0 0.00 0 13 1 0 0
v_reserve_write_off 19052 19052 0 0.00 0 13513 9 0 0
x_lock_depdocs_on_canc_invoice 12173 12173 0 0.00 0 13208 122 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 5212 336 0 0
x_make_qstorno_1000_1200 5180 5180 0 0.00 2 30019 1305 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 3 96247 3335 0 0
x_make_qstorno_2100_3300 460369 460369 0 0.00 0 116426 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2021 16:39:12 to 03-08-2021 16:39:16.

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
14:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:50 33241 8192000000 10390506624 10711875584 1431749808 14.877 100 86 173 1 1465345376 5784000 27811568
14:10:28 28774 8192000000 10447882256 10776018944 1501463488 15.489 100 94 188 0 1540080320 8699200 29917632
14:12:02 11980 8192000000 10483237840 10821967872 1537162992 15.799 100 87 174 1 1574353616 10072992 27117632
14:13:17 11878 8192000000 10547040176 10908782592 1611611648 16.438 100 69 138 1 1642985680 10475696 20898336
14:14:37 7080 8192000000 10632169520 11005804544 1699403984 17.180 100 69 138 1 1730737296 11451984 19881328
14:15:44 6345 8192000000 10705864144 11081711616 1764681984 17.723 100 79 155 2 1800178768 13431552 22065232
14:16:52 3224 8192000000 10763048192 11145584640 1819731808 18.175 100 87 174 1 1857695968 13534480 24429680
14:17:58 2218 8192000000 10799710976 11192901632 1865152304 18.545 100 87 174 1 1901869200 12973952 23742944
14:19:02 1280 8192000000 10822748528 11212881920 1879676432 18.662 100 84 168 3 1914272512 11833376 22762704
14:20:04 6463 8192000000 10805617712 11218157568 1880871552 18.672 100 38 76 1 1899755840 8109328 10774960
14:21:12 8735 8192000000 10807823680 11228299264 1889111408 18.739 100 21 42 2 1901590448 5897392 6581648
14:22:25 10426 8192000000 10800740128 11235917824 1898596336 18.815 100 15 30 1 1908967504 6700096 3671072
14:23:36 21732 8192000000 10849229888 11255353344 1910570176 18.911 100 57 114 1 1933095920 5076304 17449440
14:24:58 8174 8192000000 10830406496 11271553024 1920688256 18.992 100 35 71 0 1938356448 7857952 9810240
14:26:09 4880 8192000000 10838911200 11273011200 1906050384 18.875 99 25 50 0 1927746224 14708064 6987776
14:27:16 75580 8192000000 10794020768 11230744576 1829838336 18.258 96 32 64 0 1844317424 5296240 9182848
14:29:35 4693 8192000000 10714518000 11122610176 1740199520 17.520 96 26 52 0 1753372752 5709344 7463888
14:30:42 6004 8192000000 10711280240 11114553344 1769821808 17.766 99 31 62 0 1785753152 6668864 9262480
14:31:51 45204 8192000000 10716336000 11131330560 1780466720 17.853 98 25 50 2 1794289840 6892992 6930128
14:33:50 6827 8192000000 10756316624 11170783232 1835762144 18.306 100 48 96 1 1858707344 8580112 14365088
14:35:01 7839 8192000000 10769081328 11197157376 1857163056 18.480 100 17 34 0 1867139232 5016576 4959600
14:36:12 18101 8192000000 10784190224 11217981440 1874870624 18.624 100 19 38 1 1885608432 5625616 5112192
14:37:32 99606 8192000000 10857168384 11275595776 1920907952 18.994 100 47 92 3 1941031520 7005392 13118176
14:40:17 10658 8192000000 10877272496 11295879168 1936420128 19.118 100 43 86 0 1961034672 12730080 11884464
14:41:31 14789 8192000000 10901841808 11324981248 1957854736 19.289 100 57 110 2 1988782864 15303888 15624240
14:42:49 5413 8192000000 10921438032 11348217856 1978535872 19.453 100 56 110 1 2007549440 13871328 15142240
14:43:57 6180 8192000000 10921728064 11347517440 1965356704 19.349 99 59 114 3 1997248512 15704720 16187088
14:45:09 21175 8192000000 10935401248 11362684928 1986878768 19.519 100 65 130 0 2025005296 18744400 19382128
14:46:36 4579 8192000000 10923896496 11352662016 1963961696 19.338 98 58 115 1 2001207792 20722048 16524048
14:47:44 8466 8192000000 10970579952 11390111744 2005575072 19.667 100 68 136 1 2051529520 25668240 20286208
14:48:57 19139 8192000000 10959837456 11393343488 2003584800 19.651 100 47 92 1 2039975184 22157472 14232912
14:50:22 3924 8192000000 10986297728 11414200320 2020507792 19.784 100 68 135 2 2072215968 30693264 21014912
14:51:33 4218 8192000000 10964064896 11397697536 2002396464 19.642 100 51 102 0 2052055584 33500400 16158720
14:52:42 3500 8192000000 10975357088 11392643072 1984463520 19.500 99 77 154 0 2043047440 34945040 23638880
14:53:53 4916 8192000000 10974484928 11396890624 1993824976 19.574 100 66 132 1 2057683600 42573376 21285248
14:55:10 8690 8192000000 11001570768 11423617024 2010971472 19.709 100 71 142 0 2082550832 48408832 23170528
14:56:28 4278 8192000000 11001011344 11417792512 2002472416 19.642 100 84 166 0 2083533232 54472848 26587968
14:57:42 2656 8192000000 10989261392 11402031104 1974654000 19.422 99 85 171 0 2057314752 55173376 27487376
14:58:47 10814 8192000000 10963517840 11407298560 1990389456 19.547 100 40 80 3 2052071008 48312704 13368848
15:00:00 7014 8192000000 10969147776 11407970304 1978605408 19.454 99 48 96 0 2046480048 51681568 16193072
15:01:15 11751 8192000000 10978002848 11426709504 1981736784 19.478 99 36 71 0 2055108432 61474944 11896704
15:02:29 15250 8192000000 10972491760 11416363008 1982982528 19.488 100 34 67 0 2059133152 64077584 12073040
15:03:47 8690 8192000000 10956882384 11396014080 1953197776 19.252 100 39 78 2 2033409264 67163344 13048144
15:05:31 14999 8192000000 10956144288 11416096768 1961015344 19.314 100 25 50 1 2040119184 69442112 9661728
15:07:11 83467 8192000000 10897633168 11366879232 1911806304 18.921 97 7 14 0 1939814080 25913408 2094368
15:09:36 44173 8192000000 10897444384 11362070528 1917962528 18.971 97 7 14 0 1930620784 11621296 1036960
15:11:34 63477 8192000000 10894606048 11354279936 1934675600 19.104 99 12 24 1 1951092496 13063312 3353584
15:13:38 71391 8192000000 10877885600 11348029440 1876425184 18.636 95 4 8 0 1892415936 15508928 481824
15:15:50 42691 8192000000 10917246944 11364831232 1964903904 19.345 100 41 82 2 1986698640 10420432 11374304
15:17:34 84469 8192000000 10889397968 11351506944 1917942528 18.970 97 26 52 0 1936926128 11297280 7686320
15:20:08 40649 8192000000 10926127968 11376160768 1970437472 19.389 99 42 84 1 1993889952 11414896 12037584
15:21:57 65305 8192000000 10917582016 11392204800 1986080304 19.513 100 8 16 0 2005284016 16652352 2551360
15:24:11 47028 8192000000 10905210688 11377659904 1967273072 19.364 99 12 24 0 1979341888 9235104 2833712
15:26:01 84469 8192000000 10914364816 11389403136 1989952768 19.543 100 12 24 2 2004858448 11625968 3279712
15:28:25 102877 8192000000 10893442064 11381043200 1938965312 19.138 97 20 40 0 1956153840 11351472 5837056
15:31:32 37475 8192000000 10923048352 11397427200 1998930816 19.614 100 18 36 1 2013835488 9486912 5417760
15:33:11 89220 8192000000 10932167568 11398098944 1921950272 19.002 96 16 32 1 1941272544 14797664 4524608
15:35:47 86439 8192000000 10907211248 11394138112 1953599440 19.255 99 13 26 1 1979391536 21727616 4064480
15:38:59 63272 8192000000 10916088368 11396136960 1962643792 19.327 98 24 47 1 1979964752 11201968 6118992
15:41:03 114948 8192000000 10916600304 11365527552 1845664032 18.387 93 22 44 0 1865984288 13639200 6681056
15:44:01 49191 8192000000 10861714528 11335651328 1859893920 18.502 95 16 32 0 1878869808 14876592 4099296
15:45:55 24346 8192000000 10902025312 11348332544 1937181600 19.124 99 46 94 0 1958452352 7619424 13651328
15:47:23 111293 8192000000 10882359424 11348123648 1868878768 18.575 95 10 20 1 1887653632 16221568 2553296
15:50:16 4958 8192000000 10920088096 11356135424 1948174000 19.212 99 61 120 1 1978462544 12396944 17891600
15:51:24 102871 8192000000 10880889232 11351207936 1935907152 19.114 99 29 57 0 1957071136 13139104 8024880
15:54:09 8494 8192000000 10855877824 11299926016 1887556688 18.726 97 46 92 1 1908520848 7330992 13633168
15:55:28 52899 8192000000 10866254640 11335348224 1908816096 18.897 98 14 28 0 1919935872 6346128 4773648
15:57:30 59219 8192000000 10855033792 11340517376 1892758896 18.768 97 25 48 1 1912468912 13292272 6417744
15:59:35 94923 8192000000 10878136960 11364892672 1960409600 19.309 100 17 33 3 1980316944 14775456 5131888
16:02:12 37589 8192000000 10853683008 11321069568 1872721392 18.606 96 22 44 1 1889167520 10524048 5922080
16:03:55 74784 8192000000 10870880048 11340902400 1885130880 18.707 97 11 22 0 1897674656 9617920 2925856
16:06:14 29510 8192000000 10891522896 11346014208 1947072624 19.203 100 22 43 1 1961060336 7848016 6139696
16:07:52 84102 8192000000 10871228896 11349151744 1922293568 19.005 98 18 36 2 1941269504 14007264 4968672
16:10:21 104695 8192000000 10913510944 11380637696 1956075424 19.275 99 16 32 0 1972654432 12379840 4199168
16:13:08 35582 8192000000 10922447488 11375722496 1941746080 19.161 98 37 74 0 1963756736 11560208 10450448
16:14:46 86747 8192000000 10920443584 11407532032 1979628944 19.462 100 6 12 0 1997615072 16017008 1969120
16:17:14 137190 8192000000 10920054592 11408965632 1942219424 19.164 97 27 54 2 1971562576 21939328 7403824
16:20:42 36986 8192000000 10938898352 11398623232 1985784000 19.510 99 44 88 0 2012206624 13991904 12430720
16:22:30 61095 8192000000 10905814272 11394682880 1982469968 19.484 100 9 18 0 1997051040 11976272 2604800
16:24:31 41138 8192000000 10913420144 11395334144 1986686672 19.518 100 35 70 3 2008700176 11687184 10326320
16:26:28 41468 8192000000 10903319728 11381280768 1974700576 19.423 100 17 34 1 1987354784 7197728 5456480
16:28:31 84878 8192000000 10870903600 11367100416 1898796208 18.817 96 23 46 2 1919679504 14020208 6863088
16:31:07 49386 8192000000 10878844336 11362607104 1911057712 18.915 97 26 52 3 1929957888 12003232 6896944
16:32:58 60004 8192000000 10862817376 11347783680 1927395632 19.046 99 7 14 0 1935657312 6534352 1727328
16:35:55 43724 8192000000 10907872176 11382067200 1946263120 19.197 98 30 60 1 1968865280 14306752 8295408
16:37:42 14464 8192000000 9076644704 9305325568 242015952 2.869 13 3 5 0 249605584 6681296 908336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-08-2021 16:39:18 to 03-08-2021 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 03-08-2021 16:39:33 to 03-08-2021 16:39:35.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237261
MON$OLDEST_ACTIVE               237262
MON$OLDEST_SNAPSHOT             237262
MON$NEXT_TRANSACTION            237262
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-03 16:41:55.3470
MON$PAGES                       2078112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Tue Aug  3 16:38:02 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Tue Aug  3 16:38:02 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Tue Aug  3 16:38:16 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
--- end of diff output ---

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

03.08.21 16:39:36. There are 3 logs in /var/tmp/logs-oltp30 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-03 16:39:38 No severe PSQL-related problems occured
03.08.21 16:39:40. End of report.