Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2021 13:40
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 16195012 kB
MemAvailable 31080636 kB
Buffers 12 kB
Cached 15485436 kB
SwapCached 2548 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-02 13:37:15
DTS_END                         2021-08-02 13:37:15.0730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56136
TRN_ID                          237552
ATT_ID                          648
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: 8868.92 25187 1064271
customer order: creation 171.81 388 20617
customer order: refuse 14.53 343 1743
order to supplier: creation 45.60 1455 5472
order to supplier: removal 3.33 105311 400
invoice (draft): creation 45.35 3662 5442
invoice (draft): removal 51.45 836 6174
invoice accept: apply 41.12 19669 4934
invoice accept: cancel 45.29 839 5435
customer reserve: creation 3715.35 200 445842
customer reserve: removal 4445.60 63 533472
realization accept: apply 84.53 188 10143
realization accept: cancel 77.03 126 9244
payment from customer: creation 59.58 126 7150
payment from customer: removal 5.52 112 662
payment to supplier: creation 48.73 129 5847
payment to supplier: removal 3.56 75 427
service: total inventory turnovers 3.55 3599 426
service: total monetary turnovers 5.96 599 715
service: aggregate perf. data 1.02 12606 122
service: refresh index statistics 0.03 353404 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2021 13:40:43 to 02-08-2021 13:40:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5178
TEST_TIME 2 4453
TEST_TIME 3 6167
TEST_TIME 4 6367
TEST_TIME 5 6468
TEST_TIME 6 6428
TEST_TIME 7 6464
TEST_TIME 8 6774
TEST_TIME 9 6722
TEST_TIME 10 6996
TEST_TIME 11 7111
TEST_TIME 12 7101
TEST_TIME 13 7326
TEST_TIME 14 7600
TEST_TIME 15 7917
TEST_TIME 16 7912
TEST_TIME 17 8275
TEST_TIME 18 8576
TEST_TIME 19 8592
TEST_TIME 20 8868
TEST_TIME 21 9003
TEST_TIME 22 9059
TEST_TIME 23 8846
TEST_TIME 24 8714
TEST_TIME 25 8788
TEST_TIME 26 9814
TEST_TIME 27 9881
TEST_TIME 28 10237
TEST_TIME 29 10611
TEST_TIME 30 10544
TEST_TIME 31 10218
TEST_TIME 32 9993
TEST_TIME 33 9958
TEST_TIME 34 9809
TEST_TIME 35 9643
TEST_TIME 36 9678
TEST_TIME 37 9578
TEST_TIME 38 9511
TEST_TIME 39 9420
TEST_TIME 40 9301
TEST_TIME 41 9154
TEST_TIME 42 9243
TEST_TIME 43 9142
TEST_TIME 44 9035
TEST_TIME 45 9011
TEST_TIME 46 8919
TEST_TIME 47 8854
TEST_TIME 48 8960
TEST_TIME 49 9043
TEST_TIME 50 9038
TEST_TIME 51 8954
TEST_TIME 52 9098
TEST_TIME 53 9231
TEST_TIME 54 9180
TEST_TIME 55 9156
TEST_TIME 56 9222
TEST_TIME 57 9193
TEST_TIME 58 9266
TEST_TIME 59 9286
TEST_TIME 60 9209
TEST_TIME 61 9111
TEST_TIME 62 9046
TEST_TIME 63 9011
TEST_TIME 64 9038
TEST_TIME 65 8934
TEST_TIME 66 9007
TEST_TIME 67 8996
TEST_TIME 68 8962
TEST_TIME 69 8937
TEST_TIME 70 8908
TEST_TIME 71 8958
TEST_TIME 72 9001
TEST_TIME 73 9067
TEST_TIME 74 9067
TEST_TIME 75 9161
TEST_TIME 76 9129
TEST_TIME 77 9146
TEST_TIME 78 9071
TEST_TIME 79 9031
TEST_TIME 80 9002
TEST_TIME 81 8967
TEST_TIME 82 8933
TEST_TIME 83 8953
TEST_TIME 84 8944
TEST_TIME 85 8889
TEST_TIME 86 8866
TEST_TIME 87 8866
TEST_TIME 88 8853
TEST_TIME 89 8840
TEST_TIME 90 8830
TEST_TIME 91 8859
TEST_TIME 92 8925
TEST_TIME 93 8915
TEST_TIME 94 8932
TEST_TIME 95 8912
TEST_TIME 96 8957
TEST_TIME 97 9028
TEST_TIME 98 8992
TEST_TIME 99 8947
TEST_TIME 100 8875
TEST_TIME 101 8864
TEST_TIME 102 8896
TEST_TIME 103 8872
TEST_TIME 104 8829
TEST_TIME 105 8835
TEST_TIME 106 8845
TEST_TIME 107 8827
TEST_TIME 108 8850
TEST_TIME 109 8852
TEST_TIME 110 8862
TEST_TIME 111 8857
TEST_TIME 112 8866
TEST_TIME 113 8937
TEST_TIME 114 9001
TEST_TIME 115 9022
TEST_TIME 116 8993
TEST_TIME 117 9039
TEST_TIME 118 8993
TEST_TIME 119 8941
TEST_TIME 120 8869

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2021 13:40:47 to 02-08-2021 13:40:50.

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 1478832 1478832 0 0.00 0 24422 7 0 0
doc_list_biud 1478832 1478832 0 0.00 0 97411 29 0 0
sp_add_invoice_to_stock 5014 4934 80 1.59 39 224425 19670 0 80
sp_cancel_adding_invoice 5435 5435 0 0.00 5 75392 839 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 19921 344 0 0
sp_cancel_customer_reserve 533472 533472 0 0.00 1 87142 64 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 11500 112 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 2543 75 0 0
sp_cancel_supplier_invoice 6179 6174 5 0.08 2 97416 836 0 5
sp_cancel_supplier_order 400 400 0 0.00 7 790103 105312 0 0
sp_cancel_write_off 9244 9244 0 0.00 1 14501 127 0 0
sp_client_order 20617 20617 0 0.00 4 81487 389 0 0
sp_customer_reserve 445842 445842 0 0.00 0 51927 200 0 0
sp_fill_shopping_cart 31520 31520 0 0.00 1 11032 38 0 0
sp_fill_shopping_cart_clo_res 445841 445841 0 0.00 0 20834 16 0 0
sp_get_clo_for_invoice 4933 4933 0 0.00 0 27696 350 0 0
sp_kill_cost_storno 15768 15768 0 0.00 0 11896 57 0 0
sp_kill_qstorno_ret_qs2qd 1082762 1082762 0 0.00 0 97408 34 0 0
sp_kill_qty_storno 571544 571544 0 0.00 0 97411 74 0 0
sp_lock_selected_doc 20812 20812 0 0.00 0 7531 6 0 0
sp_make_cost_storno 28073 28073 0 0.00 0 18149 57 0 0
sp_multiply_rows_for_pdistr 28073 28073 0 0.00 0 11422 32 0 0
sp_multiply_rows_for_qdistr 20617 20617 0 0.00 0 81466 199 0 0
sp_pay_from_customer 7150 7150 0 0.00 4 17169 127 0 0
sp_pay_to_supplier 5847 5847 0 0.00 5 11672 130 0 0
sp_payment_common 12997 12997 0 0.00 1 10755 24 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 19885 316 0 0
sp_qd_handle_on_invoice_upd_sts 10368 10368 0 0.00 0 27747 318 0 0
sp_qd_handle_on_reserve_upd_sts 10143 10143 0 0.00 0 15114 57 0 0
sp_reserve_write_off 10143 10143 0 0.00 6 19555 189 0 0
sp_supplier_invoice 5442 5442 0 0.00 8 103814 3663 0 0
sp_supplier_order 5472 5472 0 0.00 6 42167 1456 0 0
srv_aggregate_perf_data 122 122 0 0.00 2660 71578 12607 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1201 139358 46195 0 0
srv_find_qd_qs_mism 1018151 1018151 0 0.00 0 24421 7 0 0
srv_make_invnt_saldo 426 426 0 0.00 79 111491 3599 0 0
srv_make_money_saldo 715 715 0 0.00 1 17346 600 0 0
srv_recalc_idx_stat 4 4 0 0.00 179872 493020 353404 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4934 4934 0 0.00 0 15665 174 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 4246 111 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 2001 11 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 780 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 4791 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2820 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 946 14 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 36408 1631 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 3613 12 0 0
t$perf-norm:sp_client_order 20617 20617 0 0.00 0 15362 19 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 4155 10 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 7602 9 0 0
t$perf-norm:sp_pay_to_supplier 5847 5847 0 0.00 0 11232 16 0 0
t$perf-norm:sp_reserve_write_off 10143 10143 0 0.00 0 8926 12 0 0
t$perf-norm:sp_supplier_invoice 5442 5442 0 0.00 0 6630 21 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 3920 14 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 4302 47 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 66 2 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 3214 20 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 686 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 70 19 0 0
v_add_invoice_to_stock 10024 9864 160 1.59 0 3630 15 0 160
v_all_customers 37960 37960 0 0.00 0 19 1 0 0
v_all_suppliers 30256 30256 0 0.00 0 19 1 0 0
v_all_wares 41234 41234 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1246 8 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2122 6 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2296 7 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 2004 9 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 2294 8 0 0
v_cancel_supplier_invoice 842 832 10 1.18 0 1537 16 0 10
v_cancel_supplier_order 800 800 0 0.00 0 2502 8 0 0
v_cancel_write_off 1994 1994 0 0.00 0 2912 18 0 0
v_max_id_clo_ord 5472 5472 0 0.00 0 436 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 4647 26 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 4110 4 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 154 2 0 0
v_max_non_paid_realizn 3710 3710 0 0.00 0 4505 7 0 0
v_min_id_clo_ord 5472 5472 0 0.00 0 1923 5 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 8604 15 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 10309 8 0 0
v_min_non_paid_invoice 5847 5847 0 0.00 0 5166 13 0 0
v_min_non_paid_realizn 7150 7150 0 0.00 0 6306 17 0 0
v_our_firm 10154 10154 0 0.00 0 16 1 0 0
v_reserve_write_off 20286 20286 0 0.00 0 2406 5 0 0
x_lock_depdocs_on_canc_invoice 11193 11193 0 0.00 0 16689 100 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 4313 297 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 2 42152 1382 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 3 103807 3523 0 0
x_make_qstorno_2100_3300 445842 445842 0 0.00 0 51926 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2021 13:40:51 to 02-08-2021 13:40:55.

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:17 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 10000 8192000000 9697587968 9990057984 780193344 8.695 100 90 180 0 810218192 7926528 22098320
11:09:37 22649 8192000000 9754671200 10058096640 828538624 9.185 100 84 168 0 859804224 11330320 19935280
11:11:02 21432 8192000000 9791879440 10107973632 861044608 9.511 100 86 172 2 900616912 19602032 19970272
11:12:27 11303 8192000000 9847047664 10181480448 913169264 10.029 100 79 158 0 964876384 34346080 17361040
11:13:41 7425 8192000000 9909893488 10252791808 955656800 10.447 100 83 166 3 1022992016 49682912 17652304
11:14:53 5210 8192000000 9972277664 10323361792 996668240 10.846 100 84 167 3 1085873040 72729200 16475600
11:16:00 3515 8192000000 10003753168 10367680512 1027245376 11.142 100 83 165 2 1118916880 74735904 16935600
11:17:05 1960 8192000000 10008453136 10374668288 1054611856 11.405 100 94 189 0 1124274240 50545312 19117072
11:18:08 1141 8192000000 10040779856 10411900928 1064158368 11.496 100 92 185 0 1160107808 76344800 19604640
11:19:10 2388 8192000000 9997230032 10390175744 1060048240 11.457 100 48 97 0 1103287072 33830272 9408560
11:20:15 3853 8192000000 9997171392 10387808256 1071298480 11.564 100 54 112 1 1112365680 28921104 12146096
11:21:21 5063 8192000000 9988354224 10384834560 1081431152 11.661 100 44 88 1 1106167152 16403552 8332448
11:22:28 85855 8192000000 9994016944 10401980416 1082173424 11.668 100 24 49 1 1112168656 24692288 5302944
11:24:55 4197 8192000000 10011570896 10409283584 1094688560 11.787 100 52 108 0 1135053808 28584240 11781008
11:26:01 5250 8192000000 10020369504 10425524224 1085676000 11.702 99 37 74 0 1120971680 27385936 7909744
11:27:10 3977 8192000000 10014326128 10405949440 1098861632 11.827 100 41 81 0 1125074544 16105392 10107520
11:28:19 18442 8192000000 9996821568 10409148416 1032341920 11.191 95 28 56 2 1068941648 30867696 5732032
11:29:40 7558 8192000000 9983960496 10375782400 1020754544 11.079 97 53 104 1 1065512912 33523168 11235200
11:30:49 6836 8192000000 9926440896 10298466304 942129312 10.314 93 51 102 2 977129616 23264448 11735856
11:31:58 7931 8192000000 9942931088 10326155264 1008489568 10.961 99 37 74 1 1033150656 15992800 8668288
11:33:09 19061 8192000000 9933122160 10319888384 1017587920 11.049 99 26 52 0 1061238256 38250688 5399648
11:34:28 8076 8192000000 9970655040 10371944448 1052072304 11.381 100 40 80 3 1085289856 24658016 8559536
11:35:38 8860 8192000000 9996149920 10400202752 1063491440 11.490 100 33 63 1 1085111856 14873440 6746976
11:36:47 69758 8192000000 9980435424 10382344192 1076733584 11.616 100 34 67 3 1099532960 15381920 7417456
11:39:02 5265 8192000000 10040958304 10436956160 1103351008 11.869 100 57 115 2 1147359792 32788640 11220144
11:40:12 14812 8192000000 10085484080 10493685760 1118352304 12.011 100 61 121 1 1193811968 62358496 13101168
11:41:31 13060 8192000000 10093420256 10502504448 1129450816 12.116 100 49 99 1 1207298528 67421952 10425760
11:42:48 3229 8192000000 10124677840 10530213888 1144594208 12.259 100 61 122 2 1234893136 77536400 12762528
11:43:52 10133 8192000000 10130858064 10557235200 1150458208 12.314 100 41 80 3 1253109888 93427920 9223760
11:45:27 5809 8192000000 10143919760 10561716224 1148966160 12.300 100 47 94 1 1249537456 90620624 9950672
11:46:42 4085 8192000000 10177201168 10590007296 1150641632 12.316 100 53 106 1 1279726832 118076256 11008944
11:47:50 6635 8192000000 10197549696 10614149120 1154169184 12.349 100 54 108 0 1303900592 137668928 12062480
11:49:04 15482 8192000000 10233688800 10650476544 1153477952 12.342 99 56 112 0 1325268512 158843968 12946592
11:50:25 1201 8192000000 10231129440 10638221312 1150504880 12.314 99 92 180 2 1330562048 160246304 19810864
11:51:30 7231 8192000000 10257834048 10669449216 1156430304 12.370 100 75 149 1 1358180624 184387744 17362576
11:52:42 3150 8192000000 10252581936 10680967168 1166647104 12.465 100 73 145 3 1366297680 182090400 17560176
11:53:49 6287 8192000000 10303059472 10712219648 1169777680 12.495 100 66 130 2 1403255920 218587664 14890576
11:55:02 5755 8192000000 10266884208 10676244480 1140149712 12.217 100 49 96 0 1368062368 216120192 11792464
11:56:12 5760 8192000000 10273773264 10688421888 1147986512 12.291 99 73 143 5 1376587664 211151312 17449840
11:57:20 3984 8192000000 10319902080 10740207616 1156998384 12.375 100 54 108 0 1421999072 252781232 12219456
11:58:43 4075 8192000000 10262650448 10683678720 1150198960 12.311 100 77 148 1 1358906240 190947824 17759456
11:59:50 30507 8192000000 10295918336 10717937664 1139324784 12.209 99 24 48 1 1382266064 236693376 6247904
12:01:25 7028 8192000000 10378310512 10794188800 1149419488 12.304 100 44 87 0 1469152032 308709056 11023488
12:02:36 23457 8192000000 10393595824 10842636288 1131213984 12.133 100 33 66 0 1496150000 355990416 8945600
12:04:09 62368 8192000000 10194797232 10685050880 1114067232 11.971 99 18 36 1 1314280080 195580448 4632400
12:06:24 55358 8192000000 10089886016 10516639744 1091642608 11.758 98 6 12 1 1161347024 68261344 1443072
12:08:20 89829 8192000000 10058077392 10492563456 1090954928 11.752 98 41 81 0 1136942016 36962192 9024896
12:10:59 62897 8192000000 10077832672 10521976832 1107977328 11.913 99 15 30 0 1163215840 51824480 3414032
12:13:04 69021 8192000000 10056937280 10539462656 1124280832 12.067 100 16 32 1 1209614928 81875472 3458624
12:15:19 69019 8192000000 10052819200 10497511424 1115757936 11.987 99 16 32 2 1141246976 21794176 3694864
12:17:33 109824 8192000000 10085464336 10554101760 1137492128 12.192 100 25 50 0 1196807888 53863648 5452112
12:20:24 28671 8192000000 10075480640 10549202944 1145694000 12.269 100 20 40 1 1185430688 35709760 4026928
12:21:55 75211 8192000000 10118220384 10597785600 1136597216 12.184 99 15 30 0 1220527008 80434400 3495392
12:24:13 70812 8192000000 10133968096 10659483648 1147720048 12.288 100 7 14 0 1266578112 117172944 1685120
12:26:24 44013 8192000000 10111450624 10588835840 1133510832 12.154 99 8 16 0 1184507984 49200992 1796160
12:28:13 42995 8192000000 10153891632 10610491392 1160707376 12.410 100 28 56 2 1255292208 88061184 6523648
12:30:00 74412 8192000000 10082736208 10552664064 1127390288 12.097 98 17 34 1 1182658384 52049632 3218464
12:32:20 31965 8192000000 10109535104 10560057344 1146087584 12.273 100 36 72 0 1195417488 42289552 7040352
12:33:53 89307 8192000000 10127658464 10614902784 1095457968 11.795 96 12 24 0 1173707552 76093072 2156512
12:36:25 139358 8192000000 10098114976 10532618240 1141937600 12.234 100 34 66 1 1204184864 55279600 6967664
12:39:48 52751 8192000000 10137533552 10589732864 1150620016 12.315 100 28 56 0 1211297616 54851904 5825696
12:41:50 32364 8192000000 10097308144 10623688704 1133935184 12.158 99 5 10 0 1186691776 51972736 783856
12:43:23 73746 8192000000 10081456176 10561429504 1119051248 12.018 98 9 18 0 1173465392 52646848 1767296
12:45:37 32707 8192000000 10058016560 10492936192 1112429504 11.955 98 30 58 1 1138875888 20818672 5627712
12:47:11 46351 8192000000 10114125776 10558767104 1062444704 11.480 93 32 64 0 1144776576 75394592 6937280
12:49:03 58465 8192000000 10112447456 10585133056 1105352992 11.888 98 10 20 0 1209175392 101663168 2159232
12:51:17 34761 8192000000 10102391008 10536026112 1099949408 11.837 97 29 58 0 1165456272 59291184 6215680
12:52:53 38745 8192000000 10064619008 10483507200 1068605456 11.539 95 31 62 1 1114448448 39903744 5939248
12:54:58 64574 8192000000 10061631088 10510282752 1093443184 11.775 99 41 82 0 1156941552 55468224 8030144
12:57:17 12708 8192000000 10043692672 10470666240 1104687568 11.882 99 46 92 1 1143461536 28703424 10070544
12:58:30 72499 8192000000 10100661952 10587844608 1109856544 11.931 99 17 32 1 1195361392 81758944 3745904
13:00:46 56591 8192000000 10055661600 10573828096 1109599968 11.929 99 4 8 0 1160407168 49971488 835712
13:02:48 59506 8192000000 10041130480 10503495680 1101382768 11.851 98 15 30 0 1121377536 17083504 2911264
13:04:54 55552 8192000000 10058882880 10507145216 1116780624 11.997 99 12 24 1 1170625264 51015136 2829504
13:07:13 25765 8192000000 10105182848 10543411200 1128030240 12.103 99 33 66 1 1189442144 55048192 6363712
13:08:45 118858 8192000000 10090820128 10561998848 1115318288 11.983 97 17 34 0 1169263856 50734320 3211248
13:11:58 41124 8192000000 10103493920 10544963584 1103337744 11.869 96 37 71 2 1140125216 29424080 7363392
13:13:39 53694 8192000000 10115684896 10584997888 1122374368 12.049 98 17 34 1 1187812496 62084560 3353568
13:15:41 26495 8192000000 10125616928 10548514816 1137161648 12.189 99 57 114 1 1181095152 31035408 12898096
13:17:13 116428 8192000000 10140331776 10615717888 1147039152 12.282 100 29 58 1 1259388544 105768368 6581024
13:20:16 51149 8192000000 10098799456 10568073216 1134751872 12.166 98 15 30 1 1186067008 47858288 3456848
13:22:09 74319 8192000000 10095522128 10599669760 1135468928 12.173 98 14 28 0 1219741088 81019008 3253152
13:24:58 57006 8192000000 10115979920 10574401536 1116317600 11.992 97 29 57 2 1168714560 45782304 6614656
13:26:55 81329 8192000000 10152442960 10591670272 1144653264 12.259 99 16 32 1 1228544464 79893488 3997712
13:29:22 59284 8192000000 10179989168 10642219008 1133585728 12.155 97 31 63 0 1232269968 91571168 7113072
13:31:23 46721 8192000000 10093482000 10580434944 1120203376 12.029 97 11 22 1 1176496704 54145424 2147904
13:33:14 109251 8192000000 10089073184 10584244224 1082289216 11.669 94 17 34 0 1168359584 82834192 3236176
13:36:03 88064 8192000000 9660515152 10052734976 717316128 8.051 62 23 46 0 782213360 59884544 5012688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-08-2021 13:40:57 to 02-08-2021 13:41:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2021 13:41:13 to 02-08-2021 13:41:15.

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          237551
MON$OLDEST_ACTIVE               238356
MON$OLDEST_SNAPSHOT             238356
MON$NEXT_TRANSACTION            238356
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                       2051488
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2555 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2555 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2555 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2555 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Mon Aug  2 13:38:16 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Mon Aug  2 13:38:20 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Mon Aug  2 13:38:44 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

02.08.21 13:41:16. 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-02 13:41:19 No severe PSQL-related problems occured
02.08.21 13:41:20. End of report.