Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16162184 kB
MemAvailable 31016372 kB
Buffers 0 kB
Cached 15564372 kB
SwapCached 3640 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-21 07:37:43
DTS_END                         2021-08-21 07:37:43.7070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41350
TRN_ID                          232222
ATT_ID                          691
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: 8971.10 25534 1076532
customer order: creation 167.79 388 20135
customer order: refuse 14.21 348 1705
order to supplier: creation 45.62 1399 5474
order to supplier: removal 3.34 106939 401
invoice (draft): creation 44.93 3377 5392
invoice (draft): removal 51.61 800 6193
invoice accept: apply 40.28 18994 4834
invoice accept: cancel 46.40 830 5568
customer reserve: creation 3681.93 190 441831
customer reserve: removal 4590.47 63 550856
realization accept: apply 82.78 194 9934
realization accept: cancel 76.88 113 9226
payment from customer: creation 57.97 134 6956
payment from customer: removal 5.36 94 643
payment to supplier: creation 47.78 139 5733
payment to supplier: removal 3.28 74 394
service: total inventory turnovers 3.73 3890 448
service: total monetary turnovers 5.82 466 698
service: aggregate perf. data 0.89 15436 107
service: refresh index statistics 0.03 356807 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-08-2021 07:39:08 to 21-08-2021 07:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3182
TEST_TIME 2 2955
TEST_TIME 3 3821
TEST_TIME 4 4386
TEST_TIME 5 5018
TEST_TIME 6 5390
TEST_TIME 7 5480
TEST_TIME 8 5617
TEST_TIME 9 6062
TEST_TIME 10 6258
TEST_TIME 11 6814
TEST_TIME 12 7293
TEST_TIME 13 7291
TEST_TIME 14 7503
TEST_TIME 15 7941
TEST_TIME 16 7955
TEST_TIME 17 8162
TEST_TIME 18 8317
TEST_TIME 19 8760
TEST_TIME 20 9213
TEST_TIME 21 9151
TEST_TIME 22 9246
TEST_TIME 23 9541
TEST_TIME 24 9533
TEST_TIME 25 9625
TEST_TIME 26 9644
TEST_TIME 27 10042
TEST_TIME 28 11105
TEST_TIME 29 11002
TEST_TIME 30 10687
TEST_TIME 31 10421
TEST_TIME 32 10215
TEST_TIME 33 10269
TEST_TIME 34 10216
TEST_TIME 35 10150
TEST_TIME 36 10007
TEST_TIME 37 9903
TEST_TIME 38 9770
TEST_TIME 39 9642
TEST_TIME 40 9457
TEST_TIME 41 9495
TEST_TIME 42 9419
TEST_TIME 43 9537
TEST_TIME 44 9512
TEST_TIME 45 9450
TEST_TIME 46 9425
TEST_TIME 47 9407
TEST_TIME 48 9302
TEST_TIME 49 9572
TEST_TIME 50 9495
TEST_TIME 51 9453
TEST_TIME 52 9463
TEST_TIME 53 9508
TEST_TIME 54 9600
TEST_TIME 55 9449
TEST_TIME 56 9378
TEST_TIME 57 9365
TEST_TIME 58 9281
TEST_TIME 59 9200
TEST_TIME 60 9136
TEST_TIME 61 9059
TEST_TIME 62 9096
TEST_TIME 63 9068
TEST_TIME 64 9171
TEST_TIME 65 9199
TEST_TIME 66 9254
TEST_TIME 67 9353
TEST_TIME 68 9288
TEST_TIME 69 9340
TEST_TIME 70 9493
TEST_TIME 71 9449
TEST_TIME 72 9470
TEST_TIME 73 9494
TEST_TIME 74 9462
TEST_TIME 75 9485
TEST_TIME 76 9392
TEST_TIME 77 9337
TEST_TIME 78 9299
TEST_TIME 79 9218
TEST_TIME 80 9179
TEST_TIME 81 9159
TEST_TIME 82 9153
TEST_TIME 83 9099
TEST_TIME 84 9066
TEST_TIME 85 9103
TEST_TIME 86 9063
TEST_TIME 87 9072
TEST_TIME 88 9036
TEST_TIME 89 9106
TEST_TIME 90 9103
TEST_TIME 91 9102
TEST_TIME 92 9110
TEST_TIME 93 9121
TEST_TIME 94 9093
TEST_TIME 95 9172
TEST_TIME 96 9213
TEST_TIME 97 9225
TEST_TIME 98 9172
TEST_TIME 99 9139
TEST_TIME 100 9094
TEST_TIME 101 9076
TEST_TIME 102 9040
TEST_TIME 103 9005
TEST_TIME 104 8970
TEST_TIME 105 8961
TEST_TIME 106 8972
TEST_TIME 107 8958
TEST_TIME 108 8958
TEST_TIME 109 8953
TEST_TIME 110 8985
TEST_TIME 111 8974
TEST_TIME 112 9021
TEST_TIME 113 8997
TEST_TIME 114 9003
TEST_TIME 115 9131
TEST_TIME 116 9129
TEST_TIME 117 9096
TEST_TIME 118 9052
TEST_TIME 119 9006
TEST_TIME 120 8977

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2021 07:39:12 to 21-08-2021 07:39:15.

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 1487580 1487580 0 0.00 0 26782 7 0 0
doc_list_biud 1487580 1487580 0 0.00 0 35110 29 0 0
sp_add_invoice_to_stock 4917 4834 83 1.68 40 180375 18994 0 83
sp_cancel_adding_invoice 5568 5568 0 0.00 6 84538 830 0 0
sp_cancel_client_order 1705 1705 0 0.00 5 11656 349 0 0
sp_cancel_customer_reserve 550856 550856 0 0.00 1 35111 63 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 8745 94 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 3428 74 0 0
sp_cancel_supplier_invoice 6197 6193 4 0.06 2 19915 800 0 4
sp_cancel_supplier_order 401 401 0 0.00 14 969851 106939 0 0
sp_cancel_write_off 9226 9226 0 0.00 1 7219 113 0 0
sp_client_order 20135 20135 0 0.00 4 36030 388 0 0
sp_customer_reserve 441831 441831 0 0.00 0 119965 190 0 0
sp_fill_shopping_cart 30990 30990 0 0.00 1 17009 47 0 0
sp_fill_shopping_cart_clo_res 441827 441827 0 0.00 0 16462 16 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 30877 360 0 0
sp_kill_cost_storno 15831 15831 0 0.00 0 8575 52 0 0
sp_kill_qstorno_ret_qs2qd 1117532 1117532 0 0.00 0 35106 34 0 0
sp_kill_qty_storno 588703 588703 0 0.00 0 35110 73 0 0
sp_lock_selected_doc 20421 20421 0 0.00 0 7080 5 0 0
sp_make_cost_storno 27443 27443 0 0.00 0 16847 58 0 0
sp_multiply_rows_for_pdistr 27443 27443 0 0.00 0 10046 36 0 0
sp_multiply_rows_for_qdistr 20135 20135 0 0.00 0 36015 185 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 20672 134 0 0
sp_pay_to_supplier 5733 5733 0 0.00 4 17592 140 0 0
sp_payment_common 12689 12689 0 0.00 1 11841 24 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 11651 311 0 0
sp_qd_handle_on_invoice_upd_sts 10388 10388 0 0.00 0 25798 335 0 0
sp_qd_handle_on_reserve_upd_sts 9934 9934 0 0.00 0 16192 59 0 0
sp_reserve_write_off 9934 9934 0 0.00 7 17141 195 0 0
sp_supplier_invoice 5392 5392 0 0.00 6 66190 3377 0 0
sp_supplier_order 5474 5474 0 0.00 6 55333 1399 0 0
srv_aggregate_perf_data 107 107 0 0.00 2680 138201 15437 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1859 159610 50739 0 0
srv_find_qd_qs_mism 1031286 1031286 0 0.00 0 26772 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 36 130006 3890 0 0
srv_make_money_saldo 698 698 0 0.00 1 13849 467 0 0
srv_recalc_idx_stat 4 4 0 0.00 267622 467390 356808 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4834 4834 0 0.00 0 34952 184 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 42583 206 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 4775 15 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1886 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1904 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 33237 85 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 524 8 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 33912 1532 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1664 8 0 0
t$perf-norm:sp_client_order 20135 20135 0 0.00 0 40363 24 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 1802 5 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 28741 17 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 3726 9 0 0
t$perf-norm:sp_reserve_write_off 9934 9934 0 0.00 0 6463 10 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 6814 17 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 9829 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 131 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 7709 45 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 8988 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9806 9640 166 1.69 0 5924 17 0 166
v_all_customers 36440 36440 0 0.00 0 117 1 0 0
v_all_suppliers 29962 29962 0 0.00 0 43 1 0 0
v_all_wares 40270 40270 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2445 10 0 0
v_cancel_client_order 3410 3410 0 0.00 0 5504 9 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 2893 11 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 1911 9 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 3407 9 0 0
v_cancel_supplier_invoice 854 846 8 0.93 0 2295 13 0 8
v_cancel_supplier_order 802 802 0 0.00 0 1676 9 0 0
v_cancel_write_off 2040 2040 0 0.00 0 1957 14 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 1549 2 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 5944 23 0 0
v_max_id_ord_sup 5381 5381 0 0.00 0 12001 8 0 0
v_max_non_paid_invoice 1607 1607 0 0.00 0 201 2 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 9202 7 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 2096 7 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 4364 19 0 0
v_min_id_ord_sup 5381 5381 0 0.00 0 4705 8 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 5651 12 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 11837 20 0 0
v_our_firm 10242 10242 0 0.00 0 12 1 0 0
v_reserve_write_off 19868 19868 0 0.00 0 14750 9 0 0
x_lock_depdocs_on_canc_invoice 11338 11338 0 0.00 0 30506 135 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 22394 425 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 2 55327 1319 0 0
x_make_qstorno_1200_2000 5392 5392 0 0.00 3 66006 3194 0 0
x_make_qstorno_2100_3300 441831 441831 0 0.00 0 119935 172 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-08-2021 07:39:16 to 21-08-2021 07:39:21.

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
05:07:47 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:47 10942 8192000000 9728114160 10027196416 808847888 8.986 100 86 170 1 837099904 7502128 20749888
05:10:09 21462 8192000000 9773189280 10084540416 848414432 9.384 100 87 173 0 881051488 12027664 20609392
05:11:39 12914 8192000000 9812735280 10133618688 878581152 9.686 100 92 184 0 921488880 21776672 21131056
05:12:55 16535 8192000000 9865519040 10200297472 919755776 10.094 100 87 172 1 978243168 39782208 18705184
05:14:14 11445 8192000000 9914378816 10259501056 959273696 10.482 100 87 174 0 1037284448 59715040 18295712
05:15:29 6532 8192000000 9973716416 10330034176 1000266784 10.881 100 85 170 0 1087161408 69062912 17831712
05:16:38 2934 8192000000 10013878880 10382487552 1024847440 11.119 100 84 166 1 1133160352 91876912 16436000
05:17:42 1708 8192000000 10030320464 10397413376 1051418720 11.374 100 88 174 1 1145179952 76144496 17616736
05:18:45 3724 8192000000 10044387760 10437476352 1068986608 11.542 100 74 148 1 1158367904 74168000 15213296
05:20:09 4807 8192000000 9995860800 10391703552 1074596656 11.596 100 50 99 0 1116450688 31024448 10829584
05:21:14 9624 8192000000 9993791632 10395377664 1084587184 11.691 100 36 72 2 1118454480 26666160 7201136
05:22:28 65589 8192000000 10007532928 10402177024 1090071664 11.743 100 41 81 2 1114886096 15976832 8837600
05:24:34 3930 8192000000 10024551120 10433343488 1096677248 11.806 100 52 104 0 1151898672 44282256 10939168
05:25:39 4675 8192000000 10026633232 10423607296 1101241744 11.849 100 47 87 6 1132984256 21758512 9984000
05:26:44 5997 8192000000 10003125328 10409545728 1098344624 11.822 100 27 53 2 1126353328 22830992 5177712
05:27:54 25588 8192000000 10014364080 10430611456 1097856960 11.817 100 28 55 0 1133202864 29913648 5432256
05:29:20 5253 8192000000 9977674272 10376667136 1047966480 11.341 99 43 87 2 1089422384 31817536 9638368
05:30:26 5125 8192000000 9960719504 10344427520 1036654288 11.232 99 38 76 1 1077742384 33582112 7505984
05:31:33 17257 8192000000 9916572720 10311720960 926768720 10.163 90 18 36 0 950403328 19279888 4354720
05:32:50 32717 8192000000 9920473840 10321506304 974175360 10.627 95 13 26 0 1020867328 44227152 2464816
05:34:24 6091 8192000000 9973127744 10360684544 1052570480 11.385 100 34 64 1 1077417312 17829808 7017024
05:35:34 8966 8192000000 9992125856 10391461888 1066153264 11.515 100 47 95 1 1087837824 9770080 11914480
05:36:48 92541 8192000000 9997380768 10403975168 1077541696 11.624 100 29 59 1 1103325120 19561776 6221648
05:39:32 34386 8192000000 10072000480 10476167168 1106459056 11.899 100 42 84 0 1191232000 76007536 8765408
05:41:10 12887 8192000000 10101430368 10512384000 1118784144 12.016 100 43 86 0 1220160624 92002416 9374064
05:42:30 29901 8192000000 10116340624 10532622336 1132744832 12.147 100 45 90 2 1223027552 80382160 9900560
05:44:05 16138 8192000000 10157798592 10571849728 1148840080 12.299 100 55 110 2 1264868224 104073824 11954320
05:45:27 15117 8192000000 10159104352 10581405696 1154419840 12.351 100 41 80 1 1275123856 111752896 8951120
05:46:53 6426 8192000000 10183050704 10598940672 1162515632 12.427 100 51 102 0 1285130128 111625024 10989472
05:48:03 1852 8192000000 10237954480 10648985600 1172619856 12.521 100 86 172 0 1344288768 152958288 18710624
05:49:08 2184 8192000000 10247641536 10653229056 1134206352 12.161 97 72 144 1 1326045888 177061952 14777584
05:50:16 3162 8192000000 10270992352 10688499712 1165487424 12.455 100 70 138 1 1374526128 193547216 15491488
05:51:23 3535 8192000000 10285471760 10708148224 1167300048 12.472 100 76 150 1 1387058128 202454400 17303680
05:52:30 2422 8192000000 10288827904 10703290368 1165318624 12.453 100 66 131 1 1392634128 211996048 15319456
05:53:37 2329 8192000000 10321268320 10716160000 1150427232 12.314 100 81 159 0 1413390448 244593008 18370208
05:54:42 4720 8192000000 10365257984 10778214400 1153947120 12.347 100 58 116 2 1463527136 295434208 14145808
05:55:54 4579 8192000000 10395934992 10803204096 1162316576 12.425 100 72 144 0 1481967808 302536656 17114576
05:57:04 7357 8192000000 10344440256 10766241792 1159966192 12.403 100 43 85 0 1449292928 278399168 10927568
05:58:17 8441 8192000000 10339701584 10764427264 1151871712 12.327 100 48 96 1 1438739056 275212352 11654992
05:59:36 10999 8192000000 10339531296 10786820096 1131421984 12.135 98 35 70 1 1441505216 300600160 9483072
06:00:55 20806 8192000000 10319619120 10767867904 1144609904 12.259 99 27 54 0 1413355312 261863088 6882320
06:02:18 17935 8192000000 10318434784 10787569664 1110697232 11.939 98 20 40 3 1401755088 286133856 4924000
06:03:58 34557 8192000000 10293219104 10735136768 1106752832 11.902 99 16 32 0 1390140800 278804544 4583424
06:05:44 16676 8192000000 10039827680 10466762752 1093906416 11.780 99 15 30 1 1130707824 33719296 3082112
06:07:02 95785 8192000000 10061712544 10485276672 1101749872 11.854 99 32 59 3 1156873024 49153248 5969904
06:09:39 89505 8192000000 10081260240 10524581888 1120551632 12.032 99 27 54 0 1181102960 53405072 7146256
06:12:10 84434 8192000000 10027539376 10464432128 1086382480 11.708 96 11 22 0 1134666864 46330544 1953840
06:14:38 119195 8192000000 10093394928 10544611328 1126105344 12.085 99 18 36 1 1191686352 61605904 3975104
06:18:06 68558 8192000000 10107694656 10581073920 1137403600 12.191 100 12 24 0 1223606848 83202896 3000352
06:20:19 47216 8192000000 10085974848 10578321408 1142549312 12.240 100 11 22 0 1186568816 41708912 2310592
06:22:08 43964 8192000000 10085831584 10520879104 1120940816 12.036 98 27 55 3 1157543504 31131088 5471600
06:23:52 43352 8192000000 10095477024 10561527808 1131211616 12.133 99 18 36 1 1197363328 62257696 3894016
06:25:52 83113 8192000000 10075984736 10614792192 1128555808 12.108 99 25 50 3 1257773584 123906720 5311056
06:28:15 30505 8192000000 10102741040 10541686784 1150304096 12.312 100 43 81 4 1198420608 39278576 8837936
06:29:46 67192 8192000000 10109260368 10630541312 1149077584 12.301 100 22 44 1 1233517248 79981808 4457856
06:31:57 99023 8192000000 10084057552 10552971264 1135773936 12.176 99 26 52 0 1176803152 34818912 6210304
06:35:35 44718 8192000000 10067060416 10530570240 1151033744 12.319 100 12 23 0 1192366080 38827248 2505088
06:37:45 58447 8192000000 10131840048 10604163072 1136854064 12.186 98 15 30 0 1225570656 85444032 3272560
06:40:40 88295 8192000000 10103448640 10580795392 1100031520 11.838 95 16 32 1 1175978656 72505728 3441408
06:43:08 110396 8192000000 10093643248 10589626368 1100178912 11.839 96 11 22 2 1167455232 65212160 2064160
06:46:00 57764 8192000000 10139047584 10626162688 1081476560 11.662 95 34 68 1 1216004304 127328032 7199712
06:48:00 106193 8192000000 10076615600 10545111040 1104710576 11.882 98 21 42 2 1147132160 37631248 4790336
06:50:52 76095 8192000000 10093733888 10547216384 1073772752 11.588 95 22 44 0 1112023536 33963856 4286928
06:53:08 111511 8192000000 10077038672 10522775552 1077022560 11.619 96 30 59 0 1134299696 50396272 6880864
06:56:03 23024 8192000000 10070163008 10503348224 1087446784 11.718 98 40 80 0 1159012144 63131840 8433520
06:57:29 64584 8192000000 10067086576 10520510464 1106323424 11.898 99 22 43 1 1179566288 68415856 4827008
06:59:34 56627 8192000000 10066313904 10522595328 1115985040 11.989 100 12 23 0 1171280272 53168016 2127216
07:01:33 75039 8192000000 10045574576 10521034752 1102298928 11.859 99 17 34 0 1154288304 48250256 3739120
07:03:51 41505 8192000000 10069352464 10503065600 1091756560 11.759 98 34 68 1 1137043328 37998608 7288160
07:05:33 38756 8192000000 10109890304 10558988288 1111398256 11.946 99 34 68 0 1177427696 59073568 6955872
07:07:20 159608 8192000000 10091441072 10563485696 1090367824 11.746 96 12 24 0 1108506640 16081408 2057408
07:11:00 58704 8192000000 10132842816 10599428096 1135628464 12.174 99 28 56 1 1220489792 79251264 5610064
07:13:04 40376 8192000000 10098670304 10561114112 1133520208 12.155 99 26 52 0 1195678032 57061488 5096336
07:14:45 83716 8192000000 10106935776 10558746624 1120049584 12.027 98 24 46 0 1172178080 47209520 4918976
07:18:12 52309 8192000000 10087864544 10540802048 1130871552 12.130 99 18 36 0 1177354640 42976912 3506176
07:21:23 92013 8192000000 10124567712 10577895424 1145021744 12.263 99 19 37 0 1195530032 45927616 4580672
07:23:55 47797 8192000000 10063595408 10530738176 1112964096 11.960 97 33 65 3 1151880928 31053040 7863792
07:25:44 69474 8192000000 10108631376 10562568192 1136753232 12.185 99 19 38 1 1210183456 69278048 4152176
07:27:53 85790 8192000000 10097810592 10583588864 1129240288 12.114 98 20 40 1 1195268304 61077280 4950736
07:30:21 28624 8192000000 10074865184 10510712832 1112477232 11.956 98 35 70 2 1141272064 21611840 7182992
07:31:55 79303 8192000000 10097924944 10675318784 1130944480 12.130 99 18 36 0 1243766928 109270464 3551984
07:34:22 18163 8192000000 10080423584 10517073920 1139584848 12.212 100 38 76 2 1170265072 23149568 7530656
07:35:45 105402 8192000000 10055019216 10515423232 1061284304 11.469 93 34 68 0 1125432976 57437920 6710752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-08-2021 07:39:22 to 21-08-2021 07:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-08-2021 07:39:38 to 21-08-2021 07:39:40.

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          230331
MON$OLDEST_ACTIVE               232662
MON$OLDEST_SNAPSHOT             232662
MON$NEXT_TRANSACTION            232662
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                       2051424
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Aug 21 07:37:50 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Aug 21 07:37:51 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
--- end of diff output ---

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

21.08.21 07:39:41. 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-21 07:39:43 No severe PSQL-related problems occured
21.08.21 07:39:45. End of report.