Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.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 15976716 kB
MemAvailable 30826796 kB
Buffers 0 kB
Cached 15701328 kB
SwapCached 4796 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-28 22:37:56
DTS_END                         2021-10-28 22:37:56.9470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37350
TRN_ID                          246385
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: 9593.07 22302 1151168
customer order: creation 183.73 312 22047
customer order: refuse 15.40 326 1848
order to supplier: creation 49.37 1166 5924
order to supplier: removal 3.57 82211 428
invoice (draft): creation 47.18 3220 5661
invoice (draft): removal 52.80 745 6336
invoice accept: apply 44.77 14724 5372
invoice accept: cancel 48.03 826 5764
customer reserve: creation 4129.22 145 495506
customer reserve: removal 4703.17 48 564380
realization accept: apply 90.10 168 10812
realization accept: cancel 86.76 100 10411
payment from customer: creation 63.33 82 7600
payment from customer: removal 6.18 76 742
payment to supplier: creation 53.08 120 6370
payment to supplier: removal 3.63 58 435
service: total inventory turnovers 4.88 1083 585
service: total monetary turnovers 6.94 43 833
service: aggregate perf. data 0.92 17303 110
service: refresh index statistics 0.03 323287 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2021 22:38:35 to 28-10-2021 22:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1588
TEST_TIME 2 2308
TEST_TIME 3 2870
TEST_TIME 4 3098
TEST_TIME 5 2825
TEST_TIME 6 3152
TEST_TIME 7 3800
TEST_TIME 8 4619
TEST_TIME 9 4927
TEST_TIME 10 5312
TEST_TIME 11 5949
TEST_TIME 12 5946
TEST_TIME 13 5903
TEST_TIME 14 6432
TEST_TIME 15 7129
TEST_TIME 16 7664
TEST_TIME 17 8056
TEST_TIME 18 8226
TEST_TIME 19 8656
TEST_TIME 20 8590
TEST_TIME 21 8500
TEST_TIME 22 9215
TEST_TIME 23 9574
TEST_TIME 24 9668
TEST_TIME 25 9796
TEST_TIME 26 9811
TEST_TIME 27 9802
TEST_TIME 28 10198
TEST_TIME 29 10082
TEST_TIME 30 10006
TEST_TIME 31 9931
TEST_TIME 32 9712
TEST_TIME 33 9636
TEST_TIME 34 9652
TEST_TIME 35 9873
TEST_TIME 36 9823
TEST_TIME 37 9905
TEST_TIME 38 9866
TEST_TIME 39 9952
TEST_TIME 40 9980
TEST_TIME 41 9909
TEST_TIME 42 9864
TEST_TIME 43 10184
TEST_TIME 44 10024
TEST_TIME 45 9863
TEST_TIME 46 9787
TEST_TIME 47 9687
TEST_TIME 48 9648
TEST_TIME 49 9697
TEST_TIME 50 9803
TEST_TIME 51 9820
TEST_TIME 52 9784
TEST_TIME 53 9702
TEST_TIME 54 9710
TEST_TIME 55 9679
TEST_TIME 56 9625
TEST_TIME 57 9523
TEST_TIME 58 9587
TEST_TIME 59 9543
TEST_TIME 60 9595
TEST_TIME 61 9585
TEST_TIME 62 9671
TEST_TIME 63 9729
TEST_TIME 64 9690
TEST_TIME 65 9736
TEST_TIME 66 9758
TEST_TIME 67 9827
TEST_TIME 68 9783
TEST_TIME 69 9945
TEST_TIME 70 9967
TEST_TIME 71 10016
TEST_TIME 72 10016
TEST_TIME 73 9985
TEST_TIME 74 9888
TEST_TIME 75 9810
TEST_TIME 76 9804
TEST_TIME 77 9760
TEST_TIME 78 9782
TEST_TIME 79 9740
TEST_TIME 80 9701
TEST_TIME 81 9643
TEST_TIME 82 9571
TEST_TIME 83 9544
TEST_TIME 84 9631
TEST_TIME 85 9595
TEST_TIME 86 9629
TEST_TIME 87 9597
TEST_TIME 88 9598
TEST_TIME 89 9589
TEST_TIME 90 9676
TEST_TIME 91 9680
TEST_TIME 92 9715
TEST_TIME 93 9700
TEST_TIME 94 9735
TEST_TIME 95 9751
TEST_TIME 96 9761
TEST_TIME 97 9783
TEST_TIME 98 9755
TEST_TIME 99 9670
TEST_TIME 100 9677
TEST_TIME 101 9648
TEST_TIME 102 9652
TEST_TIME 103 9596
TEST_TIME 104 9609
TEST_TIME 105 9555
TEST_TIME 106 9542
TEST_TIME 107 9490
TEST_TIME 108 9524
TEST_TIME 109 9521
TEST_TIME 110 9491
TEST_TIME 111 9496
TEST_TIME 112 9498
TEST_TIME 113 9540
TEST_TIME 114 9537
TEST_TIME 115 9553
TEST_TIME 116 9520
TEST_TIME 117 9556
TEST_TIME 118 9604
TEST_TIME 119 9608
TEST_TIME 120 9596

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2021 22:38:38 to 28-10-2021 22:38:42.

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 1608482 1608482 0 0.00 0 25518 5 0 0
doc_list_biud 1608482 1608482 0 0.00 0 34128 22 0 0
sp_add_invoice_to_stock 5487 5372 115 2.09 29 175884 14725 0 115
sp_cancel_adding_invoice 5764 5764 0 0.00 5 95472 827 0 0
sp_cancel_client_order 1848 1848 0 0.00 4 10433 327 0 0
sp_cancel_customer_reserve 564380 564380 0 0.00 1 34130 48 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 4619 77 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 4001 58 0 0
sp_cancel_supplier_invoice 6346 6336 10 0.15 2 23321 745 0 10
sp_cancel_supplier_order 428 428 0 0.00 11 817224 82211 0 0
sp_cancel_write_off 10411 10411 0 0.00 1 17653 100 0 0
sp_client_order 22047 22047 0 0.00 3 41470 313 0 0
sp_customer_reserve 495506 495506 0 0.00 0 116062 146 0 0
sp_fill_shopping_cart 33609 33609 0 0.00 1 13771 36 0 0
sp_fill_shopping_cart_clo_res 495503 495503 0 0.00 0 14053 12 0 0
sp_get_clo_for_invoice 5369 5369 0 0.00 0 29685 253 0 0
sp_kill_cost_storno 17352 17352 0 0.00 0 13880 53 0 0
sp_kill_qstorno_ret_qs2qd 1145935 1145935 0 0.00 0 34125 26 0 0
sp_kill_qty_storno 605348 605348 0 0.00 0 34128 59 0 0
sp_lock_selected_doc 22352 22352 0 0.00 0 7437 5 0 0
sp_make_cost_storno 30151 30151 0 0.00 0 24470 50 0 0
sp_multiply_rows_for_pdistr 30151 30151 0 0.00 0 14310 28 0 0
sp_multiply_rows_for_qdistr 22047 22047 0 0.00 0 32250 142 0 0
sp_pay_from_customer 7600 7600 0 0.00 4 14515 82 0 0
sp_pay_to_supplier 6370 6370 0 0.00 5 22622 120 0 0
sp_payment_common 13970 13970 0 0.00 1 12401 26 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 10425 303 0 0
sp_qd_handle_on_invoice_upd_sts 11133 11133 0 0.00 0 32362 323 0 0
sp_qd_handle_on_reserve_upd_sts 10812 10812 0 0.00 0 20071 58 0 0
sp_reserve_write_off 10812 10812 0 0.00 6 20121 169 0 0
sp_supplier_invoice 5661 5661 0 0.00 7 91648 3220 0 0
sp_supplier_order 5924 5924 0 0.00 5 30367 1167 0 0
srv_aggregate_perf_data 110 110 0 0.00 2806 136342 17303 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2163 145903 51865 0 0
srv_find_qd_qs_mism 1099353 1099353 0 0.00 0 25517 4 0 0
srv_make_invnt_saldo 585 585 0 0.00 32 117816 1084 0 0
srv_make_money_saldo 833 833 0 0.00 1 15066 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 204577 409791 323287 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5373 5373 0 0.00 0 33519 136 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 1 5674 99 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 6216 21 0 0
t$perf-norm:sp_cancel_customer_reserve 763 763 0 0.00 0 3417 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 390 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 969 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1843 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 55935 1623 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 29453 31 0 0
t$perf-norm:sp_client_order 22047 22047 0 0.00 0 47681 19 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 19145 22 0 0
t$perf-norm:sp_pay_from_customer 7600 7600 0 0.00 0 7762 8 0 0
t$perf-norm:sp_pay_to_supplier 6370 6370 0 0.00 0 47423 18 0 0
t$perf-norm:sp_reserve_write_off 10812 10812 0 0.00 0 8308 9 0 0
t$perf-norm:sp_supplier_invoice 5661 5661 0 0.00 0 29636 19 0 0
t$perf-norm:sp_supplier_order 5924 5924 0 0.00 0 6316 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 503 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1182 18 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 870 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 118 32 0 0
v_add_invoice_to_stock 10968 10738 230 2.09 0 3740 15 0 230
v_all_customers 40084 40084 0 0.00 0 81 1 0 0
v_all_suppliers 32312 32312 0 0.00 0 73 1 0 0
v_all_wares 44094 44094 0 0.00 0 81 1 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 636 6 0 0
v_cancel_client_order 3696 3696 0 0.00 0 4119 4 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 474 3 0 0
v_cancel_customer_reserve 1526 1526 0 0.00 0 2318 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 3926 8 0 0
v_cancel_supplier_invoice 868 848 20 2.30 0 3245 20 0 20
v_cancel_supplier_order 856 856 0 0.00 0 429 4 0 0
v_cancel_write_off 2144 2144 0 0.00 0 2904 13 0 0
v_max_id_clo_ord 5924 5924 0 0.00 0 677 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 3140 19 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 5842 8 0 0
v_max_non_paid_invoice 1776 1776 0 0.00 0 419 2 0 0
v_max_non_paid_realizn 4104 4104 0 0.00 0 2720 4 0 0
v_min_id_clo_ord 5924 5924 0 0.00 0 8529 11 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 5751 13 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 8932 12 0 0
v_min_non_paid_invoice 6370 6370 0 0.00 0 12005 19 0 0
v_min_non_paid_realizn 7600 7600 0 0.00 0 12388 20 0 0
v_our_firm 11002 11002 0 0.00 0 70 1 0 0
v_reserve_write_off 21624 21624 0 0.00 0 4115 4 0 0
x_lock_depdocs_on_canc_invoice 11676 11676 0 0.00 0 33908 107 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 12999 268 0 0
x_make_qstorno_1000_1200 5924 5924 0 0.00 1 30340 1090 0 0
x_make_qstorno_1200_2000 5661 5661 0 0.00 3 91641 3089 0 0
x_make_qstorno_2100_3300 495506 495506 0 0.00 0 116059 132 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2021 22:38:43 to 28-10-2021 22:38:47.

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:08:00 4 8192000000 8801523120 8813514752 7547056 0.092 2 2 3 0 7688160 66144 74960
20:09:01 9386 8192000000 9876621904 10182508544 966735136 10.555 100 89 178 1 995997648 5190928 24071584
20:10:17 11519 8192000000 9937555232 10257723392 1018724304 11.060 100 90 178 1 1049096608 7359136 23013168
20:11:39 24642 8192000000 9969754544 10299604992 1052640672 11.386 100 91 182 1 1085678976 9852976 23185328
20:13:10 10218 8192000000 10019855664 10370465792 1114262144 11.973 100 72 144 1 1141543616 10368176 16913296
20:14:23 8830 8192000000 10071231520 10428788736 1155512976 12.361 100 80 158 1 1186789728 12914784 18361968
20:15:39 9611 8192000000 10118348336 10487504896 1204255344 12.816 100 79 156 0 1234340112 12175984 17908784
20:16:52 7309 8192000000 10141592048 10521858048 1238692880 13.134 100 80 158 1 1268586832 12628224 17265728
20:18:03 2595 8192000000 10182045760 10564276224 1271760528 13.438 100 83 166 0 1303272560 12880448 18631584
20:19:08 2629 8192000000 10209897888 10595119104 1295236240 13.652 100 94 188 0 1329355024 12514656 21604128
20:20:13 1392 8192000000 10227042048 10615607296 1308118096 13.769 100 93 186 2 1342748544 13618784 21011664
20:21:15 26899 8192000000 10228097264 10631487488 1321432688 13.890 100 55 111 0 1342916080 8158928 13324464
20:22:43 8162 8192000000 10213646192 10631159808 1327900144 13.948 100 30 60 1 1341846048 6997760 6948144
20:23:51 14823 8192000000 10225701856 10642362368 1332753616 13.992 100 26 51 2 1344794368 5743088 6297664
20:25:06 18720 8192000000 10224429600 10646978560 1333731120 14.001 100 19 38 3 1344123440 6044448 4347872
20:26:26 6059 8192000000 10221416128 10647023616 1337087440 14.031 100 25 50 1 1349269568 5872576 6309552
20:27:32 5805 8192000000 10235554512 10654224384 1338307600 14.042 100 28 56 0 1355045088 10193872 6543616
20:28:38 12815 8192000000 10198482416 10607177728 1261309616 13.342 95 16 32 0 1270355392 4820864 4224912
20:29:51 36463 8192000000 10188262352 10602250240 1244228448 13.185 95 19 38 0 1253270208 4752912 4288848
20:31:29 8157 8192000000 10141218384 10541555712 1228292992 13.038 98 19 38 1 1239713536 6302016 5118528
20:32:39 6352 8192000000 10113024192 10510970880 1197482288 12.753 96 20 40 2 1211838800 9528032 4828480
20:33:46 9491 8192000000 10148850880 10549219328 1246032320 13.202 99 20 40 0 1256388672 6180048 4176304
20:34:56 14521 8192000000 10165854688 10566246400 1268022800 13.404 100 32 65 0 1280840688 5686192 7131696
20:36:15 9231 8192000000 10174359408 10584481792 1270925392 13.430 99 25 50 1 1283033600 6183680 5924528
20:37:25 8119 8192000000 10194886224 10609512448 1297220672 13.670 100 21 42 0 1312251680 10311120 4719888
20:38:41 26563 8192000000 10229407504 10637025280 1325384048 13.925 100 45 89 2 1342873584 6600864 10888672
20:40:13 40915 8192000000 10260396016 10672816128 1353671056 14.180 100 46 92 0 1373975440 9281776 11022608
20:41:56 121247 8192000000 10277200128 10709757952 1361829920 14.254 100 31 62 0 1387607136 17928576 7848640
20:45:10 8116 8192000000 10298888544 10721050624 1381590880 14.431 100 47 91 2 1413804336 21518928 10694528
20:46:25 35406 8192000000 10314497648 10743095296 1391868512 14.523 100 31 62 1 1421611136 21563536 8179088
20:48:07 2158 8192000000 10346402224 10761633792 1411535696 14.698 100 67 132 0 1452902208 25317072 16049440
20:49:12 8022 8192000000 10349655120 10770661376 1417755360 14.753 100 67 132 0 1456953376 23283344 15914672
20:50:25 4870 8192000000 10362997392 10779615232 1419050864 14.764 100 79 158 0 1470398672 31570544 19777264
20:51:42 2867 8192000000 10364169248 10788253696 1415490896 14.733 100 72 143 0 1466733504 33768640 17473968
20:52:51 2483 8192000000 10371853520 10790469632 1420963232 14.781 100 78 156 2 1472364912 32478736 18922944
20:53:57 5881 8192000000 10341915936 10763976704 1397299696 14.571 100 59 116 3 1442082288 30289952 14492640
20:55:09 2636 8192000000 10349354592 10768633856 1396013328 14.559 100 75 145 2 1446635952 32831536 17791088
20:56:14 2554 8192000000 10343063456 10756714496 1379845040 14.415 99 66 132 1 1430206672 33557424 16804208
20:57:20 8641 8192000000 10310249520 10735304704 1361981968 14.255 99 53 106 0 1403944912 28851936 13111008
20:58:36 6362 8192000000 10315704816 10731622400 1355332048 14.195 99 59 116 0 1410641456 40044848 15264560
20:59:46 28015 8192000000 10301764592 10735202304 1353862944 14.182 99 34 68 1 1402421120 38445936 10112240
21:01:25 38172 8192000000 10291781776 10717609984 1351588240 14.162 99 36 72 0 1394071472 33046992 9436240
21:03:08 19188 8192000000 10263930000 10699218944 1331848384 13.984 98 18 36 0 1356483728 20375040 4260304
21:04:41 25650 8192000000 10266350000 10705125376 1341256656 14.069 99 21 42 1 1366631408 19806320 5568432
21:06:17 51658 8192000000 10245704656 10687619072 1311092352 13.796 97 28 56 1 1326842160 8552608 7197200
21:08:09 119773 8192000000 10286739904 10715373568 1287542400 13.582 94 26 52 0 1302304496 9049728 5712368
21:11:15 115955 8192000000 10266765616 10711805952 1327821120 13.947 97 3 6 0 1343212256 14688128 703008
21:14:12 73221 8192000000 10279899232 10728738816 1333837584 14.002 97 8 16 0 1347289232 11238704 2212944
21:16:30 92061 8192000000 10289072368 10735370240 1354113984 14.184 98 7 14 1 1364827488 9507648 1205856
21:19:10 83850 8192000000 10317526512 10780573696 1397572656 14.573 100 10 20 0 1414890720 15161520 2156544
21:21:53 86438 8192000000 10305303056 10755907584 1380942528 14.425 99 26 52 1 1394330816 7292064 6096224
21:24:22 117649 8192000000 10316242896 10765979648 1389949808 14.505 99 22 44 1 1415405424 20646368 4809248
21:27:20 71567 8192000000 10295492880 10770792448 1396374048 14.563 100 12 24 0 1413936880 14864416 2698416
21:29:33 40095 8192000000 10293991728 10749218816 1390662176 14.512 100 17 34 1 1403700000 9122336 3915488
21:31:13 47782 8192000000 10290419552 10756096000 1377397872 14.393 99 12 24 0 1394709216 14243264 3068080
21:33:22 77167 8192000000 10283125696 10742644736 1372531200 14.350 99 24 42 0 1386923920 9417760 4974960
21:36:16 19870 8192000000 10274578000 10728443904 1360496416 14.242 99 14 24 1 1372393536 8477792 3419328
21:37:37 43225 8192000000 10285025408 10732650496 1336962848 14.030 97 15 29 1 1355594896 15458928 3173120
21:39:21 50637 8192000000 10280370928 10743218176 1354495040 14.188 98 15 30 0 1371428144 13087536 3845568
21:41:14 61270 8192000000 10283349520 10736578560 1318534640 13.863 95 15 30 0 1332089856 10013088 3542128
21:43:22 67786 8192000000 10303023664 10756452352 1369405568 14.322 99 17 34 0 1394824304 21271312 4147424
21:45:34 51171 8192000000 10305945088 10753101824 1373604048 14.359 100 29 58 2 1404241600 23579312 7058240
21:47:29 145902 8192000000 10269944752 10716631040 1138515824 12.202 83 22 44 0 1162054272 18771664 4766784
21:51:00 109688 8192000000 10262122864 10704732160 1323509760 13.908 97 15 30 0 1337835344 11344080 2981504
21:53:51 32001 8192000000 10264415440 10725224448 1354316208 14.186 100 10 20 0 1376308672 20303424 1689040
21:55:23 53201 8192000000 10266762880 10721746944 1336885216 14.029 98 11 22 0 1349246848 10219376 2142256
21:57:18 62784 8192000000 10273295232 10728087552 1365773008 14.289 100 9 18 0 1379991488 11604192 2614288
22:00:05 49537 8192000000 10263786656 10725068800 1343091952 14.085 98 10 20 0 1362541792 16827584 2622256
22:01:55 48832 8192000000 10278980624 10729054208 1354121408 14.185 99 23 46 0 1369303696 9520112 5662176
22:03:51 54725 8192000000 10295773920 10737385472 1365365680 14.286 99 25 52 0 1379791520 8045296 6380544
22:05:47 60465 8192000000 10296276496 10752520192 1382705184 14.441 100 24 48 0 1400629680 12359120 5565376
22:08:14 62074 8192000000 10301681216 10764210176 1394886960 14.549 100 9 18 0 1411035824 14098768 2050096
22:10:20 49255 8192000000 10317089232 10762670080 1385324800 14.464 99 25 49 2 1399212624 8921648 4966176
22:12:11 95812 8192000000 10315398000 10761240576 1338531408 14.044 95 19 38 1 1352402128 10005744 3864976
22:14:48 80703 8192000000 10304742288 10767208448 1376480288 14.385 98 23 47 1 1394151552 12232128 5439136
22:17:09 63366 8192000000 10303931616 10761662464 1378214480 14.401 99 19 38 1 1393623456 11197776 4211200
22:19:13 55253 8192000000 10288413888 10749902848 1364992944 14.282 98 13 26 0 1385882048 17759120 3129984
22:21:17 70820 8192000000 10290594432 10761854976 1380106864 14.418 99 14 28 0 1397482688 14112416 3263408
22:23:34 67056 8192000000 10286964064 10760863744 1390404752 14.509 100 9 18 0 1407777792 14912592 2460448
22:25:41 71148 8192000000 10278869408 10747551744 1347875696 14.128 97 5 10 1 1355185040 6391728 917616
22:27:52 31329 8192000000 10274798464 10734051328 1360052112 14.238 99 33 66 1 1376714224 8488560 8173552
22:29:27 31506 8192000000 10276382928 10720481280 1361555616 14.251 100 37 74 0 1379985392 10231616 8198160
22:31:06 49557 8192000000 10258570848 10705285120 1292712272 13.629 95 25 50 4 1308284592 10344624 5227696
22:32:56 72702 8192000000 10266863808 10708512768 1278169472 13.496 94 27 54 1 1306208672 22083472 5955728
22:35:14 37482 8192000000 10262880256 10713108480 1332730400 13.992 99 18 36 1 1355978352 19203520 4044432
22:37:01 47595 8192000000 10266785872 10703151104 1313836176 13.821 97 29 58 4 1331898976 11551104 6511696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          243683
MON$OLDEST_ACTIVE               246703
MON$OLDEST_SNAPSHOT             246703
MON$NEXT_TRANSACTION            246703
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                       2043232
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 ---
:392: 
:393: mscs-test-161.budzdorov.ru    Thu Oct 28 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    Thu Oct 28 22:38:05 2021
:398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:399: 
:400: 
:401: mscs-test-161.budzdorov.ru    Thu Oct 28 22:38:05 2021
:402:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:403: 
:404: 
:405: mscs-test-161.budzdorov.ru    Thu Oct 28 22:38:05 2021
:406:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:407: 
--- end of diff output ---

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

28.10.21 22:39:07. 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-28 22:39:09 No severe PSQL-related problems occured
28.10.21 22:39:11. End of report.