Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.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 15548640 kB
MemAvailable 30580788 kB
Buffers 0 kB
Cached 16100416 kB
SwapCached 3704 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-22 22:37:55
DTS_END                         2021-10-22 22:37:55.8260
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/55872
TRN_ID                          231084
ATT_ID                          656
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: 9416.28 26645 1129953
customer order: creation 161.46 375 19375
customer order: refuse 14.45 349 1734
order to supplier: creation 44.18 1277 5302
order to supplier: removal 3.54 114362 425
invoice (draft): creation 42.78 3615 5134
invoice (draft): removal 56.15 818 6738
invoice accept: apply 40.05 18062 4806
invoice accept: cancel 51.13 722 6136
customer reserve: creation 3696.58 178 443589
customer reserve: removal 5012.98 63 601557
realization accept: apply 78.74 187 9449
realization accept: cancel 90.31 109 10837
payment from customer: creation 56.95 112 6834
payment from customer: removal 5.38 112 645
payment to supplier: creation 47.22 129 5666
payment to supplier: removal 3.18 73 381
service: total inventory turnovers 4.12 1650 494
service: total monetary turnovers 6.28 30 753
service: aggregate perf. data 0.78 23294 94
service: refresh index statistics 0.03 367389 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2021 22:38:43 to 22-10-2021 22:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2889
TEST_TIME 2 2958
TEST_TIME 3 3668
TEST_TIME 4 4220
TEST_TIME 5 4685
TEST_TIME 6 4357
TEST_TIME 7 4311
TEST_TIME 8 4414
TEST_TIME 9 4499
TEST_TIME 10 5021
TEST_TIME 11 5245
TEST_TIME 12 6022
TEST_TIME 13 6219
TEST_TIME 14 6588
TEST_TIME 15 6792
TEST_TIME 16 7096
TEST_TIME 17 7282
TEST_TIME 18 7450
TEST_TIME 19 7621
TEST_TIME 20 8391
TEST_TIME 21 8401
TEST_TIME 22 8338
TEST_TIME 23 8460
TEST_TIME 24 8607
TEST_TIME 25 9250
TEST_TIME 26 9777
TEST_TIME 27 9945
TEST_TIME 28 10332
TEST_TIME 29 11401
TEST_TIME 30 11243
TEST_TIME 31 11160
TEST_TIME 32 11114
TEST_TIME 33 11131
TEST_TIME 34 11347
TEST_TIME 35 11194
TEST_TIME 36 11275
TEST_TIME 37 10994
TEST_TIME 38 10841
TEST_TIME 39 10766
TEST_TIME 40 10613
TEST_TIME 41 10466
TEST_TIME 42 10309
TEST_TIME 43 10162
TEST_TIME 44 10078
TEST_TIME 45 10204
TEST_TIME 46 10070
TEST_TIME 47 9975
TEST_TIME 48 9936
TEST_TIME 49 10028
TEST_TIME 50 9926
TEST_TIME 51 9832
TEST_TIME 52 9764
TEST_TIME 53 9773
TEST_TIME 54 9777
TEST_TIME 55 9668
TEST_TIME 56 9614
TEST_TIME 57 9577
TEST_TIME 58 9633
TEST_TIME 59 9600
TEST_TIME 60 9516
TEST_TIME 61 9432
TEST_TIME 62 9357
TEST_TIME 63 9230
TEST_TIME 64 9236
TEST_TIME 65 9218
TEST_TIME 66 9278
TEST_TIME 67 9226
TEST_TIME 68 9304
TEST_TIME 69 9372
TEST_TIME 70 9322
TEST_TIME 71 9421
TEST_TIME 72 9406
TEST_TIME 73 9530
TEST_TIME 74 9576
TEST_TIME 75 9511
TEST_TIME 76 9533
TEST_TIME 77 9588
TEST_TIME 78 9641
TEST_TIME 79 9625
TEST_TIME 80 9569
TEST_TIME 81 9529
TEST_TIME 82 9484
TEST_TIME 83 9502
TEST_TIME 84 9437
TEST_TIME 85 9402
TEST_TIME 86 9364
TEST_TIME 87 9319
TEST_TIME 88 9280
TEST_TIME 89 9258
TEST_TIME 90 9386
TEST_TIME 91 9385
TEST_TIME 92 9353
TEST_TIME 93 9338
TEST_TIME 94 9375
TEST_TIME 95 9486
TEST_TIME 96 9452
TEST_TIME 97 9412
TEST_TIME 98 9478
TEST_TIME 99 9514
TEST_TIME 100 9468
TEST_TIME 101 9508
TEST_TIME 102 9542
TEST_TIME 103 9477
TEST_TIME 104 9433
TEST_TIME 105 9438
TEST_TIME 106 9395
TEST_TIME 107 9403
TEST_TIME 108 9349
TEST_TIME 109 9336
TEST_TIME 110 9299
TEST_TIME 111 9311
TEST_TIME 112 9285
TEST_TIME 113 9290
TEST_TIME 114 9313
TEST_TIME 115 9329
TEST_TIME 116 9318
TEST_TIME 117 9350
TEST_TIME 118 9373
TEST_TIME 119 9375
TEST_TIME 120 9418

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 22:38:47 to 22-10-2021 22:38:50.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1541552 1541552 0 0.00 0 14867 6 0 0
doc_list_biud 1541552 1541552 0 0.00 0 20653 31 0 0
sp_add_invoice_to_stock 4908 4806 102 2.07 29 184325 18062 0 102
sp_cancel_adding_invoice 6136 6136 0 0.00 5 45545 723 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 8691 350 0 0
sp_cancel_customer_reserve 601557 601557 0 0.00 1 20655 63 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 2 13336 113 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 5942 73 0 0
sp_cancel_supplier_invoice 6743 6738 5 0.07 2 11268 818 0 5
sp_cancel_supplier_order 425 425 0 0.00 5 1070526 114362 0 0
sp_cancel_write_off 10837 10837 0 0.00 1 8586 110 0 0
sp_client_order 19375 19375 0 0.00 3 26424 376 0 0
sp_customer_reserve 443589 443589 0 0.00 0 43199 178 0 0
sp_fill_shopping_cart 29792 29792 0 0.00 1 23458 44 0 0
sp_fill_shopping_cart_clo_res 443576 443576 0 0.00 0 14344 14 0 0
sp_get_clo_for_invoice 4793 4793 0 0.00 0 16560 335 0 0
sp_kill_cost_storno 17999 17999 0 0.00 0 8342 58 0 0
sp_kill_qstorno_ret_qs2qd 1221114 1221114 0 0.00 0 20645 34 0 0
sp_kill_qty_storno 641669 641669 0 0.00 0 20653 73 0 0
sp_lock_selected_doc 19883 19883 0 0.00 0 10453 6 0 0
sp_make_cost_storno 26742 26742 0 0.00 0 14683 54 0 0
sp_multiply_rows_for_pdistr 26742 26742 0 0.00 0 8715 29 0 0
sp_multiply_rows_for_qdistr 19375 19375 0 0.00 0 12951 169 0 0
sp_pay_from_customer 6834 6834 0 0.00 3 26044 113 0 0
sp_pay_to_supplier 5666 5666 0 0.00 5 14808 129 0 0
sp_payment_common 12500 12500 0 0.00 1 26033 32 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 8660 332 0 0
sp_qd_handle_on_invoice_upd_sts 10929 10929 0 0.00 0 17940 348 0 0
sp_qd_handle_on_reserve_upd_sts 9449 9449 0 0.00 0 14777 62 0 0
sp_reserve_write_off 9449 9449 0 0.00 7 18306 187 0 0
sp_supplier_invoice 5134 5134 0 0.00 7 86416 3615 0 0
sp_supplier_order 5302 5302 0 0.00 5 40477 1278 0 0
srv_aggregate_perf_data 94 94 0 0.00 2281 467882 23295 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1307 160495 43976 0 0
srv_find_qd_qs_mism 1084855 1084855 0 0.00 0 12021 5 0 0
srv_make_invnt_saldo 494 494 0 0.00 19 182695 1650 0 0
srv_make_money_saldo 753 753 0 0.00 0 1524 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 187218 520784 367390 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4806 4806 0 0.00 0 24665 223 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 3256 88 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 2548 15 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 2248 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 2356 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 1836 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 3914 28 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 68327 1812 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 861 7 0 0
t$perf-norm:sp_client_order 19376 19376 0 0.00 0 14190 20 0 0
t$perf-norm:sp_customer_reserve 906 906 0 0.00 0 647 6 0 0
t$perf-norm:sp_pay_from_customer 6834 6834 0 0.00 0 16384 12 0 0
t$perf-norm:sp_pay_to_supplier 5667 5667 0 0.00 0 16378 12 0 0
t$perf-norm:sp_reserve_write_off 9450 9450 0 0.00 0 15451 15 0 0
t$perf-norm:sp_supplier_invoice 5134 5134 0 0.00 0 10136 20 0 0
t$perf-norm:sp_supplier_order 5302 5302 0 0.00 0 15160 23 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 320 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 220 5 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 4365 33 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1678 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 9790 9586 204 2.08 0 8591 23 0 204
v_all_customers 35570 35570 0 0.00 0 36 1 0 0
v_all_suppliers 29280 29280 0 0.00 0 50 1 0 0
v_all_wares 38750 38750 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 75 5 0 0
v_cancel_client_order 3468 3468 0 0.00 0 1054 3 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 4989 12 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 2288 6 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 271 3 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 3610 27 0 10
v_cancel_supplier_order 850 850 0 0.00 0 2829 8 0 0
v_cancel_write_off 2018 2018 0 0.00 0 8451 17 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 614 1 0 0
v_max_id_clo_res 906 906 0 0.00 0 2380 21 0 0
v_max_id_ord_sup 5117 5117 0 0.00 0 5449 4 0 0
v_max_non_paid_invoice 1443 1443 0 0.00 0 992 4 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 3161 5 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 1953 5 0 0
v_min_id_clo_res 906 906 0 0.00 0 1465 6 0 0
v_min_id_ord_sup 5117 5117 0 0.00 0 6027 9 0 0
v_min_non_paid_invoice 5666 5666 0 0.00 0 8404 22 0 0
v_min_non_paid_realizn 6834 6834 0 0.00 0 26029 27 0 0
v_our_firm 9956 9956 0 0.00 0 28 1 0 0
v_reserve_write_off 18898 18898 0 0.00 0 2606 5 0 0
x_lock_depdocs_on_canc_invoice 12476 12476 0 0.00 0 15208 123 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 4935 354 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 1 40468 1195 0 0
x_make_qstorno_1200_2000 5134 5134 0 0.00 3 86398 3451 0 0
x_make_qstorno_2100_3300 443589 443589 0 0.00 0 40487 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2021 22:38:51 to 22-10-2021 22:38:55.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 30362 8192000000 9903714896 10214850560 979288880 10.677 100 86 172 0 1007810016 5702384 22818752
20:10:40 23758 8192000000 9950986096 10274017280 1030996192 11.178 100 86 172 0 1061126880 8086656 22044032
20:12:08 14539 8192000000 9980678080 10317049856 1063462352 11.490 100 82 164 0 1097483696 13424160 20597184
20:13:25 9990 8192000000 10034578720 10384687104 1123418016 12.059 100 83 166 1 1154431104 11358592 19654496
20:14:39 9073 8192000000 10092621536 10445942784 1166859504 12.467 100 91 182 0 1201719168 13841120 21018544
20:15:50 7204 8192000000 10112928880 10480881664 1205718720 12.829 100 81 161 1 1236925392 12698000 18508672
20:17:01 3779 8192000000 10159602592 10534785024 1243149696 13.175 100 84 167 0 1274591968 13356672 18085600
20:18:06 2387 8192000000 10188201376 10570858496 1275161936 13.469 100 89 178 0 1309124800 13770640 20192224
20:19:09 1313 8192000000 10207478768 10589528064 1291247792 13.616 100 85 171 2 1323132624 12497344 19387488
20:20:13 1516 8192000000 10219090256 10611916800 1306456240 13.754 100 64 128 0 1332697584 12554624 13686720
20:21:16 14488 8192000000 10214946432 10613293056 1308831248 13.775 100 66 131 3 1331064592 6098288 16135056
20:22:31 5928 8192000000 10203424272 10620239872 1319076784 13.868 100 34 68 0 1334801456 7659504 8065168
20:23:39 7244 8192000000 10215084528 10632511488 1329374304 13.962 100 33 66 0 1344027632 7164192 7489136
20:24:47 13978 8192000000 10220272144 10639515648 1337352672 14.034 100 28 56 0 1349823680 6063424 6407584
20:26:04 17046 8192000000 10252924032 10652966912 1341859408 14.074 100 69 139 0 1365322288 6155072 17307808
20:27:21 7363 8192000000 10228947616 10651164672 1345739104 14.109 100 37 74 0 1360452192 6153664 8559424
20:28:29 9432 8192000000 10225877696 10638639104 1318937344 13.867 99 26 53 0 1330564128 5432240 6194544
20:29:41 57176 8192000000 10184479296 10570346496 1253232608 13.268 97 43 86 1 1270536096 6394704 10908784
20:31:39 9019 8192000000 10133461472 10523639808 1188038352 12.665 95 24 48 1 1200200400 6102000 6060048
20:32:49 5705 8192000000 10149065776 10539716608 1245661728 13.198 100 24 48 2 1256566800 5373248 5531824
20:33:55 32935 8192000000 10166851776 10573570048 1273327408 13.452 100 39 78 1 1289806832 7720960 8758464
20:35:30 6707 8192000000 10187176720 10587320320 1284843984 13.557 100 33 65 1 1298220496 5713888 7662624
20:36:38 6992 8192000000 10192698048 10604130304 1303477888 13.727 100 31 62 0 1316312320 5827680 7006752
20:37:50 50988 8192000000 10226241296 10633781248 1319777088 13.875 100 42 84 1 1335543968 5797888 9968992
20:39:50 41206 8192000000 10263169360 10676723712 1351154464 14.158 100 41 82 0 1373473200 13509456 8809280
20:41:34 16709 8192000000 10298670896 10713382912 1376153984 14.382 100 60 119 2 1410170448 19936752 14079712
20:42:55 38101 8192000000 10308364480 10732625920 1395004640 14.550 100 48 96 1 1427618160 21168096 11445424
20:44:38 15165 8192000000 10331230080 10749255680 1400372496 14.598 100 50 100 0 1439676272 27831184 11472592
20:46:08 7166 8192000000 10330708640 10748907520 1404014160 14.631 100 58 114 0 1441402576 24606912 12781504
20:47:22 2941 8192000000 10361452976 10776227840 1418031360 14.755 100 85 168 1 1473435168 35227248 20176560
20:48:31 5530 8192000000 10353348784 10776788992 1414815488 14.727 100 71 141 0 1468155840 35618224 17722128
20:49:41 2115 8192000000 10367434464 10785157120 1417571408 14.751 100 75 148 1 1476724768 41545296 17608064
20:50:47 1757 8192000000 10381122448 10796408832 1425474032 14.821 100 91 184 1 1488724064 40676544 22573488
20:51:52 7094 8192000000 10361878992 10782613504 1412653696 14.708 100 61 122 1 1471385360 43207776 15523888
20:53:02 1304 8192000000 10369346448 10778664960 1407689200 14.663 100 79 159 4 1471779568 43103488 20986880
20:54:08 4476 8192000000 10349166032 10761359360 1390103904 14.507 100 66 131 2 1451858640 44216592 17538144
20:55:22 4548 8192000000 10343217264 10748915712 1377397504 14.393 99 83 167 1 1446606864 47363552 21845808
20:56:34 2849 8192000000 10362425952 10763718656 1380648384 14.422 99 88 173 1 1457266112 53484640 23133088
20:57:41 5349 8192000000 10356572368 10782490624 1390563152 14.511 99 63 126 1 1464007824 56114544 17330128
20:58:59 13521 8192000000 10344428560 10766831616 1379936320 14.416 99 42 84 2 1435970528 44087808 11946400
21:00:22 15509 8192000000 10347770208 10781151232 1384963184 14.461 99 35 70 2 1446667456 51497424 10206848
21:01:43 11307 8192000000 10342504608 10774282240 1375190448 14.374 99 35 70 4 1443950608 58311840 10448320
21:03:04 18453 8192000000 10324943232 10764664832 1378516304 14.403 100 24 48 1 1431604032 45719216 7368512
21:04:25 30278 8192000000 10317125920 10759548928 1365773136 14.289 99 28 56 0 1421120144 47651216 7695792
21:05:57 21219 8192000000 10278128768 10730438656 1315188672 13.833 97 14 28 2 1354521280 35567264 3765344
21:07:20 78626 8192000000 10290893248 10722136064 1329993712 13.967 97 22 42 1 1351703744 17152336 4557696
21:09:55 45559 8192000000 10306928400 10763616256 1385804128 14.468 100 8 16 0 1403183808 15754992 1624688
21:11:41 160495 8192000000 10285036160 10743943168 1330554784 13.972 96 10 20 0 1360738800 28267792 1916224
21:15:26 2260 8192000000 10289509840 10723213312 1364500656 14.278 99 69 133 3 1385797984 5488112 15809216
21:16:30 27783 8192000000 10284926272 10748284928 1383366144 14.447 100 9 18 0 1397259952 12085712 1808096
21:17:58 79602 8192000000 10279164160 10734768128 1356511856 14.206 98 8 16 0 1369622704 11165488 1945360
21:20:18 91006 8192000000 10289692256 10748227584 1373373584 14.357 99 22 44 0 1394659136 15463248 5822304
21:23:00 69273 8192000000 10296762336 10750160896 1366417952 14.295 98 30 60 0 1381970704 9006224 6546528
21:25:36 87204 8192000000 10283701680 10750574592 1360489632 14.242 98 21 43 1 1382711504 16723072 5498800
21:28:04 88424 8192000000 10290000624 10743844864 1381166176 14.427 100 44 88 3 1404312800 12557552 10589072
21:32:06 53146 8192000000 10283705632 10741641216 1385796832 14.468 100 26 52 2 1402074048 9936816 6340400
21:33:59 53345 8192000000 10298310496 10750484480 1381003184 14.426 99 27 52 2 1398397776 11125328 6269264
21:36:07 54242 8192000000 10291962976 10756882432 1396930592 14.568 100 17 34 0 1407955920 7243248 3782080
21:38:29 49859 8192000000 10275148864 10743848960 1370974000 14.336 98 9 18 0 1380806560 7896800 1935760
21:40:21 66530 8192000000 10280739728 10750033920 1371364032 14.339 98 16 32 0 1384931392 9981824 3585536
21:42:28 61735 8192000000 10277052688 10743009280 1356781168 14.208 98 12 24 0 1369735856 10200928 2753760
21:44:30 51082 8192000000 10285719680 10754772992 1388789264 14.495 100 13 26 1 1403958592 12201200 2968128
21:46:22 52664 8192000000 10293447584 10756194304 1373636208 14.360 99 10 20 1 1392397680 16318784 2442688
21:48:32 30986 8192000000 10312264736 10772582400 1389258688 14.499 99 36 72 1 1415863920 18486432 8118800
21:50:05 76366 8192000000 10269120080 10725289984 1281438336 13.526 92 19 38 2 1304875104 19140640 4296128
21:52:22 33843 8192000000 10290429648 10717069312 1331913168 13.984 97 40 80 0 1349867136 9061200 8892768
21:53:56 54717 8192000000 10291189872 10742382592 1359634608 14.234 99 18 36 0 1374881648 11412128 3834912
21:55:52 88545 8192000000 10277636128 10732449792 1342159984 14.077 98 9 17 0 1361557568 17459920 1937664
21:58:43 34921 8192000000 10272210960 10698805248 1334307280 14.006 98 46 91 0 1352162016 7285664 10569072
22:00:19 52329 8192000000 10254450368 10716561408 1352818544 14.173 99 6 12 0 1361190672 7330240 1041888
22:02:11 55680 8192000000 10264299232 10718441472 1334884576 14.011 97 10 20 0 1346981088 9790816 2305696
22:04:19 55747 8192000000 10271488416 10724966400 1362087600 14.256 100 14 28 0 1376358704 10988432 3282672
22:06:21 38397 8192000000 10275948432 10732818432 1371310688 14.339 100 16 32 0 1391433808 15927728 4195392
22:08:00 81003 8192000000 10282586720 10734964736 1364203296 14.275 99 14 28 0 1378156448 10943216 3009936
22:10:23 75766 8192000000 10297061616 10744586240 1372239104 14.347 99 22 44 1 1392422192 15408704 4774384
22:12:40 81150 8192000000 10296155856 10740531200 1330623360 13.973 96 11 22 0 1347016592 14009952 2383280
22:15:01 50848 8192000000 10306120528 10755354624 1382056576 14.435 100 26 52 0 1403105776 15015712 6033488
22:16:52 29690 8192000000 10300333024 10747781120 1388055344 14.489 100 21 42 2 1400535696 8060704 4419648
22:18:22 44306 8192000000 10307155872 10752806912 1379794176 14.415 99 35 71 2 1400701120 12880928 8026016
22:20:09 70872 8192000000 10295977936 10746810368 1388696480 14.494 100 29 58 1 1407436000 12406368 6333152
22:22:53 37510 8192000000 10296974096 10751078400 1392015744 14.524 100 22 43 0 1409764208 13034000 4714464
22:24:31 50741 8192000000 10270428432 10730364928 1364680880 14.279 99 9 18 0 1375851600 9091536 2079184
22:26:36 67803 8192000000 10279162144 10739478528 1355229744 14.195 98 20 39 2 1369649392 10362064 4057584
22:28:44 47383 8192000000 10260839568 10719068160 1334257424 14.006 97 12 24 1 1348178336 11250944 2669968
22:30:37 40295 8192000000 10271721168 10729959424 1352322848 14.168 98 18 36 0 1366819920 10430528 4066544
22:32:22 47796 8192000000 10266692976 10717282304 1323549664 13.909 96 23 47 4 1336100144 7079104 5471376
22:34:12 67550 8192000000 10270663216 10734379008 1347982304 14.129 98 26 50 0 1364411024 10907344 5521376
22:36:20 53593 8192000000 10275681168 10734813184 1340181360 14.059 98 21 42 0 1359856784 14933984 4741440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

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

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          231032
MON$OLDEST_ACTIVE               231824
MON$OLDEST_SNAPSHOT             231824
MON$NEXT_TRANSACTION            231824
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                       2063200
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Fri Oct 22 22:37:58 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Oct 22 22:37:58 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Oct 22 22:38:00 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Oct 22 22:38:05 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
--- end of diff output ---

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

22.10.21 22:39:16. 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-22 22:39:18 No severe PSQL-related problems occured
22.10.21 22:39:19. End of report.