Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2021 22: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 15874796 kB
MemAvailable 30674388 kB
Buffers 0 kB
Cached 15835728 kB
SwapCached 3768 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-15 22:37:53
DTS_END                         2021-10-15 22:37:53.3730
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(57:17)
IP                              ::1/59638
TRN_ID                          242311
ATT_ID                          697
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: 9070.78 25824 1088494
customer order: creation 165.99 387 19919
customer order: refuse 14.95 361 1794
order to supplier: creation 45.38 1257 5445
order to supplier: removal 3.38 89520 406
invoice (draft): creation 44.38 3515 5325
invoice (draft): removal 50.33 789 6040
invoice accept: apply 41.17 18296 4940
invoice accept: cancel 45.58 828 5470
customer reserve: creation 3862.87 177 463544
customer reserve: removal 4508.53 52 541024
realization accept: apply 80.56 186 9667
realization accept: cancel 79.28 116 9514
payment from customer: creation 58.22 119 6986
payment from customer: removal 5.75 78 690
payment to supplier: creation 48.24 122 5789
payment to supplier: removal 3.43 59 411
service: total inventory turnovers 4.94 1242 593
service: total monetary turnovers 6.92 47 830
service: aggregate perf. data 0.86 18700 103
service: refresh index statistics 0.03 380638 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2021 22:39:19 to 15-10-2021 22:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2147
TEST_TIME 2 4319
TEST_TIME 3 3834
TEST_TIME 4 4661
TEST_TIME 5 4531
TEST_TIME 6 4348
TEST_TIME 7 4438
TEST_TIME 8 4495
TEST_TIME 9 4821
TEST_TIME 10 5075
TEST_TIME 11 5576
TEST_TIME 12 5759
TEST_TIME 13 5741
TEST_TIME 14 6320
TEST_TIME 15 6753
TEST_TIME 16 6859
TEST_TIME 17 7472
TEST_TIME 18 7877
TEST_TIME 19 8113
TEST_TIME 20 8330
TEST_TIME 21 8894
TEST_TIME 22 8951
TEST_TIME 23 9105
TEST_TIME 24 10060
TEST_TIME 25 10046
TEST_TIME 26 10030
TEST_TIME 27 9843
TEST_TIME 28 9813
TEST_TIME 29 9917
TEST_TIME 30 10056
TEST_TIME 31 9829
TEST_TIME 32 9704
TEST_TIME 33 9731
TEST_TIME 34 9959
TEST_TIME 35 9736
TEST_TIME 36 9545
TEST_TIME 37 9411
TEST_TIME 38 9423
TEST_TIME 39 9299
TEST_TIME 40 9161
TEST_TIME 41 9174
TEST_TIME 42 9131
TEST_TIME 43 9126
TEST_TIME 44 9117
TEST_TIME 45 8979
TEST_TIME 46 8903
TEST_TIME 47 8919
TEST_TIME 48 8864
TEST_TIME 49 8729
TEST_TIME 50 8687
TEST_TIME 51 8648
TEST_TIME 52 8588
TEST_TIME 53 8621
TEST_TIME 54 8721
TEST_TIME 55 8801
TEST_TIME 56 8730
TEST_TIME 57 8728
TEST_TIME 58 8881
TEST_TIME 59 8913
TEST_TIME 60 9056
TEST_TIME 61 9115
TEST_TIME 62 9143
TEST_TIME 63 9226
TEST_TIME 64 9166
TEST_TIME 65 9082
TEST_TIME 66 9008
TEST_TIME 67 8933
TEST_TIME 68 9004
TEST_TIME 69 9019
TEST_TIME 70 8985
TEST_TIME 71 8912
TEST_TIME 72 8925
TEST_TIME 73 8911
TEST_TIME 74 8891
TEST_TIME 75 8926
TEST_TIME 76 8958
TEST_TIME 77 8933
TEST_TIME 78 8911
TEST_TIME 79 9050
TEST_TIME 80 9049
TEST_TIME 81 9101
TEST_TIME 82 9108
TEST_TIME 83 9073
TEST_TIME 84 9145
TEST_TIME 85 9192
TEST_TIME 86 9186
TEST_TIME 87 9132
TEST_TIME 88 9093
TEST_TIME 89 9089
TEST_TIME 90 9016
TEST_TIME 91 8963
TEST_TIME 92 8943
TEST_TIME 93 8976
TEST_TIME 94 8967
TEST_TIME 95 9013
TEST_TIME 96 9056
TEST_TIME 97 9080
TEST_TIME 98 9081
TEST_TIME 99 9148
TEST_TIME 100 9145
TEST_TIME 101 9223
TEST_TIME 102 9242
TEST_TIME 103 9277
TEST_TIME 104 9296
TEST_TIME 105 9354
TEST_TIME 106 9317
TEST_TIME 107 9269
TEST_TIME 108 9249
TEST_TIME 109 9214
TEST_TIME 110 9197
TEST_TIME 111 9185
TEST_TIME 112 9159
TEST_TIME 113 9170
TEST_TIME 114 9161
TEST_TIME 115 9136
TEST_TIME 116 9139
TEST_TIME 117 9090
TEST_TIME 118 9121
TEST_TIME 119 9104
TEST_TIME 120 9077

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2021 22:39:22 to 15-10-2021 22:39:26.

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 1515406 1515406 0 0.00 0 23745 6 0 0
doc_list_biud 1515404 1515404 0 0.00 0 39208 24 0 0
sp_add_invoice_to_stock 5066 4940 126 2.48 46 184633 18297 0 126
sp_cancel_adding_invoice 5470 5470 0 0.00 4 79735 829 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 13455 362 0 0
sp_cancel_customer_reserve 541024 541024 0 0.00 1 31003 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 3727 78 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 2242 60 0 0
sp_cancel_supplier_invoice 6051 6040 11 0.18 2 39218 789 0 11
sp_cancel_supplier_order 406 406 0 0.00 6 979981 89521 0 0
sp_cancel_write_off 9514 9514 0 0.00 1 17430 116 0 0
sp_client_order 19919 19919 0 0.00 4 36316 388 0 0
sp_customer_reserve 463544 463544 0 0.00 0 64405 178 0 0
sp_fill_shopping_cart 30680 30680 0 0.00 1 20771 42 0 0
sp_fill_shopping_cart_clo_res 463532 463532 0 0.00 0 19119 16 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 24895 353 0 0
sp_kill_cost_storno 16085 16085 0 0.00 0 13324 54 0 0
sp_kill_qstorno_ret_qs2qd 1098008 1098008 0 0.00 0 39196 29 0 0
sp_kill_qty_storno 578842 578842 0 0.00 0 39205 63 0 0
sp_lock_selected_doc 20311 20311 0 0.00 0 9177 4 0 0
sp_make_cost_storno 27370 27370 0 0.00 0 23737 55 0 0
sp_multiply_rows_for_pdistr 27369 27369 0 0.00 0 7849 27 0 0
sp_multiply_rows_for_qdistr 19919 19919 0 0.00 0 36303 185 0 0
sp_pay_from_customer 6986 6986 0 0.00 4 23020 119 0 0
sp_pay_to_supplier 5789 5789 0 0.00 4 15440 122 0 0
sp_payment_common 12775 12775 0 0.00 2 11857 31 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 13445 338 0 0
sp_qd_handle_on_invoice_upd_sts 10398 10398 0 0.00 0 25506 321 0 0
sp_qd_handle_on_reserve_upd_sts 9666 9666 0 0.00 0 32552 70 0 0
sp_reserve_write_off 9667 9667 0 0.00 6 32601 186 0 0
sp_supplier_invoice 5325 5325 0 0.00 7 91026 3516 0 0
sp_supplier_order 5445 5445 0 0.00 4 23254 1257 0 0
srv_aggregate_perf_data 103 103 0 0.00 2574 363285 18701 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2438 142427 48381 0 0
srv_find_qd_qs_mism 1040191 1040191 0 0.00 0 21982 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 39 107121 1243 0 0
srv_make_money_saldo 830 830 0 0.00 0 9625 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 132380 820511 380639 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4940 4940 0 0.00 0 12926 192 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 8590 122 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 4172 20 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 562 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 334 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 931 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 7062 36 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 22093 1619 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 922 6 0 0
t$perf-norm:sp_client_order 19919 19919 0 0.00 0 10624 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 6200 21 0 0
t$perf-norm:sp_pay_from_customer 6986 6986 0 0.00 0 3301 7 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 4999 9 0 0
t$perf-norm:sp_reserve_write_off 9668 9668 0 0.00 0 7841 11 0 0
t$perf-norm:sp_supplier_invoice 5325 5325 0 0.00 0 9267 26 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 6147 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 253 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 314 6 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 4352 37 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1510 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10106 9854 252 2.49 0 6427 20 0 252
v_all_customers 36422 36422 0 0.00 0 15 1 0 0
v_all_suppliers 29726 29726 0 0.00 0 18 1 0 0
v_all_wares 39838 39838 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 439 6 0 0
v_cancel_client_order 3588 3588 0 0.00 0 1807 4 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 3721 12 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 3649 8 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 327 3 0 0
v_cancel_supplier_invoice 800 778 22 2.75 0 2481 17 0 22
v_cancel_supplier_order 812 812 0 0.00 0 2683 11 0 0
v_cancel_write_off 1994 1994 0 0.00 0 3794 20 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 2949 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 1532 12 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 7366 5 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 2512 4 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 4644 7 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 11043 12 0 0
v_min_id_clo_res 990 990 0 0.00 0 3750 12 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 6114 5 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 11853 17 0 0
v_min_non_paid_realizn 6986 6986 0 0.00 0 5955 25 0 0
v_our_firm 9812 9812 0 0.00 0 16 1 0 0
v_reserve_write_off 19332 19332 0 0.00 0 7450 7 0 0
x_lock_depdocs_on_canc_invoice 11121 11121 0 0.00 0 19326 117 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 14796 377 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 2 23249 1173 0 0
x_make_qstorno_1200_2000 5325 5325 0 0.00 4 90753 3363 0 0
x_make_qstorno_2100_3300 463544 463544 0 0.00 0 64404 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2021 22:39:27 to 15-10-2021 22:39:31.

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
20:07:57 4 8192000000 8800739616 8812593152 6897328 0.084 2 1 2 0 7037664 66384 73952
20:08:57 26625 8192000000 9916629648 10232393728 992012608 10.801 100 84 168 1 1020866304 6561024 22292672
20:10:29 29215 8192000000 9967696688 10294308864 1044507056 11.308 100 88 176 2 1075836000 8997200 22331744
20:12:02 18883 8192000000 9993142208 10332372992 1078949008 11.637 100 86 172 0 1110968688 10789920 21229760
20:13:26 10995 8192000000 10050397808 10401034240 1134372656 12.163 100 79 155 1 1163693728 10723120 18597952
20:14:39 10379 8192000000 10086628000 10449444864 1175943968 12.552 100 75 150 1 1204235776 10949440 17342368
20:15:52 4740 8192000000 10141259536 10512793600 1228760320 13.043 100 84 168 0 1259892368 12478640 18653408
20:16:59 6207 8192000000 10152692704 10534182912 1246712448 13.208 100 70 138 0 1274506496 12718768 15075280
20:18:07 2523 8192000000 10202432832 10584842240 1278855184 13.503 100 93 188 1 1312825808 13016032 20954592
20:19:12 3876 8192000000 10213623824 10599120896 1297133120 13.669 100 94 188 1 1331256416 12812448 21310848
20:20:17 37841 8192000000 10218874464 10617241600 1310477280 13.790 100 60 122 0 1330502992 5447360 14578352
20:21:55 5163 8192000000 10208906272 10623987712 1315643440 13.837 100 25 50 1 1327695984 6684304 5368240
20:23:03 7216 8192000000 10216582096 10628644864 1324274720 13.915 100 37 74 0 1337904432 5361920 8267792
20:24:13 9943 8192000000 10219770064 10641838080 1331283744 13.979 100 14 28 1 1340296976 5719424 3293808
20:25:24 54107 8192000000 10223262496 10648784896 1335418464 14.016 100 33 66 3 1348467072 5612416 7436192
20:27:20 5041 8192000000 10221463040 10641641472 1322157776 13.896 99 26 52 0 1334037568 5670368 6209424
20:28:25 7835 8192000000 10166643712 10572513280 1224126544 13.000 95 29 58 0 1236537488 5232240 7178704
20:29:37 16477 8192000000 10150157552 10550484992 1219894448 12.961 96 31 60 3 1233241888 5412800 7934640
20:30:58 20250 8192000000 10131958608 10531307520 1204292816 12.816 96 24 48 0 1215708464 6441216 4974432
20:32:19 4977 8192000000 10165861856 10571530240 1271280656 13.433 100 34 68 1 1285744560 6048208 8415696
20:33:24 8052 8192000000 10175330944 10581151744 1279199056 13.506 100 31 61 1 1291612080 5090272 7322752
20:34:36 15105 8192000000 10181257616 10591002624 1282457376 13.535 100 32 62 4 1301705904 10266288 8982240
20:35:51 22011 8192000000 10184458448 10596261888 1292261392 13.625 100 24 48 0 1303779168 5927840 5589936
20:37:14 7975 8192000000 10205910256 10619564032 1313570720 13.818 100 16 32 0 1322021056 5044096 3406240
20:38:27 18290 8192000000 10237837488 10645782528 1330575856 13.972 100 44 88 1 1348976896 8962272 9438768
20:39:53 90382 8192000000 10263336944 10682568704 1352618512 14.171 100 49 99 1 1381174288 17091824 11463952
20:42:31 25532 8192000000 10275065104 10698403840 1355713088 14.199 99 30 55 4 1374906272 12994720 6198464
20:44:01 7691 8192000000 10294955680 10714017792 1369209968 14.320 99 49 98 0 1402919520 21998864 11710688
20:45:14 14453 8192000000 10303785584 10725154816 1383962240 14.452 100 52 103 0 1417739072 21122688 12654144
20:46:33 11519 8192000000 10309773328 10730311680 1379529648 14.412 99 52 104 1 1411908512 19974496 12404368
20:47:48 4128 8192000000 10338421024 10751193088 1399260224 14.588 100 79 157 1 1447532304 30052640 18219440
20:48:57 5191 8192000000 10324226704 10744905728 1398553776 14.582 100 53 104 1 1435198560 24016000 12628784
20:50:05 6629 8192000000 10334179280 10753032192 1387282048 14.482 99 60 118 0 1434840960 31854192 15704720
20:51:14 6374 8192000000 10323919040 10733957120 1368172912 14.311 99 76 150 3 1424715056 37370752 19171392
20:52:25 6090 8192000000 10292895584 10701045760 1336706944 14.028 99 64 128 1 1384128608 31542272 15879392
20:53:35 2437 8192000000 10318722096 10717622272 1358440720 14.223 100 86 171 3 1418547648 38758320 21348608
20:54:43 5076 8192000000 10315303152 10724540416 1364144224 14.275 100 80 160 1 1424096816 39745584 20207008
20:55:57 10785 8192000000 10290119360 10704982016 1316060240 13.841 97 54 108 2 1365310064 35448016 13801808
20:57:13 22377 8192000000 10296417984 10721058816 1351989568 14.165 100 31 62 1 1397829504 36996816 8843120
20:58:43 21438 8192000000 10275255952 10720665600 1342465264 14.080 99 18 36 1 1379202368 31604752 5132352
21:00:17 11253 8192000000 10292157904 10715189248 1343821680 14.092 100 32 64 2 1386541168 34747616 7971872
21:01:35 13797 8192000000 10248971472 10693271552 1320323760 13.880 99 20 40 1 1356971808 31529696 5118352
21:02:52 29977 8192000000 10264640416 10696007680 1342829056 14.083 99 37 73 1 1366468528 15318576 8320896
21:04:25 37803 8192000000 10256891104 10691796992 1316928432 13.849 97 20 40 0 1337352640 15267264 5156944
21:06:13 31350 8192000000 10276337744 10717696000 1340831968 14.065 99 16 32 2 1376470464 31309408 4329088
21:07:47 41927 8192000000 10269582032 10704502784 1363628192 14.270 100 22 42 1 1383802720 15059744 5114784
21:09:29 40304 8192000000 10281677680 10715209728 1365702288 14.289 100 24 50 2 1386664880 15315712 5646880
21:11:20 75700 8192000000 10275439120 10727124992 1363637504 14.270 100 19 38 0 1388229904 19596160 4996240
21:13:49 22348 8192000000 10280833728 10718470144 1376596320 14.386 100 32 64 1 1392093792 7954176 7543296
21:15:14 59270 8192000000 10288926080 10744799232 1368705632 14.315 99 21 42 0 1385395776 11947840 4742304
21:17:13 40631 8192000000 10297610272 10751725568 1390125552 14.507 100 12 24 0 1411404704 18268960 3010192
21:18:55 62368 8192000000 10313995872 10764357632 1393880560 14.540 100 17 34 1 1421419856 23591824 3947472
21:21:07 88563 8192000000 10299455296 10757484544 1395040480 14.551 100 17 34 0 1408753184 9834704 3878000
21:23:47 43557 8192000000 10294628304 10754916352 1393530384 14.537 100 16 32 1 1410298448 13094144 3673920
21:25:38 81504 8192000000 10286673056 10751737856 1388522416 14.493 99 19 38 1 1399291456 6622032 4147008
21:28:03 40620 8192000000 10282746416 10751631360 1391831504 14.522 100 11 22 1 1402042848 7999728 2211616
21:29:45 46591 8192000000 10315837280 10776281088 1414351392 14.723 100 25 50 1 1432245440 11895808 5998240
21:31:45 41962 8192000000 10305740336 10764066816 1378806864 14.406 98 12 23 1 1397087104 15806640 2473600
21:33:27 44208 8192000000 10307780992 10763911168 1383865696 14.451 98 27 54 2 1404915792 14734240 6315856
21:35:11 57573 8192000000 10297949568 10756554752 1392468080 14.528 100 22 44 0 1410914208 13386400 5059728
21:37:15 58486 8192000000 10295622112 10734870528 1351711440 14.163 97 31 62 0 1368741088 10600048 6429600
21:39:14 47754 8192000000 10276208272 10717036544 1295597328 13.655 94 20 40 0 1311188848 11582464 4009056
21:41:06 37131 8192000000 10255433776 10685739008 1313505424 13.818 96 30 60 0 1327857168 7627088 6724656
21:42:45 65800 8192000000 10269704560 10722410496 1358164736 14.221 99 21 42 1 1377468304 14337760 4965808
21:45:42 61047 8192000000 10260054320 10727002112 1356154480 14.203 99 17 34 1 1373987280 13853680 3979120
21:48:51 80470 8192000000 10238072656 10693885952 1295978544 13.659 94 7 14 0 1305367328 8079760 1309024
21:51:12 52161 8192000000 10251252560 10690052096 1325325808 13.925 97 24 48 0 1340929584 9570448 6033328
21:53:06 60640 8192000000 10254396048 10697064448 1307365872 13.762 96 19 36 1 1322616592 11248560 4002160
21:55:10 62125 8192000000 10266545808 10713255936 1336832560 14.029 98 22 44 0 1355946672 14143600 4970512
21:57:13 60484 8192000000 10284714352 10726055936 1347799488 14.128 98 29 56 1 1364202288 9934304 6468496
21:59:17 65423 8192000000 10309069344 10745757696 1380959776 14.425 100 26 52 1 1398044560 11548960 5535824
22:01:25 71414 8192000000 10295483376 10765467648 1379210112 14.409 100 8 16 0 1403945472 22784064 1951296
22:03:37 142427 8192000000 10282847376 10734723072 1370591440 14.332 99 38 76 0 1390330144 10966672 8772032
22:07:01 60266 8192000000 10293918208 10749833216 1389536912 14.502 100 10 20 0 1407704000 15681632 2485456
22:09:02 50941 8192000000 10297870528 10760327168 1394151648 14.543 100 9 18 0 1412424240 16322688 1949904
22:10:56 93391 8192000000 10313796992 10776092672 1403009712 14.622 100 18 36 2 1423972720 17007472 3955536
22:13:33 78247 8192000000 10301643072 10760519680 1393559968 14.538 99 19 38 1 1409374336 11206400 4607968
22:15:56 104060 8192000000 10320591680 10780438528 1393143888 14.534 99 11 22 1 1409547680 14271936 2131856
22:18:56 126527 8192000000 10308195712 10750267392 1300331792 13.698 92 18 35 0 1312350544 8628672 3390080
22:22:03 94529 8192000000 10297806528 10748452864 1362937152 14.264 97 16 32 1 1378770640 12110464 3723024
22:24:38 46323 8192000000 10312281024 10745147392 1378660336 14.405 99 43 86 0 1398807936 10350688 9796912
22:26:36 70570 8192000000 10274572096 10722553856 1345537216 14.107 97 19 38 0 1357515072 7373824 4604032
22:28:49 60840 8192000000 10259190912 10709467136 1297004288 13.668 94 20 40 0 1313011952 11863088 4144576
22:30:51 48733 8192000000 10243068672 10695487488 1311052336 13.796 97 15 30 1 1329993504 15652016 3289152
22:32:40 66991 8192000000 10246158576 10693267456 1321368832 13.889 98 15 29 0 1338251664 13784080 3098752
22:35:10 58762 8192000000 10245861728 10708144128 1340214272 14.059 99 13 26 0 1353423648 10195904 3013472
22:37:09 52637 8192000000 9229847232 9511649280 375639408 4.384 28 3 6 0 380972752 4869264 464080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2021 22:39:32 to 15-10-2021 22:39:47.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2021 22:39:48 to 15-10-2021 22:39:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242944
MON$OLDEST_ACTIVE               242945
MON$OLDEST_SNAPSHOT             242945
MON$NEXT_TRANSACTION            242945
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2038176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:392: 
:393: mscs-test-161.budzdorov.ru    Fri Oct 15 22:38:03 2021
:394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:395: 
:396: 
:397: mscs-test-161.budzdorov.ru    Fri Oct 15 22:38:19 2021
:398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:399: 
--- end of diff output ---

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

15.10.21 22:39:51. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-15 22:39:54 No severe PSQL-related problems occured
15.10.21 22:39:55. End of report.