Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2021 01: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16791784 kB
MemAvailable 31659908 kB
Buffers 0 kB
Cached 14918552 kB
SwapCached 0 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-11-13 01:37:57
DTS_END                         2021-11-13 01:37:57.3530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33336
TRN_ID                          235324
ATT_ID                          701
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: 9582.00 17397 1149840
customer order: creation 169.83 340 20379
customer order: refuse 14.73 313 1768
order to supplier: creation 43.96 1192 5275
order to supplier: removal 3.32 96731 398
invoice (draft): creation 45.33 3265 5439
invoice (draft): removal 53.83 683 6459
invoice accept: apply 42.57 18255 5108
invoice accept: cancel 49.23 732 5908
customer reserve: creation 3993.83 180 479260
customer reserve: removal 4870.73 52 584488
realization accept: apply 80.67 172 9680
realization accept: cancel 86.04 105 10325
payment from customer: creation 58.96 100 7075
payment from customer: removal 5.55 64 666
payment to supplier: creation 48.13 128 5776
payment to supplier: removal 3.32 97 398
service: total inventory turnovers 5.06 1223 607
service: total monetary turnovers 6.19 51 743
service: aggregate perf. data 0.70 24909 84
service: refresh index statistics 0.03 199346 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2021 01:40:00 to 13-11-2021 01:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1807
TEST_TIME 2 2549
TEST_TIME 3 3653
TEST_TIME 4 4446
TEST_TIME 5 4245
TEST_TIME 6 4325
TEST_TIME 7 4544
TEST_TIME 8 4310
TEST_TIME 9 4329
TEST_TIME 10 5105
TEST_TIME 11 5397
TEST_TIME 12 5763
TEST_TIME 13 6377
TEST_TIME 14 6678
TEST_TIME 15 7092
TEST_TIME 16 7592
TEST_TIME 17 7834
TEST_TIME 18 8413
TEST_TIME 19 8687
TEST_TIME 20 8933
TEST_TIME 21 9507
TEST_TIME 22 9600
TEST_TIME 23 9557
TEST_TIME 24 9710
TEST_TIME 25 10014
TEST_TIME 26 10336
TEST_TIME 27 11001
TEST_TIME 28 10813
TEST_TIME 29 10639
TEST_TIME 30 10402
TEST_TIME 31 10544
TEST_TIME 32 10705
TEST_TIME 33 10565
TEST_TIME 34 10630
TEST_TIME 35 10661
TEST_TIME 36 10743
TEST_TIME 37 10886
TEST_TIME 38 10805
TEST_TIME 39 10773
TEST_TIME 40 10540
TEST_TIME 41 10412
TEST_TIME 42 10333
TEST_TIME 43 10225
TEST_TIME 44 10191
TEST_TIME 45 10099
TEST_TIME 46 10040
TEST_TIME 47 10004
TEST_TIME 48 9980
TEST_TIME 49 9957
TEST_TIME 50 9998
TEST_TIME 51 9985
TEST_TIME 52 9892
TEST_TIME 53 9811
TEST_TIME 54 9852
TEST_TIME 55 10005
TEST_TIME 56 9948
TEST_TIME 57 9978
TEST_TIME 58 10018
TEST_TIME 59 10014
TEST_TIME 60 10024
TEST_TIME 61 10089
TEST_TIME 62 10043
TEST_TIME 63 10116
TEST_TIME 64 10189
TEST_TIME 65 10103
TEST_TIME 66 10089
TEST_TIME 67 10148
TEST_TIME 68 10124
TEST_TIME 69 10037
TEST_TIME 70 9977
TEST_TIME 71 9925
TEST_TIME 72 9945
TEST_TIME 73 9884
TEST_TIME 74 9884
TEST_TIME 75 9861
TEST_TIME 76 9811
TEST_TIME 77 9793
TEST_TIME 78 9767
TEST_TIME 79 9694
TEST_TIME 80 9731
TEST_TIME 81 9751
TEST_TIME 82 9716
TEST_TIME 83 9684
TEST_TIME 84 9798
TEST_TIME 85 9782
TEST_TIME 86 9779
TEST_TIME 87 9755
TEST_TIME 88 9712
TEST_TIME 89 9757
TEST_TIME 90 9760
TEST_TIME 91 9812
TEST_TIME 92 9832
TEST_TIME 93 9907
TEST_TIME 94 9824
TEST_TIME 95 9797
TEST_TIME 96 9734
TEST_TIME 97 9741
TEST_TIME 98 9691
TEST_TIME 99 9689
TEST_TIME 100 9654
TEST_TIME 101 9625
TEST_TIME 102 9572
TEST_TIME 103 9546
TEST_TIME 104 9504
TEST_TIME 105 9523
TEST_TIME 106 9491
TEST_TIME 107 9466
TEST_TIME 108 9504
TEST_TIME 109 9510
TEST_TIME 110 9522
TEST_TIME 111 9540
TEST_TIME 112 9535
TEST_TIME 113 9561
TEST_TIME 114 9554
TEST_TIME 115 9557
TEST_TIME 116 9594
TEST_TIME 117 9612
TEST_TIME 118 9668
TEST_TIME 119 9657
TEST_TIME 120 9588
TEST_TIME 121 9508

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2021 01:40:03 to 13-11-2021 01:40:07.

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 1593157 1593157 0 0.00 0 23366 6 0 0
doc_list_biud 1593157 1593157 0 0.00 0 28052 24 0 0
sp_add_invoice_to_stock 5236 5108 128 2.44 33 230349 18256 0 128
sp_cancel_adding_invoice 5908 5908 0 0.00 6 79897 733 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 10043 313 0 0
sp_cancel_customer_reserve 584488 584488 0 0.00 1 23926 53 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 2912 64 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 6711 97 0 0
sp_cancel_supplier_invoice 6470 6459 11 0.17 2 16544 684 0 11
sp_cancel_supplier_order 398 398 0 0.00 7 1014358 96732 0 0
sp_cancel_write_off 10325 10325 0 0.00 1 28072 106 0 0
sp_client_order 20379 20379 0 0.00 3 29538 340 0 0
sp_customer_reserve 479260 479260 0 0.00 0 46375 181 0 0
sp_fill_shopping_cart 31082 31082 0 0.00 1 10245 36 0 0
sp_fill_shopping_cart_clo_res 479251 479251 0 0.00 0 17240 12 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 26534 267 0 0
sp_kill_cost_storno 17297 17297 0 0.00 0 23365 53 0 0
sp_kill_qstorno_ret_qs2qd 1186158 1186158 0 0.00 0 28051 28 0 0
sp_kill_qty_storno 624125 624125 0 0.00 0 28051 62 0 0
sp_lock_selected_doc 20549 20549 0 0.00 0 8625 6 0 0
sp_make_cost_storno 27630 27630 0 0.00 0 11165 54 0 0
sp_multiply_rows_for_pdistr 27630 27630 0 0.00 0 10344 29 0 0
sp_multiply_rows_for_qdistr 20379 20379 0 0.00 0 29526 153 0 0
sp_pay_from_customer 7075 7075 0 0.00 4 12628 100 0 0
sp_pay_to_supplier 5776 5776 0 0.00 5 16008 128 0 0
sp_payment_common 12851 12851 0 0.00 1 16001 31 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 10036 296 0 0
sp_qd_handle_on_invoice_upd_sts 11007 11007 0 0.00 0 27260 301 0 0
sp_qd_handle_on_reserve_upd_sts 9680 9680 0 0.00 0 18870 55 0 0
sp_reserve_write_off 9680 9680 0 0.00 6 19139 172 0 0
sp_supplier_invoice 5439 5439 0 0.00 7 93695 3265 0 0
sp_supplier_order 5275 5275 0 0.00 4 24637 1193 0 0
srv_aggregate_perf_data 84 84 0 0.00 3063 511597 24910 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1792 159550 47216 0 0
srv_find_qd_qs_mism 1101688 1101688 0 0.00 0 12783 6 0 0
srv_make_invnt_saldo 607 607 0 0.00 54 147246 1224 0 0
srv_make_money_saldo 743 743 0 0.00 1 12860 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 138511 347476 199347 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5109 5109 0 0.00 0 21756 145 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 3436 84 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 6259 11 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1320 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3904 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 594 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 715 10 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 18741 1328 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 1550 5 0 0
t$perf-norm:sp_client_order 20379 20379 0 0.00 0 29256 18 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1037 5 0 0
t$perf-norm:sp_pay_from_customer 7075 7075 0 0.00 0 11249 8 0 0
t$perf-norm:sp_pay_to_supplier 5776 5776 0 0.00 0 7160 7 0 0
t$perf-norm:sp_reserve_write_off 9680 9680 0 0.00 0 7147 11 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 3359 15 0 0
t$perf-norm:sp_supplier_order 5275 5275 0 0.00 0 5997 15 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 23 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 288 6 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 1839 19 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 2583 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10454 10198 256 2.44 0 4376 17 0 256
v_all_customers 37254 37254 0 0.00 0 23 0 0 0
v_all_suppliers 29990 29990 0 0.00 0 13 1 0 0
v_all_wares 40758 40758 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3526 10 0 0
v_cancel_client_order 3536 3536 0 0.00 0 1645 3 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 464 3 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 1386 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 4229 10 0 0
v_cancel_supplier_invoice 872 850 22 2.52 0 1849 18 0 22
v_cancel_supplier_order 796 796 0 0.00 0 4030 11 0 0
v_cancel_write_off 2052 2052 0 0.00 0 2852 13 0 0
v_max_id_clo_ord 5274 5274 0 0.00 0 701 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 1786 12 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 4370 5 0 0
v_max_non_paid_invoice 1484 1484 0 0.00 0 3738 8 0 0
v_max_non_paid_realizn 3725 3725 0 0.00 0 5934 6 0 0
v_min_id_clo_ord 5274 5274 0 0.00 0 2987 6 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 5133 10 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 4129 6 0 0
v_min_non_paid_invoice 5776 5776 0 0.00 0 15999 27 0 0
v_min_non_paid_realizn 7075 7075 0 0.00 0 6988 20 0 0
v_our_firm 10204 10204 0 0.00 0 41 1 0 0
v_reserve_write_off 19360 19360 0 0.00 0 2950 5 0 0
x_lock_depdocs_on_canc_invoice 11942 11942 0 0.00 0 21423 107 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 15709 319 0 0
x_make_qstorno_1000_1200 5275 5275 0 0.00 2 24621 1125 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 3 83447 3131 0 0
x_make_qstorno_2100_3300 479260 479260 0 0.00 0 40015 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2021 01:40:08 to 13-11-2021 01:40:12.

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
23:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 30887 8192000000 9904228240 10215219200 977266480 10.658 100 83 166 0 1005122240 5780576 22075184
23:10:44 30575 8192000000 9961864336 10286821376 1039567632 11.261 100 92 184 0 1071560128 8601248 23391248
23:12:27 12826 8192000000 9996931120 10336075776 1084358768 11.689 100 80 160 0 1114319216 9876928 20083520
23:13:44 13919 8192000000 10052599984 10404720640 1133352256 12.153 100 71 141 1 1159684304 9954064 16377984
23:15:01 7953 8192000000 10087288416 10453528576 1175067344 12.544 100 73 146 1 1204117024 12404224 16645456
23:16:12 3170 8192000000 10119787408 10491691008 1215846080 12.923 100 76 152 4 1245729472 13173328 16710064
23:17:16 3894 8192000000 10167128512 10545250304 1258034256 13.312 100 75 151 0 1288096992 13213072 16849664
23:18:22 2917 8192000000 10199320448 10577993728 1280311280 13.516 100 90 181 3 1315314720 14094960 20908480
23:19:26 2249 8192000000 10223555424 10607575040 1301789296 13.712 100 90 181 1 1337235552 15335824 20110432
23:20:30 10588 8192000000 10217279120 10610798592 1308918672 13.776 100 77 154 2 1334467968 7884656 17664640
23:21:42 3360 8192000000 10218118640 10625097728 1318682192 13.865 100 53 109 0 1338765504 7205376 12877936
23:22:46 7987 8192000000 10213607104 10628018176 1324270272 13.915 100 20 40 1 1335240864 6269616 4700976
23:24:00 8829 8192000000 10229117824 10644688896 1334936336 14.012 100 13 26 2 1342821808 5378576 2506896
23:25:15 35022 8192000000 10221471168 10646679552 1335878928 14.020 100 26 47 1 1346660432 5114576 5666928
23:26:51 4905 8192000000 10243523952 10659033088 1337265568 14.033 100 34 68 1 1351212784 6171200 7776016
23:27:57 9190 8192000000 10227552192 10651852800 1339372432 14.052 100 16 32 0 1348976896 5836288 3768176
23:29:06 66348 8192000000 10170651120 10580062208 1181027104 12.600 89 27 54 0 1192774768 5616736 6130928
23:31:16 6142 8192000000 10138235968 10541121536 1234170480 13.093 98 28 56 0 1247678112 7175536 6332096
23:32:24 9976 8192000000 10142484608 10545881088 1229154320 13.046 98 20 40 2 1239310048 5598352 4557376
23:33:35 67630 8192000000 10176286736 10572496896 1262959168 13.357 99 27 53 2 1275389888 5997616 6433104
23:35:42 7537 8192000000 10185206224 10598338560 1296636656 13.665 100 29 58 2 1309644400 6625808 6381936
23:36:53 10095 8192000000 10197344912 10609496064 1305381584 13.744 100 19 38 1 1315204240 5699488 4123168
23:38:05 61391 8192000000 10234538352 10643206144 1319482288 13.872 100 44 85 1 1336137248 5421424 11233536
23:40:19 2183 8192000000 10264489808 10672939008 1355359616 14.196 100 57 113 1 1377396304 9787184 12249504
23:41:25 47403 8192000000 10279127920 10700582912 1373454832 14.358 100 31 62 1 1396943760 16177024 7311904
23:43:15 10966 8192000000 10308531648 10721820672 1387725632 14.486 100 51 100 2 1417313792 17975104 11613056
23:44:29 12431 8192000000 10310549072 10729897984 1395142944 14.552 100 56 112 5 1428231856 19976160 13112752
23:45:48 23708 8192000000 10327661024 10746847232 1404806432 14.638 100 57 112 1 1441363648 22552624 14004592
23:47:24 5195 8192000000 10343754000 10766426112 1415170208 14.730 100 63 124 1 1458227168 27868528 15188432
23:48:35 2148 8192000000 10357143888 10770165760 1416492400 14.742 99 75 148 2 1464123248 29268368 18362480
23:49:41 4739 8192000000 10371889264 10790936576 1424212000 14.810 100 83 166 0 1482318016 37215440 20890576
23:50:50 1792 8192000000 10358956256 10776793088 1409499696 14.679 100 71 142 2 1463418400 36684672 17234032
23:51:57 2931 8192000000 10367402384 10784718848 1413485920 14.715 100 81 164 0 1472388656 39163552 19739184
23:53:07 7960 8192000000 10387085312 10804838400 1425966000 14.826 100 82 163 2 1488984720 42278672 20740048
23:54:19 5277 8192000000 10336764960 10764292096 1385858976 14.469 99 43 86 0 1434913472 37592176 11462320
23:55:35 2060 8192000000 10342221904 10755530752 1371391296 14.340 98 60 120 1 1429768048 42796064 15580688
23:56:42 4557 8192000000 10324993920 10739847168 1375354256 14.375 100 68 133 4 1424180000 31991728 16834016
23:57:49 10623 8192000000 10321539408 10754584576 1381001920 14.426 100 30 59 1 1428803520 39279872 8521728
23:59:02 28873 8192000000 10295645760 10727022592 1361376944 14.250 99 27 54 3 1403132704 34308848 7446912
00:00:32 16562 8192000000 10285926032 10722377728 1344653680 14.099 99 25 50 2 1385540160 33699392 7187088
00:02:17 40002 8192000000 10270305344 10699243520 1332129248 13.986 99 22 44 0 1367782704 30584864 5068592
00:03:59 20375 8192000000 10253712048 10689740800 1323675888 13.910 99 17 34 1 1356053568 27921744 4455936
00:05:20 40968 8192000000 10235766176 10669776896 1307959808 13.768 98 16 30 2 1322291792 10860880 3471104
00:07:10 67187 8192000000 10261816928 10691379200 1317771168 13.857 98 41 83 1 1347076736 20223728 9081840
00:09:46 159550 8192000000 10269605392 10720182272 1275076496 13.468 94 7 14 0 1298022944 21318688 1627760
00:13:30 73979 8192000000 10284105744 10751827968 1362530064 14.260 100 9 18 0 1384103248 19353632 2219552
00:15:45 47516 8192000000 10288933312 10733301760 1376528416 14.385 100 27 54 1 1397675296 14672832 6474048
00:19:10 89490 8192000000 10298959072 10758385664 1387876848 14.487 100 25 50 0 1408094656 13988176 6229632
00:21:41 43013 8192000000 10338674912 10776010752 1404207344 14.632 100 31 64 2 1428385904 16521664 7656896
00:23:24 25316 8192000000 10341569360 10784788480 1399449664 14.590 99 47 95 1 1432572016 21644000 11478352
00:24:53 77076 8192000000 10326044928 10790092800 1392394656 14.527 99 19 38 1 1416677760 19621616 4661488
00:28:33 61713 8192000000 10309508976 10776178688 1391116672 14.516 99 16 32 1 1412374112 17547744 3709696
00:30:43 17440 8192000000 10321237840 10783907840 1413381696 14.714 100 19 38 0 1433866576 15785264 4699616
00:32:03 86508 8192000000 10319217584 10784313344 1392209760 14.526 99 12 24 0 1414976112 19080720 3685632
00:34:31 64643 8192000000 10309189568 10778943488 1383481696 14.448 98 16 32 0 1400324000 12765840 4076464
00:36:36 47815 8192000000 10311874320 10773331968 1397944560 14.577 100 17 34 0 1415504880 13498656 4061664
00:38:36 31734 8192000000 10304908848 10752585728 1354581472 14.189 97 32 61 2 1371638624 10383424 6673728
00:40:10 79276 8192000000 10307660720 10755284992 1328128560 13.950 95 22 44 0 1346085104 13755328 4201216
00:43:11 59559 8192000000 10300743952 10750840832 1339239248 14.051 97 28 56 2 1367094096 21419456 6435392
00:45:13 64248 8192000000 10281695488 10728382464 1311121024 13.796 95 31 62 1 1331197200 12962944 7113232
00:47:17 61356 8192000000 10277081120 10733805568 1342171152 14.077 98 26 52 0 1358036528 9712416 6152960
00:49:20 58761 8192000000 10283043648 10745622528 1360154464 14.239 99 21 42 0 1378323632 14208768 3960400
00:51:28 102967 8192000000 10255540992 10714857472 1324401968 13.917 97 11 22 0 1340578480 13600032 2576480
00:54:11 47769 8192000000 10230868608 10688733184 1300845120 13.703 97 5 10 0 1317212528 15352976 1014432
00:56:00 58866 8192000000 10227893472 10683142144 1323856928 13.912 100 7 14 0 1336667872 11203840 1607104
00:58:01 74898 8192000000 10242212368 10702954496 1297917536 13.676 97 4 8 0 1312537504 13922272 697696
01:00:25 57909 8192000000 10245743984 10682306560 1266931920 13.394 94 33 66 4 1288035184 13565008 7538256
01:02:23 46362 8192000000 10231161824 10669948928 1261295664 13.342 95 17 34 0 1273188528 8297088 3595776
01:04:16 13046 8192000000 10262781968 10697617408 1334381728 14.007 100 38 76 0 1356200064 13798768 8019568
01:05:30 29610 8192000000 10279303376 10712756224 1344573616 14.099 99 50 100 0 1370248992 14581200 11094176
01:07:15 75296 8192000000 10292641904 10737676288 1365689104 14.288 100 23 46 0 1383955584 13596512 4669968
01:09:36 115035 8192000000 10293684016 10763288576 1383758672 14.450 100 10 20 1 1407254096 21610768 1884656
01:12:32 77624 8192000000 10301915008 10755334144 1390166864 14.507 100 33 66 0 1409309040 11362208 7779968
01:14:50 33516 8192000000 10293238448 10762260480 1392619456 14.529 100 11 22 1 1406726352 11565696 2541200
01:16:29 58109 8192000000 10285590176 10757124096 1366723088 14.298 98 10 20 0 1378395024 9324112 2347824
01:18:28 37601 8192000000 10276734224 10733150208 1352389280 14.169 97 32 63 0 1364932848 5522352 7021216
01:21:37 87621 8192000000 10304850144 10780831744 1399584560 14.591 100 13 26 1 1419868400 16930768 3353072
01:24:23 62456 8192000000 10327506624 10794303488 1412823648 14.709 100 17 34 2 1435161632 18142016 4195968
01:26:44 50890 8192000000 10322451216 10778779648 1379325264 14.411 97 17 34 0 1393606208 10334752 3946192
01:28:49 73612 8192000000 10302346848 10757705728 1341252496 14.069 95 21 42 0 1357052848 11091984 4708368
01:31:03 18092 8192000000 10317858688 10761822208 1373371184 14.357 98 41 82 1 1392589264 10647376 8570704
01:32:22 106575 8192000000 10298433536 10748989440 1313690752 13.820 94 26 53 0 1335989392 16196976 6101664
01:35:09 81304 8192000000 10301005392 10764713984 1354494880 14.188 97 11 22 0 1374268272 17315488 2457904
01:37:33 49849 8192000000 8967065936 9156378624 138524960 1.662 11 3 4 1 140091184 1090528 475696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-11-2021 01:40:14 to 13-11-2021 01:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-11-2021 01:40:29 to 13-11-2021 01:40:31.

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          235610
MON$OLDEST_ACTIVE               235611
MON$OLDEST_SNAPSHOT             235611
MON$NEXT_TRANSACTION            235611
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                       2039776
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:201: 
:202: mscs-test-161.budzdorov.ru    Sat Nov 13 01:38:47 2021
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Sat Nov 13 01:38:47 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Sat Nov 13 01:38:47 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
--- end of diff output ---

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

13.11.21 01:40:32. 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-11-13 01:40:34 No severe PSQL-related problems occured
13.11.21 01:40:36. End of report.