Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 13:37
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 16400444 kB
MemAvailable 30968024 kB
Buffers 0 kB
Cached 15261900 kB
SwapCached 2580 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-16 13:37:05
DTS_END                         2021-08-16 13:37:05.1780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51146
TRN_ID                          226997
ATT_ID                          680
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: 8580.62 25110 1029674
customer order: creation 164.61 448 19753
customer order: refuse 14.18 365 1701
order to supplier: creation 43.93 1564 5272
order to supplier: removal 3.31 106859 397
invoice (draft): creation 42.84 4005 5141
invoice (draft): removal 47.24 947 5669
invoice accept: apply 38.77 19698 4652
invoice accept: cancel 42.89 802 5147
customer reserve: creation 3675.66 189 441079
customer reserve: removal 4224.32 65 506918
realization accept: apply 80.66 176 9679
realization accept: cancel 77.48 121 9298
payment from customer: creation 58.55 127 7026
payment from customer: removal 5.27 78 632
payment to supplier: creation 47.13 117 5656
payment to supplier: removal 3.23 100 387
service: total inventory turnovers 3.81 3537 457
service: total monetary turnovers 5.82 551 698
service: aggregate perf. data 0.90 17771 108
service: refresh index statistics 0.03 344680 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 13:37:42 to 16-08-2021 13:37:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5562
TEST_TIME 2 5650
TEST_TIME 3 5858
TEST_TIME 4 5376
TEST_TIME 5 4807
TEST_TIME 6 4850
TEST_TIME 7 5034
TEST_TIME 8 4958
TEST_TIME 9 4981
TEST_TIME 10 5413
TEST_TIME 11 5724
TEST_TIME 12 5745
TEST_TIME 13 6198
TEST_TIME 14 6717
TEST_TIME 15 6757
TEST_TIME 16 6935
TEST_TIME 17 6986
TEST_TIME 18 7468
TEST_TIME 19 7947
TEST_TIME 20 8591
TEST_TIME 21 8502
TEST_TIME 22 8695
TEST_TIME 23 8888
TEST_TIME 24 9250
TEST_TIME 25 9632
TEST_TIME 26 10091
TEST_TIME 27 10029
TEST_TIME 28 9920
TEST_TIME 29 9762
TEST_TIME 30 9663
TEST_TIME 31 9569
TEST_TIME 32 9322
TEST_TIME 33 9325
TEST_TIME 34 9360
TEST_TIME 35 9265
TEST_TIME 36 9136
TEST_TIME 37 9070
TEST_TIME 38 8986
TEST_TIME 39 8984
TEST_TIME 40 9075
TEST_TIME 41 8957
TEST_TIME 42 8796
TEST_TIME 43 8628
TEST_TIME 44 8688
TEST_TIME 45 8647
TEST_TIME 46 8646
TEST_TIME 47 8576
TEST_TIME 48 8477
TEST_TIME 49 8614
TEST_TIME 50 8589
TEST_TIME 51 8578
TEST_TIME 52 8647
TEST_TIME 53 8683
TEST_TIME 54 8621
TEST_TIME 55 8804
TEST_TIME 56 8752
TEST_TIME 57 8840
TEST_TIME 58 8820
TEST_TIME 59 8802
TEST_TIME 60 8966
TEST_TIME 61 9033
TEST_TIME 62 8902
TEST_TIME 63 8864
TEST_TIME 64 8761
TEST_TIME 65 8813
TEST_TIME 66 8753
TEST_TIME 67 8676
TEST_TIME 68 8712
TEST_TIME 69 8700
TEST_TIME 70 8745
TEST_TIME 71 8685
TEST_TIME 72 8677
TEST_TIME 73 8786
TEST_TIME 74 8811
TEST_TIME 75 8784
TEST_TIME 76 8748
TEST_TIME 77 8795
TEST_TIME 78 8857
TEST_TIME 79 8883
TEST_TIME 80 8926
TEST_TIME 81 8846
TEST_TIME 82 8789
TEST_TIME 83 8748
TEST_TIME 84 8706
TEST_TIME 85 8706
TEST_TIME 86 8673
TEST_TIME 87 8628
TEST_TIME 88 8586
TEST_TIME 89 8555
TEST_TIME 90 8652
TEST_TIME 91 8645
TEST_TIME 92 8659
TEST_TIME 93 8670
TEST_TIME 94 8699
TEST_TIME 95 8679
TEST_TIME 96 8762
TEST_TIME 97 8768
TEST_TIME 98 8796
TEST_TIME 99 8793
TEST_TIME 100 8787
TEST_TIME 101 8839
TEST_TIME 102 8824
TEST_TIME 103 8758
TEST_TIME 104 8715
TEST_TIME 105 8665
TEST_TIME 106 8696
TEST_TIME 107 8648
TEST_TIME 108 8608
TEST_TIME 109 8588
TEST_TIME 110 8568
TEST_TIME 111 8599
TEST_TIME 112 8615
TEST_TIME 113 8613
TEST_TIME 114 8641
TEST_TIME 115 8625
TEST_TIME 116 8673
TEST_TIME 117 8656
TEST_TIME 118 8679
TEST_TIME 119 8647
TEST_TIME 120 8584

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-08-2021 13:37:45 to 16-08-2021 13:37:49.

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 1436885 1436885 0 0.00 0 24735 7 0 0
doc_list_biud 1436884 1436884 0 0.00 0 62420 29 0 0
sp_add_invoice_to_stock 4839 4652 187 3.86 26 169152 19699 0 187
sp_cancel_adding_invoice 5147 5147 0 0.00 5 57244 803 0 0
sp_cancel_client_order 1701 1701 0 0.00 5 8080 365 0 0
sp_cancel_customer_reserve 506918 506918 0 0.00 1 50355 65 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 3392 78 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 6481 100 0 0
sp_cancel_supplier_invoice 5680 5669 11 0.19 2 62424 948 0 11
sp_cancel_supplier_order 397 397 0 0.00 8 1038480 106859 0 0
sp_cancel_write_off 9298 9298 0 0.00 1 8586 121 0 0
sp_client_order 19753 19753 0 0.00 4 36138 448 0 0
sp_customer_reserve 441079 441079 0 0.00 0 43485 190 0 0
sp_fill_shopping_cart 30163 30163 0 0.00 1 17788 48 0 0
sp_fill_shopping_cart_clo_res 441073 441073 0 0.00 0 21829 17 0 0
sp_get_clo_for_invoice 4646 4646 0 0.00 0 25146 414 0 0
sp_kill_cost_storno 15464 15464 0 0.00 0 24724 64 0 0
sp_kill_qstorno_ret_qs2qd 1029200 1029200 0 0.00 0 62419 36 0 0
sp_kill_qty_storno 543454 543454 0 0.00 0 62420 77 0 0
sp_lock_selected_doc 19823 19823 0 0.00 0 5826 4 0 0
sp_make_cost_storno 27007 27007 0 0.00 0 19680 55 0 0
sp_multiply_rows_for_pdistr 27006 27006 0 0.00 0 9255 31 0 0
sp_multiply_rows_for_qdistr 19753 19753 0 0.00 0 17108 227 0 0
sp_pay_from_customer 7026 7026 0 0.00 4 15315 127 0 0
sp_pay_to_supplier 5656 5656 0 0.00 5 17003 118 0 0
sp_payment_common 12682 12682 0 0.00 1 6775 21 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 7077 335 0 0
sp_qd_handle_on_invoice_upd_sts 9792 9792 0 0.00 0 31578 363 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 17244 55 0 0
sp_reserve_write_off 9679 9679 0 0.00 6 17400 177 0 0
sp_supplier_invoice 5141 5141 0 0.00 8 91008 4005 0 0
sp_supplier_order 5272 5272 0 0.00 5 53266 1564 0 0
srv_aggregate_perf_data 108 108 0 0.00 2394 151352 17772 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1030 166499 48820 0 0
srv_find_qd_qs_mism 983321 983321 0 0.00 0 24198 6 0 0
srv_make_invnt_saldo 457 457 0 0.00 46 119903 3537 0 0
srv_make_money_saldo 698 698 0 0.00 1 15078 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 177498 502651 344681 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 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 4652 4652 0 0.00 0 15503 156 0 0
t$perf-norm:sp_cancel_adding_invoice 335 335 0 0.00 0 6894 109 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 9888 17 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 2833 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 1954 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 2817 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 10640 41 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 40820 1608 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 9385 19 0 0
t$perf-norm:sp_client_order 19753 19753 0 0.00 0 9743 16 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 924 6 0 0
t$perf-norm:sp_pay_from_customer 7026 7026 0 0.00 0 6011 10 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 8695 12 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 9187 12 0 0
t$perf-norm:sp_supplier_invoice 5141 5141 0 0.00 0 5831 16 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 2999 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 265 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1653 36 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 4103 20 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 2286 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9662 9288 374 3.87 0 7257 20 0 374
v_all_customers 36512 36512 0 0.00 0 110 1 0 0
v_all_suppliers 28814 28814 0 0.00 0 99 1 0 0
v_all_wares 39506 39506 0 0.00 0 166 1 0 0
v_cancel_adding_invoice 670 670 0 0.00 0 580 6 0 0
v_cancel_client_order 3402 3402 0 0.00 0 6978 6 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 678 6 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 1785 6 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 816 7 0 0
v_cancel_supplier_invoice 730 708 22 3.01 0 6959 26 0 22
v_cancel_supplier_order 794 794 0 0.00 0 414 4 0 0
v_cancel_write_off 1930 1930 0 0.00 0 3216 15 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 4266 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 3423 15 0 0
v_max_id_ord_sup 5139 5139 0 0.00 0 3063 5 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 1052 3 0 0
v_max_non_paid_realizn 3629 3629 0 0.00 0 4948 6 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 1837 5 0 0
v_min_id_clo_res 997 997 0 0.00 0 4094 15 0 0
v_min_id_ord_sup 5139 5139 0 0.00 0 5818 7 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 6260 12 0 0
v_min_non_paid_realizn 7026 7026 0 0.00 0 6557 16 0 0
v_our_firm 9788 9788 0 0.00 0 15 1 0 0
v_reserve_write_off 19358 19358 0 0.00 0 6560 8 0 0
x_lock_depdocs_on_canc_invoice 10462 10462 0 0.00 0 14492 113 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 16029 424 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 2 53172 1491 0 0
x_make_qstorno_1200_2000 5141 5141 0 0.00 4 90674 3806 0 0
x_make_qstorno_2100_3300 441079 441079 0 0.00 0 39838 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 13:37:50 to 16-08-2021 13:37:54.

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
11:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:10 28768 8192000000 9726056640 10027061248 808206624 8.979 100 86 172 0 836460336 7710928 20542784
11:09:41 25265 8192000000 9771748944 10078359552 846373248 9.364 100 93 186 1 880552320 12349936 21829136
11:11:11 16473 8192000000 9797958144 10117378048 866048096 9.561 100 78 156 1 907168096 23515952 17604048
11:12:31 10008 8192000000 9852436768 10182696960 904608416 9.944 100 77 154 0 962889648 41319360 16961872
11:13:45 14639 8192000000 9909930864 10256527360 943904112 10.331 100 80 160 1 1028645008 67489856 17251040
11:15:04 5256 8192000000 9954829872 10308354048 985501744 10.738 100 80 160 0 1068998736 67101936 16395056
11:16:11 3814 8192000000 9969151536 10324029440 1010218304 10.977 100 80 158 1 1085802992 60156624 15428064
11:17:17 2736 8192000000 9997761552 10359332864 1034498272 11.212 100 89 179 2 1106931328 53936256 18496800
11:18:21 2231 8192000000 10028008272 10393968640 1050883328 11.369 100 90 179 1 1136667536 66818400 18965808
11:19:26 27095 8192000000 10010545072 10394816512 1061280960 11.469 100 64 127 3 1126757776 53114112 12362704
11:20:54 6384 8192000000 9981055968 10370752512 1071399280 11.565 100 47 89 2 1100653408 21045600 8208528
11:22:01 5300 8192000000 9988485664 10380562432 1082870928 11.675 100 49 98 1 1108009984 13812896 11326160
11:23:08 8290 8192000000 9989486864 10384941056 1085674304 11.702 100 44 87 1 1108285600 12221280 10390016
11:24:17 14004 8192000000 10006480512 10413961216 1090309760 11.746 100 41 80 2 1118230752 19801232 8119760
11:25:35 13548 8192000000 10019130352 10408091648 1093290528 11.774 100 75 154 2 1122079472 12232272 16556672
11:26:48 5230 8192000000 10015516528 10413236224 1084513168 11.690 99 28 54 0 1126704592 36482992 5708432
11:27:55 9218 8192000000 10011473984 10423668736 1086626960 11.711 99 30 59 1 1125427728 32638672 6162096
11:29:05 47051 8192000000 10000956720 10398031872 1050003920 11.361 98 40 80 3 1074024448 15171840 8848688
11:30:52 6472 8192000000 9948978848 10336227328 1017068848 11.044 98 30 58 2 1046143776 22757792 6317136
11:32:02 4961 8192000000 9942662864 10323918848 1012915104 11.004 99 43 84 5 1048164272 25661072 9588096
11:33:12 12793 8192000000 9949902320 10342891520 1018345312 11.056 99 26 52 1 1059945936 36057600 5543024
11:34:27 38497 8192000000 9967960128 10359504896 1039240688 11.257 100 45 90 1 1084640656 35554960 9845008
11:36:07 9427 8192000000 9969046864 10364051456 1049982224 11.361 100 41 82 1 1084813504 26231328 8599952
11:37:18 20585 8192000000 9991873424 10383216640 1073348128 11.584 100 40 79 1 1093416368 11314640 8753600
11:38:43 12783 8192000000 10062761328 10454896640 1098711664 11.825 100 51 100 2 1165405520 56517232 10176624
11:40:03 7226 8192000000 10078066736 10474094592 1116007824 11.989 100 43 86 2 1181357712 56134544 9215344
11:41:35 65792 8192000000 10129662176 10544246784 1128282240 12.105 100 45 88 2 1233517184 94912752 10322192
11:43:51 12712 8192000000 10177448688 10588684288 1139011296 12.206 100 48 94 2 1284633088 134203968 11417824
11:45:09 17168 8192000000 10214010704 10629279744 1152850640 12.336 100 51 102 0 1308633456 144859184 10923632
11:46:28 1884 8192000000 10245237200 10648961024 1153240448 12.340 100 72 143 0 1345718128 176877264 15600416
11:47:37 4645 8192000000 10277789232 10694836224 1174198016 12.536 100 60 119 0 1398822560 211670592 12953952
11:48:46 2440 8192000000 10287037616 10716372992 1176014224 12.553 100 69 133 2 1390108016 198474432 15619360
11:49:53 6084 8192000000 10317131008 10757111808 1176507072 12.558 100 71 140 1 1442258672 249477376 16274224
11:51:12 1029 8192000000 10355733632 10787057664 1192827104 12.710 100 89 174 0 1453351776 240852272 19672400
11:52:18 13937 8192000000 10375060848 10793656320 1192656080 12.708 100 63 125 0 1470556112 263439840 14460192
11:53:38 6597 8192000000 10340857888 10769256448 1179898304 12.589 99 59 115 0 1424319728 230932416 13489008
11:54:52 2802 8192000000 10416210288 10827358208 1181560256 12.605 100 79 157 0 1516136976 316754752 17821968
11:55:58 3716 8192000000 10418460768 10835877888 1190852256 12.691 100 78 155 0 1518064576 308103120 19109200
11:57:09 4659 8192000000 10372004896 10799972352 1175425088 12.548 100 49 98 0 1480277616 292426016 12426512
11:58:18 14587 8192000000 10305663040 10733367296 1170189904 12.499 100 27 54 1 1408542096 231634256 6717936
11:59:36 18813 8192000000 10276061696 10721628160 1121551792 12.042 97 25 50 0 1388759744 260197184 7010768
12:01:05 17968 8192000000 10240121824 10717315072 1123407408 12.059 99 24 48 1 1342684752 213126784 6150560
12:02:31 29491 8192000000 10163842832 10642063360 1129020112 12.112 100 15 30 0 1304017776 171111760 3885904
12:04:05 85892 8192000000 10091337200 10551132160 1073560944 11.586 95 25 48 4 1158803184 79428400 5813840
12:06:38 47016 8192000000 10121416928 10552483840 1101884064 11.856 98 28 56 0 1210807648 103185632 5737952
12:08:27 76676 8192000000 10115105632 10564542464 1125744896 12.081 100 25 48 2 1221115328 89436592 5933840
12:10:45 69997 8192000000 10051310016 10495598592 1092715696 11.768 97 26 51 0 1148492800 50748288 5028816
12:12:57 59266 8192000000 10087013840 10532876288 1101442352 11.851 98 15 30 0 1153168304 48312432 3413520
12:15:34 95782 8192000000 10098112656 10545377280 1105992096 11.894 98 17 34 0 1212441104 103022848 3426160
12:18:54 131575 8192000000 10118291776 10559574016 1132924816 12.149 100 24 48 0 1202719488 64614928 5179744
12:23:48 64840 8192000000 10090512624 10547240960 1123183504 12.057 99 15 30 0 1178661728 52050992 3427232
12:25:54 50278 8192000000 10118734240 10598887424 1141080912 12.226 100 15 30 0 1222237648 77663712 3493024
12:27:48 53421 8192000000 10098335936 10566348800 1146764336 12.279 100 19 38 0 1214271472 63134944 4372192
12:30:40 55434 8192000000 10131419376 10583531520 1150308896 12.312 100 25 50 0 1209288496 54214400 4765200
12:32:42 78807 8192000000 10105554880 10559246336 1124165680 12.066 98 21 42 1 1190624960 61774128 4685152
12:35:20 74674 8192000000 10123412560 10595450880 1149436496 12.304 99 18 36 1 1227329408 74006768 3886144
12:39:37 120350 8192000000 10090511392 10541936640 1112100656 11.952 96 28 53 1 1161658944 43999632 5558656
12:42:38 38014 8192000000 10038926400 10493820928 1108205744 11.915 97 15 30 3 1132638560 21104096 3328720
12:45:13 69584 8192000000 10083176896 10607116288 1125098224 12.075 98 10 20 0 1189032448 61630304 2303920
12:47:25 39998 8192000000 10056717328 10502594560 1112724704 11.958 98 23 46 0 1159251392 41530176 4996512
12:49:06 41228 8192000000 10131605840 10564911104 1123953840 12.064 99 31 63 0 1212531280 82177856 6399584
12:50:48 63266 8192000000 10069261808 10528083968 1123310704 12.058 99 24 48 1 1181469072 53225616 4932752
12:52:53 24021 8192000000 10091267520 10523811840 1121521168 12.041 99 32 64 2 1168172096 40477808 6173120
12:54:22 77643 8192000000 10081045328 10541543424 1095240256 11.792 97 13 26 0 1145784000 47579440 2964304
12:56:44 142188 8192000000 10045955088 10493579264 1069406672 11.546 95 23 46 1 1111299552 37200368 4692512
13:00:07 43957 8192000000 10073507424 10527920128 1103163216 11.868 99 27 54 0 1145899392 37149232 5586944
13:01:52 88647 8192000000 10040362912 10514374656 1068342112 11.536 96 10 20 0 1132185584 61642624 2200848
13:04:21 90750 8192000000 10084602864 10541830144 1106330496 11.898 99 17 34 3 1165680960 55615856 3734608
13:06:53 66446 8192000000 10039248880 10476044288 1104927952 11.884 98 16 31 0 1159259584 51467440 2864192
13:09:02 48798 8192000000 10061323232 10505994240 1115300688 11.983 99 18 36 0 1154810384 36068192 3441504
13:10:51 59446 8192000000 10125706064 10574876672 1128790464 12.110 99 18 36 1 1201592256 69083536 3718256
13:12:56 46740 8192000000 10057038176 10542411776 1086503056 11.709 97 15 30 0 1182828480 92958672 3366752
13:14:48 45515 8192000000 10060593968 10522705920 1105537632 11.890 99 23 46 0 1168360336 58306448 4516256
13:16:33 38641 8192000000 10093828944 10519203840 1112838176 11.959 99 30 61 1 1170881392 52285664 5757552
13:18:19 166499 8192000000 10067242160 10508197888 1102997728 11.866 97 26 52 1 1142433968 34343168 5093072
13:22:18 15913 8192000000 10093938992 10530144256 1129359184 12.115 99 34 67 0 1181285872 45187152 6739536
13:23:37 52102 8192000000 10078401488 10566393856 1136872368 12.186 100 14 28 0 1189593168 49995424 2725376
13:25:32 47966 8192000000 10078739120 10576138240 1128343648 12.106 99 11 21 2 1196986832 66262240 2380944
13:27:21 67966 8192000000 10108514000 10604060672 1114114144 11.971 97 19 38 0 1197642624 79341680 4186800
13:29:39 76282 8192000000 10084807952 10552827904 1100634800 11.844 97 16 32 1 1172684096 68171152 3878144
13:31:58 48305 8192000000 10099838656 10543042560 1133968672 12.159 100 18 37 0 1181410400 43840672 3601056
13:33:57 53510 8192000000 10062440256 10498129920 1083329120 11.679 95 24 47 3 1132670912 43691360 5650432
13:35:56 46753 8192000000 10052879184 10487898112 1115609872 11.985 98 24 48 1 1139796112 19723440 4462800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-08-2021 13:37:56 to 16-08-2021 13:38:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 187 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 16-08-2021 13:38:12 to 16-08-2021 13:38:14.

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          224913
MON$OLDEST_ACTIVE               227134
MON$OLDEST_SNAPSHOT             227134
MON$NEXT_TRANSACTION            227134
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                       2020352
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             782
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 ---
:112: 
:113: mscs-test-161.budzdorov.ru    Mon Aug 16 13:37:07 2021
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Mon Aug 16 13:37:11 2021
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Mon Aug 16 13:37:11 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Mon Aug 16 13:37:11 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
--- end of diff output ---

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

16.08.21 13:38:15. 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-16 13:38:17 No severe PSQL-related problems occured
16.08.21 13:38:19. End of report.