Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2022 16: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16382688 kB
MemAvailable 31268348 kB
Buffers 0 kB
Cached 15252524 kB
SwapCached 1180 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.9
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 2022-01-08 16:38:04
DTS_END                         2022-01-08 16:38:04.1720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42792
TRN_ID                          238918
ATT_ID                          622
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: 9497.65 22901 1139718
customer order: creation 174.30 363 20916
customer order: refuse 14.52 335 1742
order to supplier: creation 45.14 1324 5417
order to supplier: removal 3.68 109210 441
invoice (draft): creation 47.68 3203 5722
invoice (draft): removal 55.13 970 6616
invoice accept: apply 41.39 17429 4967
invoice accept: cancel 49.36 812 5923
customer reserve: creation 3908.68 167 469041
customer reserve: removal 4862.21 63 583465
realization accept: apply 83.53 185 10023
realization accept: cancel 84.15 118 10098
payment from customer: creation 58.72 102 7046
payment from customer: removal 5.40 97 648
payment to supplier: creation 47.93 123 5752
payment to supplier: removal 3.49 104 419
service: total inventory turnovers 4.82 982 578
service: total monetary turnovers 6.62 42 794
service: aggregate perf. data 0.88 20122 106
service: refresh index statistics 0.03 302267 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2022 16:39:02 to 08-01-2022 16:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6243
TEST_TIME 2 5638
TEST_TIME 3 5152
TEST_TIME 4 5182
TEST_TIME 5 4820
TEST_TIME 6 5232
TEST_TIME 7 5316
TEST_TIME 8 5514
TEST_TIME 9 5504
TEST_TIME 10 5990
TEST_TIME 11 6147
TEST_TIME 12 6114
TEST_TIME 13 6232
TEST_TIME 14 6572
TEST_TIME 15 6527
TEST_TIME 16 6655
TEST_TIME 17 6975
TEST_TIME 18 7096
TEST_TIME 19 7501
TEST_TIME 20 7508
TEST_TIME 21 8000
TEST_TIME 22 8439
TEST_TIME 23 9081
TEST_TIME 24 9655
TEST_TIME 25 10995
TEST_TIME 26 11081
TEST_TIME 27 11060
TEST_TIME 28 10801
TEST_TIME 29 10702
TEST_TIME 30 10494
TEST_TIME 31 10341
TEST_TIME 32 10300
TEST_TIME 33 10295
TEST_TIME 34 10069
TEST_TIME 35 10178
TEST_TIME 36 10137
TEST_TIME 37 10275
TEST_TIME 38 10268
TEST_TIME 39 10136
TEST_TIME 40 10183
TEST_TIME 41 10048
TEST_TIME 42 9997
TEST_TIME 43 9882
TEST_TIME 44 9913
TEST_TIME 45 9792
TEST_TIME 46 9883
TEST_TIME 47 9778
TEST_TIME 48 9696
TEST_TIME 49 9636
TEST_TIME 50 9530
TEST_TIME 51 9659
TEST_TIME 52 9830
TEST_TIME 53 9953
TEST_TIME 54 9918
TEST_TIME 55 10052
TEST_TIME 56 9922
TEST_TIME 57 10053
TEST_TIME 58 10235
TEST_TIME 59 10207
TEST_TIME 60 10127
TEST_TIME 61 10028
TEST_TIME 62 9950
TEST_TIME 63 9910
TEST_TIME 64 9945
TEST_TIME 65 9877
TEST_TIME 66 9832
TEST_TIME 67 9746
TEST_TIME 68 9747
TEST_TIME 69 9726
TEST_TIME 70 9768
TEST_TIME 71 9776
TEST_TIME 72 9744
TEST_TIME 73 9773
TEST_TIME 74 9745
TEST_TIME 75 9827
TEST_TIME 76 9911
TEST_TIME 77 9953
TEST_TIME 78 9993
TEST_TIME 79 9923
TEST_TIME 80 9911
TEST_TIME 81 9885
TEST_TIME 82 9893
TEST_TIME 83 9882
TEST_TIME 84 9843
TEST_TIME 85 9805
TEST_TIME 86 9749
TEST_TIME 87 9724
TEST_TIME 88 9707
TEST_TIME 89 9643
TEST_TIME 90 9652
TEST_TIME 91 9619
TEST_TIME 92 9549
TEST_TIME 93 9610
TEST_TIME 94 9597
TEST_TIME 95 9582
TEST_TIME 96 9632
TEST_TIME 97 9663
TEST_TIME 98 9668
TEST_TIME 99 9643
TEST_TIME 100 9697
TEST_TIME 101 9680
TEST_TIME 102 9666
TEST_TIME 103 9657
TEST_TIME 104 9728
TEST_TIME 105 9746
TEST_TIME 106 9759
TEST_TIME 107 9780
TEST_TIME 108 9715
TEST_TIME 109 9644
TEST_TIME 110 9605
TEST_TIME 111 9571
TEST_TIME 112 9555
TEST_TIME 113 9527
TEST_TIME 114 9544
TEST_TIME 115 9520
TEST_TIME 116 9496
TEST_TIME 117 9510
TEST_TIME 118 9508
TEST_TIME 119 9578
TEST_TIME 120 9501

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2022 16:39:06 to 08-01-2022 16:39:09.

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 1575777 1575777 0 0.00 0 33356 6 0 0
doc_list_biud 1575767 1575767 0 0.00 0 24983 30 0 0
sp_add_invoice_to_stock 5114 4967 147 2.87 33 167564 17430 0 147
sp_cancel_adding_invoice 5923 5923 0 0.00 4 48947 813 0 0
sp_cancel_client_order 1742 1742 0 0.00 3 7464 336 0 0
sp_cancel_customer_reserve 583465 583465 0 0.00 1 24035 64 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 2 6884 97 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 2 3533 105 0 0
sp_cancel_supplier_invoice 6624 6616 8 0.12 1 17146 971 0 8
sp_cancel_supplier_order 441 441 0 0.00 6 1266224 109210 0 0
sp_cancel_write_off 10098 10098 0 0.00 1 28609 118 0 0
sp_client_order 20916 20916 0 0.00 3 17993 363 0 0
sp_customer_reserve 469041 469041 0 0.00 0 39071 168 0 0
sp_fill_shopping_cart 32034 32034 0 0.00 1 20878 35 0 0
sp_fill_shopping_cart_clo_res 469027 469027 0 0.00 0 14523 15 0 0
sp_get_clo_for_invoice 4951 4951 0 0.00 0 27224 326 0 0
sp_kill_cost_storno 17088 17088 0 0.00 0 33345 66 0 0
sp_kill_qstorno_ret_qs2qd 1184085 1184085 0 0.00 0 24030 35 0 0
sp_kill_qty_storno 623258 623258 0 0.00 0 24981 75 0 0
sp_lock_selected_doc 20717 20717 0 0.00 0 6826 7 0 0
sp_make_cost_storno 27771 27771 0 0.00 0 20839 52 0 0
sp_multiply_rows_for_pdistr 27771 27771 0 0.00 0 20526 32 0 0
sp_multiply_rows_for_qdistr 20915 20915 0 0.00 0 17976 187 0 0
sp_pay_from_customer 7046 7046 0 0.00 4 21930 102 0 0
sp_pay_to_supplier 5752 5752 0 0.00 5 29000 124 0 0
sp_payment_common 12797 12797 0 0.00 1 19125 24 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 7453 294 0 0
sp_qd_handle_on_invoice_upd_sts 10873 10873 0 0.00 0 24981 337 0 0
sp_qd_handle_on_reserve_upd_sts 10023 10023 0 0.00 0 22393 63 0 0
sp_reserve_write_off 10023 10023 0 0.00 6 22432 186 0 0
sp_supplier_invoice 5722 5722 0 0.00 5 85175 3203 0 0
sp_supplier_order 5417 5417 0 0.00 5 27957 1325 0 0
srv_aggregate_perf_data 106 106 0 0.00 2631 380667 20122 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2163 105910 42279 0 0
srv_find_qd_qs_mism 1092599 1092599 0 0.00 0 20969 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 36 40692 982 0 0
srv_make_money_saldo 794 794 0 0.00 1 10619 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 245969 420628 302267 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4967 4967 0 0.00 0 18765 177 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 3311 96 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 3623 14 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 474 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 3469 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 614 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1063 11 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 21848 1527 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 1601 6 0 0
t$perf-norm:sp_client_order 20916 20916 0 0.00 0 26290 21 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 2224 8 0 0
t$perf-norm:sp_pay_from_customer 7046 7046 0 0.00 0 5167 11 0 0
t$perf-norm:sp_pay_to_supplier 5752 5752 0 0.00 0 16245 13 0 0
t$perf-norm:sp_reserve_write_off 10023 10023 0 0.00 0 6910 14 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 5610 19 0 0
t$perf-norm:sp_supplier_order 5417 5417 0 0.00 0 5432 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 133 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 354 6 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1764 24 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 3506 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 76 23 0 0
v_add_invoice_to_stock 10192 9898 294 2.88 0 7940 20 0 294
v_all_customers 37906 37906 0 0.00 0 15 0 0 0
v_all_suppliers 30632 30632 0 0.00 0 22 1 0 0
v_all_wares 41828 41828 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 166 5 0 0
v_cancel_client_order 3484 3484 0 0.00 0 3820 6 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 4939 9 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1191 5 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 3204 18 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 2815 22 0 16
v_cancel_supplier_order 882 882 0 0.00 0 1942 8 0 0
v_cancel_write_off 2080 2080 0 0.00 0 2689 14 0 0
v_max_id_clo_ord 5406 5406 0 0.00 0 3843 3 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 3124 17 0 0
v_max_id_ord_sup 5714 5714 0 0.00 0 7401 5 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 3030 5 0 0
v_max_non_paid_realizn 3727 3727 0 0.00 0 1881 5 0 0
v_min_id_clo_ord 5406 5406 0 0.00 0 3036 5 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5532 12 0 0
v_min_id_ord_sup 5714 5714 0 0.00 0 5867 5 0 0
v_min_non_paid_invoice 5752 5752 0 0.00 0 19115 18 0 0
v_min_non_paid_realizn 7045 7045 0 0.00 0 6668 17 0 0
v_our_firm 10558 10558 0 0.00 0 86 1 0 0
v_reserve_write_off 20046 20046 0 0.00 0 5319 6 0 0
x_lock_depdocs_on_canc_invoice 12129 12129 0 0.00 0 21592 121 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 13582 405 0 0
x_make_qstorno_1000_1200 5417 5417 0 0.00 1 27947 1252 0 0
x_make_qstorno_1200_2000 5722 5722 0 0.00 2 84329 3077 0 0
x_make_qstorno_2100_3300 469041 469041 0 0.00 0 39068 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2022 16:39:10 to 08-01-2022 16:39:14.

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
14:08:07 2 8192000000 8799913696 8811413504 6309952 0.076 2 1 2 0 6409584 56848 42784
14:09:08 24923 8192000000 9912932720 10224734208 988883088 10.771 100 88 176 2 1017915232 6074080 22958064
14:10:39 17692 8192000000 9964667392 10292088832 1048578384 11.347 100 87 174 1 1078790128 8280400 21931344
14:12:06 23588 8192000000 10016891264 10356637696 1096108688 11.801 100 82 164 0 1125306096 8927040 20270368
14:13:32 12592 8192000000 10055777904 10406752256 1137642384 12.193 100 81 162 1 1167113024 10562944 18907696
14:14:49 9569 8192000000 10104416912 10469961728 1192355888 12.705 100 78 155 0 1221415728 11746752 17313088
14:16:03 4325 8192000000 10147988640 10523607040 1237395392 13.122 100 78 156 1 1267258800 12809744 17053664
14:17:10 2940 8192000000 10180816496 10565726208 1275611104 13.473 100 80 160 0 1306382384 12766592 18004688
14:18:15 1403 8192000000 10218316912 10603773952 1306830560 13.757 100 92 184 2 1342246080 15315760 20099760
14:19:17 1984 8192000000 10226404368 10619138048 1313229920 13.815 100 91 181 0 1345525664 12162384 20133360
14:20:20 4660 8192000000 10209217840 10622676992 1321526160 13.891 100 37 74 1 1336986240 7211280 8248800
14:21:26 50593 8192000000 10231506592 10641379328 1329848512 13.966 100 50 98 3 1346740160 5743088 11148560
14:23:18 5001 8192000000 10230603552 10647818240 1335303200 14.015 100 35 68 2 1350142448 7051856 7787392
14:24:23 6205 8192000000 10232644736 10654597120 1341982192 14.075 100 26 52 1 1354509216 7231376 5295648
14:25:31 6004 8192000000 10238299136 10659864576 1343890960 14.092 100 26 52 0 1356916480 6939312 6086208
14:26:40 28998 8192000000 10238097776 10668441600 1347859904 14.128 100 25 50 0 1362790016 9051824 5878288
14:28:12 12082 8192000000 10202600128 10619777024 1276913728 13.485 96 20 40 0 1286922416 5329568 4679120
14:29:24 10920 8192000000 10166340944 10570080256 1223371776 12.993 95 19 38 0 1234053424 5701936 4979712
14:30:39 64163 8192000000 10138231728 10533269504 1158382560 12.388 91 19 37 0 1167143888 4568880 4192448
14:32:46 6895 8192000000 10145624768 10546057216 1243252944 13.176 99 34 67 1 1257510096 6581632 7675520
14:33:53 7948 8192000000 10171366912 10574794752 1255682848 13.290 98 25 50 0 1271669280 9739792 6246640
14:35:02 9713 8192000000 10181142544 10594369536 1290573808 13.609 100 21 40 2 1300766608 4924544 5268256
14:36:13 36673 8192000000 10192458576 10603515904 1297861776 13.676 100 20 40 2 1307854912 5832400 4160736
14:37:52 5037 8192000000 10213728576 10623422464 1313090912 13.814 100 35 71 1 1327516944 5945888 8480144
14:39:06 18453 8192000000 10241808064 10656845824 1341565200 14.072 100 43 86 1 1359576288 8200096 9810992
14:40:30 32106 8192000000 10270079536 10686734336 1358857680 14.227 100 42 84 1 1382737264 14293408 9586176
14:42:09 81550 8192000000 10289955584 10715246592 1375595280 14.377 100 41 82 1 1401582848 16226576 9760992
14:44:32 15115 8192000000 10304488032 10730975232 1388001824 14.488 100 42 84 0 1419370720 20625552 10743344
14:46:03 17017 8192000000 10320107056 10748104704 1400759664 14.602 100 41 82 2 1433039584 22001168 10278752
14:47:31 4989 8192000000 10354245456 10774872064 1416832368 14.745 100 67 134 1 1464032288 29799184 17400736
14:48:39 3042 8192000000 10363862752 10785509376 1426218112 14.828 100 63 128 1 1472634752 30716416 15700224
14:49:48 2914 8192000000 10374541600 10794012672 1419363424 14.767 99 79 157 0 1477578288 37832736 20382128
14:50:56 4656 8192000000 10375132432 10786480128 1409114752 14.676 99 72 141 1 1466356160 39862400 17379008
14:52:05 3549 8192000000 10370350576 10786144256 1417832880 14.753 100 71 142 0 1477732960 42116416 17783664
14:53:17 3041 8192000000 10376996864 10788388864 1419370368 14.767 100 79 156 3 1482619216 43068800 20180048
14:54:26 2884 8192000000 10390215424 10799120384 1419767536 14.771 99 84 166 0 1488850640 48280304 20802800
14:55:43 2392 8192000000 10392193728 10807242752 1413184960 14.712 99 66 129 1 1486564160 56574432 16804768
14:56:56 16377 8192000000 10384562240 10813145088 1413898944 14.719 99 49 98 1 1487852976 59790384 14163648
14:58:15 8849 8192000000 10382264048 10801930240 1399374016 14.589 100 59 118 0 1487531696 71532368 16625312
14:59:37 2162 8192000000 10389042208 10808225792 1397328288 14.571 100 79 158 0 1491848944 73668848 20851808
15:00:42 7429 8192000000 10357837808 10785677312 1376762592 14.388 99 47 94 2 1451008640 60608960 13637088
15:01:57 24724 8192000000 10328801744 10769944576 1359682320 14.235 98 31 60 0 1422656896 54000560 8974016
15:03:36 32554 8192000000 10265353328 10722500608 1328781488 13.956 98 17 33 0 1372835776 39389680 4664608
15:05:28 42987 8192000000 10272294592 10722258944 1361351168 14.249 100 16 33 1 1376772976 11678528 3743280
15:07:11 49967 8192000000 10266443168 10706137088 1347118992 14.122 99 28 56 0 1361602672 8342048 6141632
15:09:02 50460 8192000000 10272758672 10708447232 1355580080 14.198 99 40 79 1 1373737456 9558256 8599120
15:10:57 34962 8192000000 10271425072 10725429248 1366757536 14.298 99 23 46 0 1384970512 13348256 4864720
15:12:38 26298 8192000000 10299296160 10748506112 1385420128 14.465 100 16 32 1 1405559776 16487152 3652496
15:14:06 39407 8192000000 10298808368 10740338688 1365671600 14.288 98 21 42 0 1382697600 11857392 5168608
15:15:47 37175 8192000000 10276232944 10742820864 1369348048 14.321 99 18 35 0 1386440864 13348896 3743920
15:17:26 18264 8192000000 10279717264 10726600704 1358192272 14.221 98 25 50 0 1372944272 8327888 6424112
15:18:45 25123 8192000000 10286562624 10743832576 1371418928 14.340 99 8 16 0 1380337312 7285136 1633248
15:20:14 52986 8192000000 10288253552 10750824448 1391710592 14.521 100 19 38 0 1407695136 11952464 4032080
15:22:12 44051 8192000000 10282553872 10740998144 1378697840 14.405 99 16 32 0 1391914624 9664944 3551840
15:23:59 44166 8192000000 10294441568 10758520832 1389604976 14.502 99 30 60 1 1408507168 12345728 6556464
15:25:47 30565 8192000000 10310878656 10770702336 1401896224 14.612 100 9 18 0 1415931952 11852544 2183184
15:27:21 73453 8192000000 10313067968 10764951552 1346787936 14.119 95 14 28 1 1370700928 20506352 3406640
15:29:47 40747 8192000000 10310767856 10770096128 1392133824 14.525 100 19 38 2 1420878656 23924704 4820128
15:31:34 57733 8192000000 10319284928 10765389824 1379750608 14.414 98 21 41 0 1393083744 8765328 4567808
15:34:03 59054 8192000000 10319737392 10776371200 1393236144 14.535 99 24 48 2 1419305504 20049872 6019488
15:36:23 62398 8192000000 10295111072 10756997120 1334085920 14.004 95 6 12 0 1348790208 13490768 1213520
15:38:25 94184 8192000000 10279887904 10721042432 1327790736 13.947 96 32 64 0 1342971056 7809872 7370448
15:41:07 51314 8192000000 10243434640 10690162688 1330212512 13.969 98 27 54 0 1344948608 8720320 6015776
15:43:10 31410 8192000000 10247196528 10704084992 1339620608 14.054 98 7 14 0 1347449440 6725440 1103392
15:44:42 42544 8192000000 10249621376 10698235904 1345255104 14.105 98 19 37 1 1355422272 6326528 3840640
15:46:25 90618 8192000000 10250659696 10715021312 1318616000 13.864 96 7 14 0 1332956976 12422096 1918880
15:49:01 35898 8192000000 10262131008 10715037696 1335606336 14.018 98 29 59 1 1353897584 11539056 6752192
15:50:39 54862 8192000000 10284915760 10729627648 1336821536 14.029 97 37 74 0 1357440048 12295232 8323280
15:53:43 31281 8192000000 10283165328 10730414080 1356214224 14.203 99 37 75 0 1379834064 14917904 8701936
15:55:17 57305 8192000000 10270732912 10744430592 1333077408 13.995 98 13 26 0 1359076416 22868224 3130784
15:57:21 24578 8192000000 10290065200 10726604800 1354455824 14.188 99 34 68 1 1370846144 9146960 7243360
15:58:48 88739 8192000000 10281493552 10739773440 1357976880 14.219 99 18 36 0 1382807264 20684672 4145712
16:01:17 56086 8192000000 10282691088 10733506560 1365806608 14.289 100 27 54 0 1393333440 20951520 6575312
16:03:16 43924 8192000000 10275419744 10728804352 1377845008 14.397 100 14 29 0 1389510400 8492272 3173120
16:05:01 86058 8192000000 10286851440 10749186048 1384616304 14.458 100 15 30 0 1398739904 10020976 4102624
16:07:31 105909 8192000000 10298586032 10762170368 1404080448 14.631 100 23 46 0 1418301712 9004320 5216944
16:10:18 51708 8192000000 10303959392 10763358208 1394412576 14.545 99 19 38 0 1407961376 9484160 4064640
16:12:10 59100 8192000000 10281049488 10739970048 1344470944 14.098 96 10 20 0 1358634832 11287424 2876464
16:14:09 58929 8192000000 10301687984 10760200192 1397073712 14.569 100 12 24 0 1412863024 13127104 2662208
16:16:08 47958 8192000000 10289894624 10745479168 1357967008 14.219 97 28 55 2 1374351136 10321296 6062832
16:17:58 25240 8192000000 10296417568 10749370368 1370743920 14.334 98 30 60 0 1387855568 10810848 6300800
16:19:49 26953 8192000000 10321124416 10759593984 1393537472 14.537 100 33 62 1 1410994576 10434064 7023040
16:21:17 50506 8192000000 10304625184 10751684608 1358656048 14.225 97 38 75 0 1380797232 14130592 8010592
16:23:13 92030 8192000000 10318283664 10773528576 1357190192 14.212 97 9 18 1 1378787776 19250496 2347088
16:25:53 100251 8192000000 10301599968 10757509120 1388432608 14.492 99 14 28 1 1402915072 10942160 3540304
16:28:38 61670 8192000000 10284074496 10744963072 1375962704 14.380 99 10 20 0 1393773056 15553776 2256576
16:30:43 73244 8192000000 10285126608 10743869440 1361702384 14.253 98 13 26 0 1376401248 11329488 3369376
16:33:14 68378 8192000000 10279841152 10733895680 1324352704 13.916 95 26 52 0 1342514880 12080768 6081408
16:35:23 57908 8192000000 10283579952 10736840704 1359707232 14.235 98 25 50 2 1381266176 15483216 6075728
16:37:26 48179 8192000000 9141638512 9386360832 300354992 3.536 22 4 7 1 306990240 5794832 840416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-01-2022 16:39:16 to 08-01-2022 16:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2022 16:39:32 to 08-01-2022 16:39:34.

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          239187
MON$OLDEST_ACTIVE               239188
MON$OLDEST_SNAPSHOT             239188
MON$NEXT_TRANSACTION            239188
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                       2035360
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:638: 
:639: mscs-test-161.budzdorov.ru    Sat Jan  8 16:38:16 2022
:640:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:641: 
:642: 
:643: mscs-test-161.budzdorov.ru    Sat Jan  8 16:38:18 2022
:644:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:645: 
:646: 
:647: mscs-test-161.budzdorov.ru    Sat Jan  8 16:38:21 2022
:648:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:649: 
--- end of diff output ---

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

08.01.22 16:39:35. 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
2022-01-08 16:39:37 No severe PSQL-related problems occured
08.01.22 16:39:38. End of report.