Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.06.2021 07:28
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 16957556 kB
MemAvailable 31451440 kB
Buffers 0 kB
Cached 14710616 kB
SwapCached 4 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.0
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-06-08 07:26:59
DTS_END                         2021-06-08 07:26:59.1600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35080
TRN_ID                          225315
ATT_ID                          624
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: 8730.42 23363 1047650
customer order: creation 164.13 433 19695
customer order: refuse 14.97 337 1796
order to supplier: creation 44.15 1406 5298
order to supplier: removal 3.25 128096 390
invoice (draft): creation 43.82 3723 5258
invoice (draft): removal 49.38 1072 5926
invoice accept: apply 39.42 19263 4731
invoice accept: cancel 44.32 955 5318
customer reserve: creation 3657.82 187 438938
customer reserve: removal 4389.39 74 526727
realization accept: apply 80.18 193 9622
realization accept: cancel 78.42 138 9410
payment from customer: creation 56.37 103 6764
payment from customer: removal 5.17 111 620
payment to supplier: creation 45.87 126 5504
payment to supplier: removal 2.93 98 352
service: total inventory turnovers 3.78 3430 454
service: total monetary turnovers 6.17 598 740
service: aggregate perf. data 0.86 15972 103
service: refresh index statistics 0.03 290945 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-06-2021 07:28:29 to 08-06-2021 07:28:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4263
TEST_TIME 2 3264
TEST_TIME 3 4278
TEST_TIME 4 4318
TEST_TIME 5 4678
TEST_TIME 6 4774
TEST_TIME 7 4627
TEST_TIME 8 5067
TEST_TIME 9 5066
TEST_TIME 10 5396
TEST_TIME 11 5814
TEST_TIME 12 5989
TEST_TIME 13 6272
TEST_TIME 14 6434
TEST_TIME 15 6455
TEST_TIME 16 6533
TEST_TIME 17 6924
TEST_TIME 18 7168
TEST_TIME 19 7151
TEST_TIME 20 7305
TEST_TIME 21 8163
TEST_TIME 22 8082
TEST_TIME 23 8096
TEST_TIME 24 8775
TEST_TIME 25 9471
TEST_TIME 26 10671
TEST_TIME 27 10963
TEST_TIME 28 10726
TEST_TIME 29 10605
TEST_TIME 30 10429
TEST_TIME 31 10388
TEST_TIME 32 10291
TEST_TIME 33 10262
TEST_TIME 34 10291
TEST_TIME 35 10293
TEST_TIME 36 10172
TEST_TIME 37 9928
TEST_TIME 38 9791
TEST_TIME 39 9814
TEST_TIME 40 9741
TEST_TIME 41 9727
TEST_TIME 42 9643
TEST_TIME 43 9605
TEST_TIME 44 9476
TEST_TIME 45 9486
TEST_TIME 46 9344
TEST_TIME 47 9263
TEST_TIME 48 9208
TEST_TIME 49 9306
TEST_TIME 50 9201
TEST_TIME 51 9156
TEST_TIME 52 9239
TEST_TIME 53 9169
TEST_TIME 54 9183
TEST_TIME 55 9209
TEST_TIME 56 9150
TEST_TIME 57 9130
TEST_TIME 58 9237
TEST_TIME 59 9291
TEST_TIME 60 9218
TEST_TIME 61 9258
TEST_TIME 62 9178
TEST_TIME 63 9041
TEST_TIME 64 8973
TEST_TIME 65 8917
TEST_TIME 66 8866
TEST_TIME 67 8803
TEST_TIME 68 8811
TEST_TIME 69 8779
TEST_TIME 70 8840
TEST_TIME 71 8980
TEST_TIME 72 8941
TEST_TIME 73 8904
TEST_TIME 74 8882
TEST_TIME 75 9037
TEST_TIME 76 9018
TEST_TIME 77 9070
TEST_TIME 78 9019
TEST_TIME 79 8909
TEST_TIME 80 8921
TEST_TIME 81 8851
TEST_TIME 82 8859
TEST_TIME 83 8802
TEST_TIME 84 8812
TEST_TIME 85 8811
TEST_TIME 86 8805
TEST_TIME 87 8783
TEST_TIME 88 8848
TEST_TIME 89 8821
TEST_TIME 90 8876
TEST_TIME 91 8935
TEST_TIME 92 8933
TEST_TIME 93 8925
TEST_TIME 94 9015
TEST_TIME 95 9002
TEST_TIME 96 8935
TEST_TIME 97 8880
TEST_TIME 98 8822
TEST_TIME 99 8764
TEST_TIME 100 8802
TEST_TIME 101 8773
TEST_TIME 102 8753
TEST_TIME 103 8759
TEST_TIME 104 8727
TEST_TIME 105 8713
TEST_TIME 106 8697
TEST_TIME 107 8771
TEST_TIME 108 8866
TEST_TIME 109 8912
TEST_TIME 110 8915
TEST_TIME 111 8915
TEST_TIME 112 8894
TEST_TIME 113 8835
TEST_TIME 114 8812
TEST_TIME 115 8773
TEST_TIME 116 8732
TEST_TIME 117 8698
TEST_TIME 118 8702
TEST_TIME 119 8683
TEST_TIME 120 8732

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-06-2021 07:28:33 to 08-06-2021 07:28:36.

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 1452602 1452602 0 0.00 0 28621 7 0 0
doc_list_biud 1452601 1452601 0 0.00 0 33525 34 0 0
sp_add_invoice_to_stock 4840 4731 109 2.25 31 191443 19264 0 109
sp_cancel_adding_invoice 5318 5318 0 0.00 5 81783 956 0 0
sp_cancel_client_order 1796 1796 0 0.00 4 14246 337 0 0
sp_cancel_customer_reserve 526727 526727 0 0.00 1 26361 75 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 11393 112 0 0
sp_cancel_pay_to_supplier 352 352 0 0.00 3 6844 98 0 0
sp_cancel_supplier_invoice 5933 5926 7 0.11 2 19689 1073 0 7
sp_cancel_supplier_order 390 390 0 0.00 6 1325461 128097 0 0
sp_cancel_write_off 9410 9410 0 0.00 1 18820 138 0 0
sp_client_order 19695 19695 0 0.00 4 33051 433 0 0
sp_customer_reserve 438938 438938 0 0.00 0 77518 188 0 0
sp_fill_shopping_cart 30244 30244 0 0.00 1 25051 49 0 0
sp_fill_shopping_cart_clo_res 438934 438934 0 0.00 0 25551 20 0 0
sp_get_clo_for_invoice 4726 4726 0 0.00 0 33350 409 0 0
sp_kill_cost_storno 15700 15700 0 0.00 0 9043 62 0 0
sp_kill_qstorno_ret_qs2qd 1069180 1069180 0 0.00 0 26355 41 0 0
sp_kill_qty_storno 563915 563915 0 0.00 0 33524 87 0 0
sp_lock_selected_doc 19974 19974 0 0.00 0 6769 5 0 0
sp_make_cost_storno 26616 26616 0 0.00 0 15209 52 0 0
sp_multiply_rows_for_pdistr 26616 26616 0 0.00 0 10283 34 0 0
sp_multiply_rows_for_qdistr 19695 19695 0 0.00 0 25665 240 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 12469 104 0 0
sp_pay_to_supplier 5504 5504 0 0.00 5 15612 127 0 0
sp_payment_common 12268 12268 0 0.00 1 12420 21 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 1 14238 302 0 0
sp_qd_handle_on_invoice_upd_sts 10044 10044 0 0.00 0 33524 382 0 0
sp_qd_handle_on_reserve_upd_sts 9622 9622 0 0.00 0 18901 68 0 0
sp_reserve_write_off 9622 9622 0 0.00 6 19263 193 0 0
sp_supplier_invoice 5258 5258 0 0.00 8 132557 3723 0 0
sp_supplier_order 5298 5298 0 0.00 5 108227 1407 0 0
srv_aggregate_perf_data 103 103 0 0.00 2695 177179 15972 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2166 135791 45274 0 0
srv_find_qd_qs_mism 1001642 1001642 0 0.00 0 28621 6 0 0
srv_make_invnt_saldo 454 454 0 0.00 47 100582 3430 0 0
srv_make_money_saldo 740 740 0 0.00 0 17466 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 171680 457390 290945 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4731 4731 0 0.00 0 10422 174 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 7741 91 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 5203 18 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 5065 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 3022 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 352 352 0 0.00 0 1432 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 659 14 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 22446 1519 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 1446 4 0 0
t$perf-norm:sp_client_order 19695 19695 0 0.00 0 8361 20 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 5344 10 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 8468 9 0 0
t$perf-norm:sp_pay_to_supplier 5504 5504 0 0.00 0 5226 8 0 0
t$perf-norm:sp_reserve_write_off 9622 9622 0 0.00 0 6292 11 0 0
t$perf-norm:sp_supplier_invoice 5258 5258 0 0.00 0 3908 16 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 4440 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 484 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 458 11 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 1251 10 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 683 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9668 9450 218 2.25 0 11081 18 0 218
v_all_customers 36362 36362 0 0.00 0 28 1 0 0
v_all_suppliers 29286 29286 0 0.00 0 27 1 0 0
v_all_wares 39386 39386 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1884 9 0 0
v_cancel_client_order 3592 3592 0 0.00 0 2470 5 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 1715 5 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 531 3 0 0
v_cancel_payment_to_supplier 704 704 0 0.00 0 385 5 0 0
v_cancel_supplier_invoice 824 810 14 1.69 0 1266 16 0 14
v_cancel_supplier_order 780 780 0 0.00 0 3943 11 0 0
v_cancel_write_off 1922 1922 0 0.00 0 3919 18 0 0
v_max_id_clo_ord 5295 5295 0 0.00 0 3972 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 2153 13 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 6753 6 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 1681 4 0 0
v_max_non_paid_realizn 3483 3483 0 0.00 0 1467 3 0 0
v_min_id_clo_ord 5295 5295 0 0.00 0 3503 5 0 0
v_min_id_clo_res 960 960 0 0.00 0 2369 7 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 6286 11 0 0
v_min_non_paid_invoice 5504 5504 0 0.00 0 10083 17 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 12414 12 0 0
v_our_firm 9586 9586 0 0.00 0 13 1 0 0
v_reserve_write_off 19244 19244 0 0.00 0 3704 7 0 0
x_lock_depdocs_on_canc_invoice 10839 10839 0 0.00 0 29860 144 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 11000 416 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 2 108196 1329 0 0
x_make_qstorno_1200_2000 5258 5258 0 0.00 4 118396 3524 0 0
x_make_qstorno_2100_3300 438938 438938 0 0.00 0 77516 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-06-2021 07:28:37 to 08-06-2021 07:28:41.

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
04:57:02 4 8192000000 8799479968 8810979328 5350240 0.065 2 1 2 0 5449632 63616 35776
04:58:04 23912 8192000000 9733525728 10035286016 815613360 9.054 100 89 178 0 845185504 8467888 21104256
04:59:36 24439 8192000000 9781821376 10091270144 853547248 9.436 100 85 171 1 886319424 13254432 19517744
05:01:08 20999 8192000000 9822338752 10144325632 878733168 9.687 100 84 168 0 925942912 28025440 19184304
05:02:33 16096 8192000000 9885383456 10220158976 930308176 10.198 100 86 172 1 993941248 44510768 19122304
05:03:51 8820 8192000000 9933730816 10282631168 965104608 10.539 100 80 156 3 1051177904 69748928 16324368
05:05:02 4506 8192000000 9972748944 10328317952 994377088 10.824 100 82 164 1 1079968880 69108688 16483104
05:06:11 3532 8192000000 9975383632 10337390592 1015180064 11.025 100 70 140 2 1093824496 65009616 13634816
05:07:17 2330 8192000000 9999963328 10370146304 1040977456 11.274 100 85 168 2 1114078560 55949376 17151728
05:08:21 10529 8192000000 10046060752 10417623040 1064111904 11.496 100 77 151 3 1154805872 75912240 14781728
05:09:32 6808 8192000000 10009103408 10394267648 1075932064 11.609 100 45 91 0 1128459344 43555568 8971712
05:10:41 5244 8192000000 10014380576 10405740544 1081541520 11.662 100 44 90 3 1129539312 38944816 9052976
05:11:50 9422 8192000000 10021870576 10408923136 1090359216 11.746 100 71 140 3 1124577120 18413456 15804448
05:13:00 6330 8192000000 10006883840 10414678016 1090349680 11.746 100 31 60 1 1124458480 28043696 6065104
05:14:08 4363 8192000000 10030796464 10428981248 1095902768 11.799 100 48 97 2 1131478320 25535648 10039904
05:15:14 51689 8192000000 10009829552 10411220992 1098716752 11.825 100 44 88 1 1115088784 7360816 9011216
05:17:10 5218 8192000000 9995029328 10389340160 1081959648 11.666 99 47 94 1 1108669088 16876496 9832944
05:18:18 8704 8192000000 9977441840 10385793024 1060688736 11.463 98 23 44 2 1085078032 18904016 5485280
05:19:28 9116 8192000000 9953720416 10343407616 1003181808 10.909 96 37 74 5 1040477904 28782608 8513488
05:20:37 40348 8192000000 9902064576 10271727616 934656704 10.240 90 45 92 1 952929280 8486320 9786256
05:22:19 2294 8192000000 9931535936 10316140544 1017588768 11.049 99 40 79 1 1049374832 23484768 8301296
05:23:23 8519 8192000000 9948526592 10341384192 1020100416 11.073 98 41 81 1 1051430448 21486432 9843600
05:24:32 7339 8192000000 9961652880 10361212928 1045984288 11.322 99 33 65 0 1066587088 13301040 7301760
05:25:42 60015 8192000000 9983296224 10404073472 1062046336 11.476 100 23 46 1 1100226704 33370224 4810144
05:27:52 33056 8192000000 10017528736 10418003968 1092049824 11.762 100 50 101 1 1126427296 23897152 10480320
05:29:29 15110 8192000000 10089687424 10495713280 1110761152 11.940 100 50 100 1 1194581552 73554928 10265472
05:30:53 16811 8192000000 10111432784 10517020672 1128676304 12.109 100 52 104 1 1212579120 73041696 10861120
05:32:15 20100 8192000000 10171474176 10583789568 1145948704 12.271 100 50 100 1 1275997600 118958976 11089920
05:33:42 35263 8192000000 10235966752 10658217984 1153066848 12.338 100 51 102 0 1330788368 166638784 11082736
05:35:33 13389 8192000000 10247762960 10671804416 1158021184 12.385 100 50 100 0 1362642160 192900096 11720880
05:36:53 7353 8192000000 10266335392 10680913920 1171239536 12.508 100 61 122 2 1382868144 198112720 13515888
05:38:04 4700 8192000000 10311089616 10720256000 1180997040 12.599 100 76 150 1 1418183232 220377264 16808928
05:39:13 2993 8192000000 10363905360 10778062848 1188013920 12.665 100 82 160 1 1457820304 252121840 17684544
05:40:20 2165 8192000000 10365589136 10771013632 1180219200 12.592 100 85 171 0 1464269664 265319424 18731040
05:41:26 7822 8192000000 10405522432 10821173248 1188307920 12.668 100 64 128 0 1508779344 306277152 14194272
05:42:43 4975 8192000000 10472127600 10882002944 1188559120 12.670 100 78 154 0 1566327616 359452304 18316192
05:43:56 2819 8192000000 10464822368 10874073088 1181323152 12.603 100 83 166 1 1563012576 362426016 19263408
05:45:02 5053 8192000000 10493830192 10910834688 1177904080 12.571 100 60 117 1 1596723408 404893248 13926080
05:46:13 3409 8192000000 10554916448 10961096704 1172486528 12.520 100 91 180 0 1648413312 453312576 22614208
05:47:20 9128 8192000000 10550302432 10993373184 1181907248 12.608 100 54 108 1 1645265104 449517440 13840416
05:48:32 9756 8192000000 10486063904 10929283072 1170599792 12.502 100 35 68 4 1597854816 417677264 9577760
05:49:49 16809 8192000000 10479248944 10929541120 1133934176 12.158 99 34 68 0 1605278112 461847264 9496672
05:51:11 13346 8192000000 10372391568 10827669504 1133193920 12.151 100 31 62 2 1494704080 352785024 8725136
05:52:27 33042 8192000000 10170666736 10740875264 1111089872 11.943 98 22 44 0 1413944016 296987200 5866944
05:54:11 39638 8192000000 10070460096 10511695872 1094913616 11.789 98 24 48 2 1147569072 47324496 5330960
05:55:52 87929 8192000000 10099113840 10552901632 1056373392 11.422 95 12 24 2 1150278592 91053776 2851424
05:58:39 82917 8192000000 10112865552 10563428352 1109693344 11.930 99 25 50 2 1236366320 121003248 5669728
06:01:13 135790 8192000000 10059827568 10531635200 1128073296 12.103 100 22 44 0 1183369792 50331568 4964928
06:04:48 26258 8192000000 10097980320 10536083456 1122172976 12.048 100 55 108 2 1194139088 60406464 11559648
06:06:15 32093 8192000000 10066281584 10512240640 1129572368 12.117 100 40 79 1 1169714224 31907216 8234640
06:07:54 74314 8192000000 10108298400 10570059776 1130136992 12.123 99 20 39 1 1182225568 48101328 3987248
06:10:13 47990 8192000000 10092316016 10546159616 1116974048 11.998 98 13 26 0 1175260688 55270128 3016512
06:12:01 30445 8192000000 10067773520 10545831936 1136641040 12.184 100 14 28 0 1179282592 39724368 2917184
06:13:34 48311 8192000000 10116589760 10593214464 1141824464 12.233 100 11 22 0 1215720176 71359024 2536688
06:16:06 41255 8192000000 10067684048 10501468160 1104566032 11.881 97 34 68 3 1132733440 21083376 7084032
06:17:50 75202 8192000000 10036704096 10494521344 1082344368 11.670 95 13 26 0 1126307376 41342336 2620672
06:20:12 47359 8192000000 10089648944 10548211712 1141107984 12.226 100 26 52 1 1191681376 45375408 5197984
06:22:07 19203 8192000000 10079606448 10521395200 1142609712 12.240 100 39 77 1 1176923632 25601648 8712272
06:23:28 59646 8192000000 10091010336 10593849344 1118062720 12.009 98 26 52 2 1211958752 88545424 5350608
06:25:28 54147 8192000000 10053729776 10497912832 1102728128 11.864 97 16 33 1 1154400592 48522320 3150144
06:27:24 33133 8192000000 10064219920 10501230592 1123021040 12.056 99 26 52 1 1164767664 36723536 5023088
06:28:58 74863 8192000000 10043685248 10471759872 1098582896 11.824 97 36 72 0 1139870096 33160304 8126896
06:31:14 63328 8192000000 10061272464 10504581120 1090745936 11.750 97 8 14 1 1139205088 47084912 1374240
06:33:19 71913 8192000000 10120056688 10585079808 1077462544 11.623 95 15 30 1 1157536272 76719568 3354160
06:35:32 76790 8192000000 10165759072 10635378688 1133054688 12.150 100 19 39 0 1267331744 129995680 4281376
06:38:12 30208 8192000000 10067053328 10496835584 1132544896 12.145 100 37 73 3 1165381088 24912656 7923536
06:39:48 75284 8192000000 10111710432 10597773312 1118765808 12.015 99 28 56 0 1202153104 76956368 6430928
06:42:12 84626 8192000000 10123938624 10570526720 1111830832 11.950 98 21 42 1 1177667488 61626000 4210656
06:44:45 105202 8192000000 10059717056 10536308736 1125811936 12.082 99 17 34 0 1207283792 77629360 3842496
06:47:46 72610 8192000000 10083460960 10578317312 1118658288 12.014 99 10 20 1 1153909520 32493792 2757440
06:49:59 67793 8192000000 10085341632 10535141376 1123080368 12.056 99 24 48 1 1207080480 78220448 5779664
06:52:17 53859 8192000000 10121948352 10577928192 1132394304 12.144 99 20 38 1 1202922480 65784832 4743344
06:54:26 52203 8192000000 10109748880 10535264256 1112847360 11.959 97 32 64 1 1184568864 64379264 7342240
06:56:18 71644 8192000000 10088715360 10615570432 1097330368 11.812 97 20 40 1 1183577584 82207904 4039312
06:58:41 31984 8192000000 10090866128 10527162368 1116187872 11.991 98 22 43 0 1144146832 23911344 4047616
07:00:13 106690 8192000000 10072250400 10499657728 1085953120 11.704 95 15 30 0 1134883664 45713904 3216640
07:03:04 79099 8192000000 10097238800 10550312960 1142759600 12.241 100 21 40 1 1197989648 50877088 4352960
07:05:29 78550 8192000000 10087923728 10601287680 1146780384 12.279 100 11 22 0 1238896464 89425680 2690400
07:08:36 42461 8192000000 10085810656 10551779328 1136273504 12.180 99 27 54 0 1179874560 37406048 6195008
07:10:23 28559 8192000000 10111784656 10548527104 1149304480 12.303 99 23 45 1 1196100000 42084560 4710960
07:11:52 29247 8192000000 10175796624 10609188864 1150063680 12.310 98 38 76 1 1241433808 82973488 8396640
07:13:22 124253 8192000000 10196014512 10740592640 1146005344 12.272 99 11 22 0 1283134016 134039984 3088688
07:16:35 49743 8192000000 10088685504 10549628928 1130299360 12.124 99 18 36 0 1178867904 44918048 3650496
07:18:26 23534 8192000000 10105566272 10522398720 1098510160 11.824 96 61 121 3 1159082112 48393088 12178864
07:20:59 67569 8192000000 10075610624 10540548096 1133886176 12.158 100 6 12 0 1188692848 53446144 1360528
07:24:30 40495 8192000000 10071813840 10516475904 1100364000 11.841 96 22 43 0 1131440080 26316512 4759568
07:26:11 56969 8192000000 9397028608 9781092352 487262928 5.614 43 7 14 0 546300144 57568336 1468880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-06-2021 07:28:43 to 08-06-2021 07:28:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-06-2021 07:28:59 to 08-06-2021 07:29:01.

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          224032
MON$OLDEST_ACTIVE               225741
MON$OLDEST_SNAPSHOT             225741
MON$NEXT_TRANSACTION            225741
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                       2071264
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2508 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2508 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2508 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2508 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 ---
:282: 
:283: mscs-test-161.budzdorov.ru    Tue Jun  8 07:27:26 2021
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:285: 
--- end of diff output ---

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

08.06.21 07:29:02. 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-06-08 07:29:04 No severe PSQL-related problems occured
08.06.21 07:29:06. End of report.