Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2022 01:40
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 15334760 kB
MemAvailable 30494824 kB
Buffers 0 kB
Cached 16403440 kB
SwapCached 2072 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.11
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-10-23 01:38:32
DTS_END                         2022-10-23 01:38:32.1760
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/55854
TRN_ID                          252081
ATT_ID                          701
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: 9576.77 23424 1149212
customer order: creation 181.58 319 21789
customer order: refuse 15.66 293 1879
order to supplier: creation 48.15 1230 5778
order to supplier: removal 3.74 82267 449
invoice (draft): creation 50.02 3076 6002
invoice (draft): removal 53.92 717 6470
invoice accept: apply 44.89 17054 5387
invoice accept: cancel 48.40 743 5808
customer reserve: creation 4066.92 174 488030
customer reserve: removal 4754.28 51 570514
realization accept: apply 89.18 160 10701
realization accept: cancel 82.24 95 9869
payment from customer: creation 63.23 109 7587
payment from customer: removal 6.12 50 734
payment to supplier: creation 51.72 111 6206
payment to supplier: removal 3.79 57 455
service: total inventory turnovers 5.05 1058 606
service: total monetary turnovers 6.91 20 829
service: aggregate perf. data 0.96 15752 115
service: refresh index statistics 0.03 345142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2022 01:41:20 to 23-10-2022 01:41:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4021
TEST_TIME 2 5772
TEST_TIME 3 6621
TEST_TIME 4 5682
TEST_TIME 5 5333
TEST_TIME 6 5035
TEST_TIME 7 4976
TEST_TIME 8 5102
TEST_TIME 9 5336
TEST_TIME 10 5376
TEST_TIME 11 5942
TEST_TIME 12 6757
TEST_TIME 13 7013
TEST_TIME 14 7295
TEST_TIME 15 8140
TEST_TIME 16 8417
TEST_TIME 17 8762
TEST_TIME 18 8578
TEST_TIME 19 8903
TEST_TIME 20 9182
TEST_TIME 21 9713
TEST_TIME 22 9923
TEST_TIME 23 9802
TEST_TIME 24 9589
TEST_TIME 25 9905
TEST_TIME 26 10082
TEST_TIME 27 10790
TEST_TIME 28 10697
TEST_TIME 29 10797
TEST_TIME 30 10656
TEST_TIME 31 10552
TEST_TIME 32 10377
TEST_TIME 33 10502
TEST_TIME 34 10413
TEST_TIME 35 10473
TEST_TIME 36 10444
TEST_TIME 37 10279
TEST_TIME 38 10219
TEST_TIME 39 10088
TEST_TIME 40 9986
TEST_TIME 41 9955
TEST_TIME 42 10061
TEST_TIME 43 10052
TEST_TIME 44 10048
TEST_TIME 45 9935
TEST_TIME 46 9943
TEST_TIME 47 9872
TEST_TIME 48 9806
TEST_TIME 49 9686
TEST_TIME 50 9643
TEST_TIME 51 9590
TEST_TIME 52 9500
TEST_TIME 53 9487
TEST_TIME 54 9553
TEST_TIME 55 9543
TEST_TIME 56 9534
TEST_TIME 57 9652
TEST_TIME 58 9610
TEST_TIME 59 9722
TEST_TIME 60 9689
TEST_TIME 61 9808
TEST_TIME 62 9868
TEST_TIME 63 9995
TEST_TIME 64 10008
TEST_TIME 65 9925
TEST_TIME 66 10089
TEST_TIME 67 10288
TEST_TIME 68 10149
TEST_TIME 69 10043
TEST_TIME 70 10036
TEST_TIME 71 10048
TEST_TIME 72 10012
TEST_TIME 73 9990
TEST_TIME 74 9945
TEST_TIME 75 9920
TEST_TIME 76 9888
TEST_TIME 77 9874
TEST_TIME 78 9832
TEST_TIME 79 9882
TEST_TIME 80 9896
TEST_TIME 81 9841
TEST_TIME 82 9882
TEST_TIME 83 9930
TEST_TIME 84 9963
TEST_TIME 85 9993
TEST_TIME 86 9938
TEST_TIME 87 9849
TEST_TIME 88 9844
TEST_TIME 89 9814
TEST_TIME 90 9794
TEST_TIME 91 9819
TEST_TIME 92 9812
TEST_TIME 93 9736
TEST_TIME 94 9688
TEST_TIME 95 9665
TEST_TIME 96 9688
TEST_TIME 97 9706
TEST_TIME 98 9673
TEST_TIME 99 9673
TEST_TIME 100 9741
TEST_TIME 101 9742
TEST_TIME 102 9807
TEST_TIME 103 9770
TEST_TIME 104 9761
TEST_TIME 105 9823
TEST_TIME 106 9820
TEST_TIME 107 9804
TEST_TIME 108 9877
TEST_TIME 109 9851
TEST_TIME 110 9811
TEST_TIME 111 9742
TEST_TIME 112 9740
TEST_TIME 113 9700
TEST_TIME 114 9730
TEST_TIME 115 9724
TEST_TIME 116 9687
TEST_TIME 117 9663
TEST_TIME 118 9608
TEST_TIME 119 9591
TEST_TIME 120 9581

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2022 01:41:24 to 23-10-2022 01:41:27.

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 1600738 1600738 0 0.00 0 19525 6 0 0
doc_list_biud 1600738 1600738 0 0.00 0 63579 24 0 0
sp_add_invoice_to_stock 5489 5387 102 1.85 12 178363 17055 0 102
sp_cancel_adding_invoice 5808 5808 0 0.00 3 106945 743 0 0
sp_cancel_client_order 1879 1879 0 0.00 3 15129 294 0 0
sp_cancel_customer_reserve 570514 570514 0 0.00 0 63580 52 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 2 4765 50 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 2 3542 57 0 0
sp_cancel_supplier_invoice 6476 6470 6 0.09 1 16852 718 0 6
sp_cancel_supplier_order 449 449 0 0.00 10 1021867 82268 0 0
sp_cancel_write_off 9869 9869 0 0.00 1 12192 95 0 0
sp_client_order 21789 21789 0 0.00 3 58711 320 0 0
sp_customer_reserve 488030 488030 0 0.00 0 42583 174 0 0
sp_fill_shopping_cart 33545 33545 0 0.00 1 13908 33 0 0
sp_fill_shopping_cart_clo_res 488017 488017 0 0.00 0 18609 14 0 0
sp_get_clo_for_invoice 5374 5374 0 0.00 0 21443 250 0 0
sp_kill_cost_storno 16866 16866 0 0.00 0 8276 46 0 0
sp_kill_qstorno_ret_qs2qd 1157816 1157816 0 0.00 0 63572 28 0 0
sp_kill_qty_storno 611062 611062 0 0.00 0 63578 61 0 0
sp_lock_selected_doc 22338 22338 0 0.00 0 7971 6 0 0
sp_make_cost_storno 29866 29866 0 0.00 0 16654 51 0 0
sp_multiply_rows_for_pdistr 29866 29866 0 0.00 0 19523 26 0 0
sp_multiply_rows_for_qdistr 21789 21789 0 0.00 0 58704 152 0 0
sp_pay_from_customer 7587 7587 0 0.00 4 21878 109 0 0
sp_pay_to_supplier 6206 6206 0 0.00 4 16035 111 0 0
sp_payment_common 13793 13793 0 0.00 1 21875 26 0 0
sp_qd_handle_on_cancel_clo 1879 1879 0 0.00 0 15123 274 0 0
sp_qd_handle_on_invoice_upd_sts 11180 11180 0 0.00 0 12813 289 0 0
sp_qd_handle_on_reserve_upd_sts 10701 10701 0 0.00 0 17837 55 0 0
sp_reserve_write_off 10701 10701 0 0.00 5 20399 161 0 0
sp_supplier_invoice 6002 6002 0 0.00 7 84251 3076 0 0
sp_supplier_order 5778 5778 0 0.00 4 56150 1231 0 0
srv_aggregate_perf_data 115 115 0 0.00 2696 105062 15752 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2296 119083 43688 0 0
srv_find_qd_qs_mism 1098422 1098422 0 0.00 0 10393 5 0 0
srv_make_invnt_saldo 606 606 0 0.00 34 67483 1059 0 0
srv_make_money_saldo 829 829 0 0.00 1 529 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 149597 467166 345143 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 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5387 5387 0 0.00 0 23797 184 0 0
t$perf-norm:sp_cancel_adding_invoice 468 468 0 0.00 0 3653 89 0 0
t$perf-norm:sp_cancel_client_order 1879 1879 0 0.00 0 6844 16 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 2459 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 2402 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 2478 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 503 9 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 34915 1575 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 2494 5 0 0
t$perf-norm:sp_client_order 21789 21789 0 0.00 0 8684 15 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 901 5 0 0
t$perf-norm:sp_pay_from_customer 7587 7587 0 0.00 0 18490 11 0 0
t$perf-norm:sp_pay_to_supplier 6206 6206 0 0.00 0 14846 16 0 0
t$perf-norm:sp_reserve_write_off 10701 10701 0 0.00 0 5932 9 0 0
t$perf-norm:sp_supplier_invoice 6002 6002 0 0.00 0 7839 20 0 0
t$perf-norm:sp_supplier_order 5778 5778 0 0.00 0 12385 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 446 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 6050 43 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 463 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10942 10738 204 1.86 0 9688 15 0 204
v_all_customers 39638 39638 0 0.00 0 149 0 0 0
v_all_suppliers 32550 32550 0 0.00 0 18 1 0 0
v_all_wares 43578 43578 0 0.00 0 120 1 0 0
v_cancel_adding_invoice 936 936 0 0.00 0 1496 9 0 0
v_cancel_client_order 3758 3758 0 0.00 0 1304 4 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 959 3 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 271 3 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 2822 7 0 0
v_cancel_supplier_invoice 892 880 12 1.34 0 2592 16 0 12
v_cancel_supplier_order 898 898 0 0.00 0 4981 9 0 0
v_cancel_write_off 2200 2200 0 0.00 0 7881 16 0 0
v_max_id_clo_ord 5778 5778 0 0.00 0 566 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 3107 22 0 0
v_max_id_ord_sup 5978 5978 0 0.00 0 5436 4 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 1479 3 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 1542 3 0 0
v_min_id_clo_ord 5778 5778 0 0.00 0 1618 4 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 5333 10 0 0
v_min_id_ord_sup 5978 5978 0 0.00 0 4962 6 0 0
v_min_non_paid_invoice 6206 6206 0 0.00 0 12322 15 0 0
v_min_non_paid_realizn 7587 7587 0 0.00 0 21871 21 0 0
v_our_firm 11094 11094 0 0.00 0 22 1 0 0
v_reserve_write_off 21402 21402 0 0.00 0 3518 4 0 0
x_lock_depdocs_on_canc_invoice 11838 11838 0 0.00 0 10287 95 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 19184 331 0 0
x_make_qstorno_1000_1200 5778 5778 0 0.00 1 56138 1168 0 0
x_make_qstorno_1200_2000 6002 6002 0 0.00 2 84236 2955 0 0
x_make_qstorno_2100_3300 488030 488030 0 0.00 0 42581 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2022 01:41:28 to 23-10-2022 01:41:32.

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
23:08:36 3 8192000000 8800623600 8812728320 5947632 0.072 3 1 2 0 6048560 58144 42784
23:09:36 30653 8192000000 9904787216 10214625280 979921920 10.683 100 85 170 0 1008104960 5859200 22323840
23:11:12 8725 8192000000 9950567872 10267283456 1029449344 11.163 100 96 192 0 1062195664 8241360 24504960
23:12:25 13528 8192000000 9968877008 10298130432 1052322144 11.383 100 84 168 0 1082592528 9154816 21115568
23:13:44 6602 8192000000 10000391904 10343124992 1093907392 11.780 100 71 142 0 1120605088 9875216 16822480
23:14:58 4719 8192000000 10084413536 10439331840 1171801936 12.514 100 85 170 0 1203246384 11813712 19630736
23:16:05 2815 8192000000 10133465376 10502586368 1221654096 12.977 100 81 159 0 1252211552 11833456 18724000
23:17:10 2109 8192000000 10163469520 10536681472 1251050720 13.248 100 81 161 0 1281441088 12875104 17515264
23:18:14 2324 8192000000 10199339456 10581491712 1285556288 13.564 100 86 168 1 1318293168 13859088 18877792
23:19:17 15230 8192000000 10197112512 10608521216 1306478176 13.754 100 49 99 1 1331907488 14877760 10551552
23:20:47 6830 8192000000 10197249664 10606972928 1310745232 13.793 100 30 60 0 1324089904 7008224 6336448
23:21:56 7186 8192000000 10205771120 10617876480 1318063152 13.859 100 25 48 0 1329124688 5931552 5129984
23:23:03 9324 8192000000 10213017616 10628902912 1324608992 13.918 100 26 53 0 1340821328 10791232 5421104
23:24:13 50840 8192000000 10237083568 10642575360 1327870480 13.948 100 52 106 0 1346730816 5981392 12878944
23:26:05 6282 8192000000 10219065472 10637516800 1309951216 13.786 98 27 54 0 1322171104 5826128 6393760
23:27:16 7088 8192000000 10206593568 10622443520 1305969040 13.749 99 19 38 1 1315620800 5144352 4507408
23:28:25 43575 8192000000 10201827936 10620616704 1297025664 13.668 98 31 62 0 1310851504 6611136 7214704
23:30:13 4830 8192000000 10139948064 10536529920 1197075472 12.749 93 27 54 1 1208754720 4698512 6980736
23:31:18 5478 8192000000 10126154112 10522017792 1207072240 12.842 96 26 52 0 1219374064 6447776 5854048
23:32:25 11374 8192000000 10163611920 10563190784 1237204272 13.120 97 29 58 0 1249460144 5797808 6458064
23:33:38 14852 8192000000 10148389328 10552061952 1226290000 13.020 97 28 56 1 1239072880 6438752 6344128
23:34:53 5465 8192000000 10193583056 10604384256 1294868352 13.649 100 24 48 0 1310759856 10920208 4971296
23:35:59 7510 8192000000 10196256512 10612936704 1305351312 13.744 100 14 29 0 1314114128 5885856 2876960
23:37:08 12057 8192000000 10211261584 10623737856 1318222176 13.861 100 22 43 0 1328726608 5051152 5453280
23:38:20 30130 8192000000 10233776160 10643984384 1329494176 13.963 100 50 100 2 1346538624 5633600 11410848
23:40:01 9689 8192000000 10265227504 10675400704 1348484416 14.134 100 61 122 1 1373042400 11443856 13114128
23:41:16 19545 8192000000 10279662224 10701066240 1370244752 14.329 100 45 88 3 1393335024 12445296 10644976
23:42:40 19163 8192000000 10306937952 10728407040 1391122880 14.516 100 47 94 0 1415085616 13254896 10707840
23:44:01 31869 8192000000 10323448768 10744459264 1400823360 14.602 100 42 83 0 1430189328 19690880 9675088
23:46:01 12534 8192000000 10320585552 10749128704 1406447344 14.652 100 44 86 2 1440148592 22385344 11315904
23:47:29 7017 8192000000 10348430384 10774188032 1422485136 14.795 100 48 95 0 1460528480 25540288 12503056
23:48:40 4378 8192000000 10363991872 10785124352 1427384080 14.838 100 67 129 0 1474265904 30284128 16597696
23:49:54 2295 8192000000 10376317776 10797428736 1434779264 14.904 100 77 151 2 1484972096 31533488 18659344
23:50:58 7425 8192000000 10362122800 10788347904 1417136816 14.747 99 60 120 0 1465989456 34520416 14332224
23:52:17 5640 8192000000 10361311504 10778025984 1410508976 14.688 100 79 156 3 1467361008 37354720 19497312
23:53:25 4929 8192000000 10335105536 10763771904 1397294400 14.571 100 35 70 0 1440723520 34064560 9364560
23:54:34 6628 8192000000 10318229952 10735222784 1374308640 14.366 100 66 129 0 1420510288 29766768 16434880
23:55:44 10343 8192000000 10307277760 10731556864 1372108384 14.346 100 41 80 1 1414045600 31900416 10036800
23:57:13 18931 8192000000 10309656448 10734374912 1356693216 14.208 99 30 60 0 1404047920 38695904 8658800
23:58:36 12778 8192000000 10303054352 10734616576 1371385888 14.339 100 27 54 0 1412651776 34140720 7125168
00:00:01 36282 8192000000 10312398224 10747797504 1382052880 14.435 100 29 58 1 1421460768 31503344 7904544
00:01:44 17636 8192000000 10316160720 10742349824 1373623808 14.360 99 44 87 1 1407765248 23804832 10336608
00:03:04 19150 8192000000 10306134208 10751819776 1373401248 14.358 99 21 42 0 1408908864 29615328 5892288
00:05:56 41194 8192000000 10233442864 10654474240 1268434608 13.407 94 23 46 0 1284692464 11096688 5161168
00:07:39 94570 8192000000 10262399856 10701271040 1326134656 13.932 98 17 34 0 1344420096 14738656 3546784
00:10:14 65791 8192000000 10279997600 10706317312 1313198624 13.815 96 33 64 0 1330098304 9556144 7343536
00:12:23 119082 8192000000 10278861312 10720493568 1335687040 14.019 97 6 12 0 1353023456 16193808 1142608
00:15:23 81183 8192000000 10303974992 10739367936 1378494928 14.403 100 42 82 1 1399173712 10805360 9873424
00:17:47 67316 8192000000 10289780656 10742607872 1376236576 14.383 99 33 66 0 1392176864 8666464 7273824
00:19:56 52592 8192000000 10316286736 10772226048 1404672912 14.637 100 39 76 0 1422185536 8375904 9136720
00:21:56 32550 8192000000 10293050080 10757816320 1387783776 14.486 99 6 11 0 1397600336 8518448 1298112
00:23:29 59307 8192000000 10305122336 10763575296 1400783616 14.602 100 24 46 0 1413396128 7247472 5365040
00:25:43 30603 8192000000 10292997040 10750500864 1386356128 14.473 99 19 38 0 1398623904 7976896 4290880
00:27:14 56472 8192000000 10287964448 10747637760 1391682464 14.521 100 17 34 0 1402019856 6570480 3766912
00:29:45 10748 8192000000 10287438832 10743132160 1373912208 14.362 98 28 54 1 1387265200 7152544 6200448
00:30:58 67717 8192000000 10294315088 10767720448 1383376560 14.447 99 13 26 0 1396083840 9538016 3169264
00:33:07 21992 8192000000 10275750720 10726637568 1353846768 14.182 98 19 38 0 1366579296 8531440 4201088
00:34:34 47359 8192000000 10296057296 10746478592 1365043056 14.283 98 30 59 1 1386685744 14959856 6682832
00:36:32 28026 8192000000 10300410160 10749263872 1347810224 14.128 97 26 52 0 1371806160 17990688 6005248
00:38:00 76643 8192000000 10305808448 10764050432 1344432912 14.097 96 16 31 1 1368423904 19960144 4030848
00:40:24 23435 8192000000 10310324448 10744197120 1372472720 14.349 100 43 86 0 1405054016 22952144 9629152
00:41:52 83943 8192000000 10266112288 10701635584 1283185456 13.542 93 12 24 0 1301438320 15665072 2587792
00:44:16 54792 8192000000 10289440688 10752098304 1351514048 14.161 99 10 20 0 1382004352 28169536 2320768
00:46:11 98699 8192000000 10278158912 10721075200 1351062320 14.157 99 17 34 0 1370227952 15262512 3903120
00:48:51 86754 8192000000 10269538816 10730180608 1319116528 13.869 96 5 10 0 1339110368 18863328 1130512
00:51:18 47653 8192000000 10246841968 10694492160 1277733392 13.492 94 17 34 0 1291973632 10754576 3485664
00:53:07 20045 8192000000 10267077648 10692608000 1336377376 14.025 99 48 95 1 1353981824 6639872 10964576
00:54:28 42255 8192000000 10274780224 10717523968 1360303440 14.240 100 27 52 1 1375902640 10227888 5371312
00:56:13 19844 8192000000 10268394176 10694721536 1321936752 13.894 97 47 93 2 1343501184 10993168 10571264
00:57:35 73758 8192000000 10257492928 10703347712 1317827312 13.857 97 14 28 0 1329684896 9067984 2789600
00:59:52 9913 8192000000 10290002560 10726141952 1370272128 14.329 100 36 72 1 1387190096 8848576 8069392
01:01:02 56518 8192000000 10283216624 10747518976 1367588464 14.305 99 12 24 0 1383011888 12480624 2942800
01:03:09 101349 8192000000 10283678832 10729029632 1353349152 14.178 98 18 36 1 1369072576 12105568 3617856
01:05:50 73080 8192000000 10290425904 10746998784 1390590112 14.511 100 22 44 0 1403897216 8354416 4952688
01:08:15 14765 8192000000 10299827328 10744102912 1382196640 14.436 100 35 69 0 1402598160 12578656 7822864
01:09:31 72308 8192000000 10265255056 10732474368 1342996272 14.084 97 10 20 0 1355518576 10317424 2204880
01:11:49 51214 8192000000 10293104544 10753855488 1389917392 14.505 100 9 18 0 1403040480 11137712 1985376
01:13:41 71333 8192000000 10287965312 10744426496 1337104336 14.031 96 16 32 0 1356571232 15646016 3820880
01:16:07 45118 8192000000 10305418464 10757906432 1363529168 14.269 98 21 42 0 1375479136 7270896 4679072
01:17:55 59646 8192000000 10303267120 10759725056 1382420032 14.438 99 24 48 0 1399767168 11604000 5743136
01:19:57 64300 8192000000 10277514736 10735456256 1347550112 14.125 97 13 27 0 1362449680 12023280 2876288
01:22:08 16294 8192000000 10293997248 10732056576 1348261824 14.132 98 38 75 0 1368767488 12478240 8027424
01:23:25 90024 8192000000 10295479824 10745040896 1361421088 14.250 99 16 32 0 1377263840 12215728 3627024
01:25:58 26782 8192000000 10306935792 10756231168 1383182624 14.445 100 22 44 0 1404197584 15856560 5158400
01:27:31 49787 8192000000 10276867472 10719723520 1343720096 14.091 98 20 38 2 1360165696 11637872 4807728
01:29:21 80560 8192000000 10248238224 10689339392 1318745296 13.865 96 23 46 0 1330354192 6546976 5061920
01:31:45 30757 8192000000 10245588288 10686713856 1321502752 13.890 97 39 78 1 1339868464 8867376 9498336
01:33:41 52472 8192000000 10252754432 10717642752 1343268048 14.087 99 7 14 1 1359643200 14763360 1611792
01:35:34 64551 8192000000 10256938208 10710044672 1341005936 14.066 99 24 46 2 1356886096 10072240 5807920
01:37:38 71893 8192000000 9377536592 9695948800 532682064 6.105 39 12 24 0 544109392 8232400 3194928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2022 01:41:34 to 23-10-2022 01:41:49.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 01:41:50 to 23-10-2022 01:41:52.

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          251682
MON$OLDEST_ACTIVE               252366
MON$OLDEST_SNAPSHOT             252366
MON$NEXT_TRANSACTION            252366
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                       2039904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Sun Oct 23 01:39:32 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
--- end of diff output ---

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

23.10.22 01:41:54. 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-10-23 01:41:56 No severe PSQL-related problems occured
23.10.22 01:41:57. End of report.