Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2023 01: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 15128912 kB
MemAvailable 30395488 kB
Buffers 0 kB
Cached 16556720 kB
SwapCached 4428 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 2023-01-09 01:38:29
DTS_END                         2023-01-09 01:38:29.3150
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/43330
TRN_ID                          245570
ATT_ID                          702
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: 9743.03 19588 1169163
customer order: creation 175.43 348 21052
customer order: refuse 15.38 326 1845
order to supplier: creation 46.89 1248 5627
order to supplier: removal 3.67 104032 440
invoice (draft): creation 46.33 3013 5560
invoice (draft): removal 57.50 764 6900
invoice accept: apply 42.39 17734 5087
invoice accept: cancel 51.40 737 6168
customer reserve: creation 3923.75 174 470850
customer reserve: removal 5072.01 58 608641
realization accept: apply 85.43 212 10251
realization accept: cancel 90.56 105 10867
payment from customer: creation 61.26 140 7351
payment from customer: removal 5.68 104 681
payment to supplier: creation 50.00 137 6000
payment to supplier: removal 3.29 138 395
service: total inventory turnovers 4.97 1235 596
service: total monetary turnovers 6.23 37 748
service: aggregate perf. data 0.83 26553 100
service: refresh index statistics 0.03 234668 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2023 01:39:23 to 09-01-2023 01:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7382
TEST_TIME 2 6054
TEST_TIME 3 5496
TEST_TIME 4 4810
TEST_TIME 5 5212
TEST_TIME 6 5010
TEST_TIME 7 5136
TEST_TIME 8 5734
TEST_TIME 9 6105
TEST_TIME 10 6176
TEST_TIME 11 6275
TEST_TIME 12 6273
TEST_TIME 13 6260
TEST_TIME 14 6462
TEST_TIME 15 6557
TEST_TIME 16 6955
TEST_TIME 17 6888
TEST_TIME 18 6892
TEST_TIME 19 6967
TEST_TIME 20 7034
TEST_TIME 21 7414
TEST_TIME 22 8035
TEST_TIME 23 8168
TEST_TIME 24 8066
TEST_TIME 25 8378
TEST_TIME 26 9192
TEST_TIME 27 9127
TEST_TIME 28 9023
TEST_TIME 29 9360
TEST_TIME 30 10225
TEST_TIME 31 10637
TEST_TIME 32 10720
TEST_TIME 33 10570
TEST_TIME 34 10767
TEST_TIME 35 10739
TEST_TIME 36 10676
TEST_TIME 37 10626
TEST_TIME 38 10466
TEST_TIME 39 10345
TEST_TIME 40 10122
TEST_TIME 41 10212
TEST_TIME 42 10247
TEST_TIME 43 10048
TEST_TIME 44 10010
TEST_TIME 45 10004
TEST_TIME 46 9932
TEST_TIME 47 9970
TEST_TIME 48 10031
TEST_TIME 49 9947
TEST_TIME 50 9923
TEST_TIME 51 9894
TEST_TIME 52 9841
TEST_TIME 53 9774
TEST_TIME 54 9704
TEST_TIME 55 9679
TEST_TIME 56 9622
TEST_TIME 57 9629
TEST_TIME 58 9608
TEST_TIME 59 9586
TEST_TIME 60 9614
TEST_TIME 61 9684
TEST_TIME 62 9772
TEST_TIME 63 9853
TEST_TIME 64 9856
TEST_TIME 65 9840
TEST_TIME 66 9847
TEST_TIME 67 9808
TEST_TIME 68 9901
TEST_TIME 69 9951
TEST_TIME 70 10053
TEST_TIME 71 10052
TEST_TIME 72 9944
TEST_TIME 73 9936
TEST_TIME 74 9943
TEST_TIME 75 9841
TEST_TIME 76 9820
TEST_TIME 77 9798
TEST_TIME 78 9879
TEST_TIME 79 9823
TEST_TIME 80 9797
TEST_TIME 81 9707
TEST_TIME 82 9694
TEST_TIME 83 9683
TEST_TIME 84 9671
TEST_TIME 85 9669
TEST_TIME 86 9623
TEST_TIME 87 9732
TEST_TIME 88 9712
TEST_TIME 89 9731
TEST_TIME 90 9737
TEST_TIME 91 9867
TEST_TIME 92 9864
TEST_TIME 93 9855
TEST_TIME 94 9947
TEST_TIME 95 9931
TEST_TIME 96 9990
TEST_TIME 97 9914
TEST_TIME 98 9995
TEST_TIME 99 10038
TEST_TIME 100 9953
TEST_TIME 101 9961
TEST_TIME 102 9938
TEST_TIME 103 9899
TEST_TIME 104 9843
TEST_TIME 105 9829
TEST_TIME 106 9812
TEST_TIME 107 9809
TEST_TIME 108 9789
TEST_TIME 109 9758
TEST_TIME 110 9693
TEST_TIME 111 9727
TEST_TIME 112 9712
TEST_TIME 113 9724
TEST_TIME 114 9679
TEST_TIME 115 9715
TEST_TIME 116 9721
TEST_TIME 117 9757
TEST_TIME 118 9804
TEST_TIME 119 9783
TEST_TIME 120 9745

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2023 01:39:26 to 09-01-2023 01:39:30.

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 1606770 1606770 0 0.00 0 40671 6 0 0
doc_list_biud 1606768 1606768 0 0.00 0 59281 28 0 0
sp_add_invoice_to_stock 5202 5087 115 2.21 36 161167 17734 0 115
sp_cancel_adding_invoice 6168 6168 0 0.00 4 54763 738 0 0
sp_cancel_client_order 1845 1845 0 0.00 3 17714 327 0 0
sp_cancel_customer_reserve 608641 608641 0 0.00 0 59283 59 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 8056 104 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 19616 138 0 0
sp_cancel_supplier_invoice 6908 6900 8 0.11 1 22813 764 0 8
sp_cancel_supplier_order 440 440 0 0.00 11 1302594 104032 0 0
sp_cancel_write_off 10867 10867 0 0.00 1 25113 106 0 0
sp_client_order 21052 21052 0 0.00 3 25028 348 0 0
sp_customer_reserve 470850 470850 0 0.00 0 43706 175 0 0
sp_fill_shopping_cart 32234 32234 0 0.00 1 11876 35 0 0
sp_fill_shopping_cart_clo_res 470845 470845 0 0.00 0 20888 14 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 24908 300 0 0
sp_kill_cost_storno 18111 18111 0 0.00 0 24562 59 0 0
sp_kill_qstorno_ret_qs2qd 1235489 1235489 0 0.00 0 59276 31 0 0
sp_kill_qty_storno 650194 650194 0 0.00 0 59280 68 0 0
sp_lock_selected_doc 21192 21192 0 0.00 0 7499 7 0 0
sp_make_cost_storno 28684 28684 0 0.00 0 40658 65 0 0
sp_multiply_rows_for_pdistr 28684 28684 0 0.00 0 18411 32 0 0
sp_multiply_rows_for_qdistr 21052 21052 0 0.00 0 25010 148 0 0
sp_pay_from_customer 7351 7351 0 0.00 4 24616 140 0 0
sp_pay_to_supplier 6000 6000 0 0.00 4 18519 138 0 0
sp_payment_common 13351 13351 0 0.00 2 18499 49 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 17702 301 0 0
sp_qd_handle_on_invoice_upd_sts 11250 11250 0 0.00 0 34037 326 0 0
sp_qd_handle_on_reserve_upd_sts 10251 10251 0 0.00 0 12680 58 0 0
sp_reserve_write_off 10251 10251 0 0.00 6 19607 213 0 0
sp_supplier_invoice 5560 5560 0 0.00 5 55313 3013 0 0
sp_supplier_order 5627 5627 0 0.00 5 32775 1249 0 0
srv_aggregate_perf_data 100 100 0 0.00 2998 596250 26553 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1572 115805 42156 0 0
srv_find_qd_qs_mism 1121271 1121271 0 0.00 0 24666 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 36 66685 1235 0 0
srv_make_money_saldo 748 748 0 0.00 0 2533 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 106769 344047 234669 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 7403 151 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 7492 101 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 1583 10 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 5576 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 1995 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 160 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1749 24 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 31162 1585 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 5494 16 0 0
t$perf-norm:sp_client_order 21052 21052 0 0.00 0 9833 15 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 2556 12 0 0
t$perf-norm:sp_pay_from_customer 7351 7351 0 0.00 0 15471 9 0 0
t$perf-norm:sp_pay_to_supplier 6000 6000 0 0.00 0 3337 9 0 0
t$perf-norm:sp_reserve_write_off 10251 10251 0 0.00 0 5956 8 0 0
t$perf-norm:sp_supplier_invoice 5560 5560 0 0.00 0 9005 20 0 0
t$perf-norm:sp_supplier_order 5627 5627 0 0.00 0 5786 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 160 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 76 2 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2704 24 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1061 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10394 10164 230 2.21 0 19034 19 0 230
v_all_customers 38804 38804 0 0.00 0 23 1 0 0
v_all_suppliers 31368 31368 0 0.00 0 90 1 0 0
v_all_wares 42104 42104 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2977 13 0 0
v_cancel_client_order 3690 3690 0 0.00 0 3045 6 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 8010 15 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 856 4 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 19531 39 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 2915 23 0 16
v_cancel_supplier_order 880 880 0 0.00 0 14404 37 0 0
v_cancel_write_off 2042 2042 0 0.00 0 3367 16 0 0
v_max_id_clo_ord 5625 5625 0 0.00 0 1633 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 2595 17 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 4317 4 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 958 4 0 0
v_max_non_paid_realizn 3730 3730 0 0.00 0 1566 4 0 0
v_min_id_clo_ord 5625 5625 0 0.00 0 5979 12 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 6822 22 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 1820 3 0 0
v_min_non_paid_invoice 6000 6000 0 0.00 0 18493 41 0 0
v_min_non_paid_realizn 7351 7351 0 0.00 0 18330 39 0 0
v_our_firm 10542 10542 0 0.00 0 20 1 0 0
v_reserve_write_off 20502 20502 0 0.00 0 19534 12 0 0
x_lock_depdocs_on_canc_invoice 12658 12658 0 0.00 0 14178 114 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 8511 384 0 0
x_make_qstorno_1000_1200 5627 5627 0 0.00 1 32768 1157 0 0
x_make_qstorno_1200_2000 5560 5560 0 0.00 2 55306 2899 0 0
x_make_qstorno_2100_3300 470850 470850 0 0.00 0 43706 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2023 01:39:31 to 09-01-2023 01:39:35.

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:33 13 8192000000 8815726624 8832655360 19498256 0.237 5 5 6 0 19767792 98112 171424
23:09:34 12852 8192000000 9908831360 10218561536 993334592 10.814 100 85 170 0 1021117776 5863872 21919312
23:10:51 21439 8192000000 9953174736 10274385920 1033063408 11.198 100 91 182 0 1064820480 8515552 23241520
23:12:15 15891 8192000000 9970561888 10298859520 1053913904 11.398 100 86 172 0 1085580000 10278112 21387984
23:13:35 7045 8192000000 10007287888 10351165440 1098775744 11.826 100 74 146 1 1126972976 10827440 17369792
23:14:47 6768 8192000000 10071053104 10426064896 1156090720 12.367 100 80 159 0 1186066064 11809264 18166080
23:15:57 2942 8192000000 10118517936 10480570368 1201370688 12.789 100 81 161 0 1232785280 13511568 17903024
23:17:01 2503 8192000000 10139519216 10511392768 1236203328 13.111 100 87 172 0 1268707312 13527808 18976176
23:18:06 1695 8192000000 10173788624 10552557568 1262091888 13.349 100 87 172 0 1293817920 12882864 18843168
23:19:09 2098 8192000000 10211132560 10591543296 1288783504 13.593 100 91 176 2 1321371248 13480832 19106912
23:20:12 24964 8192000000 10214605632 10604281856 1302110320 13.714 100 60 119 0 1323611216 8296576 13204320
23:21:38 4215 8192000000 10199864832 10611920896 1314032208 13.823 100 31 62 0 1328386432 7784096 6570128
23:22:46 7655 8192000000 10211719968 10627469312 1319626112 13.873 100 23 47 0 1335551360 11268016 4657232
23:23:54 10673 8192000000 10213949776 10635411456 1331321472 13.979 100 14 28 0 1340281056 5741184 3218400
23:25:06 54053 8192000000 10230722320 10652471296 1338944304 14.048 100 44 88 1 1354198160 4640448 10613408
23:27:03 8380 8192000000 10228798336 10646953984 1332642960 13.991 100 22 44 1 1342955760 5626256 4686544
23:28:12 6151 8192000000 10217607280 10630086656 1321185120 13.887 100 29 59 0 1334622736 6831888 6605728
23:29:18 13840 8192000000 10174879600 10571993088 1223932640 12.998 94 16 31 0 1233176160 5920800 3322720
23:30:35 13420 8192000000 10134543568 10534965248 1174719040 12.541 91 12 24 0 1181973584 4810736 2443808
23:31:49 5220 8192000000 10145748896 10553225216 1245140464 13.194 98 29 59 0 1257333808 5307408 6885936
23:32:55 6272 8192000000 10162643616 10559897600 1262895616 13.357 99 43 86 1 1283288736 9866096 10527024
23:34:07 35059 8192000000 10156064688 10571280384 1274462672 13.462 100 23 46 0 1285600016 5925024 5212320
23:36:00 4041 8192000000 10184418144 10595930112 1296143008 13.660 100 27 52 1 1307894992 5649488 6102496
23:37:04 8075 8192000000 10188738736 10607992832 1303415520 13.726 100 20 40 0 1313217200 4919856 4881824
23:38:13 6010 8192000000 10213894176 10630352896 1321352144 13.889 100 20 40 1 1334616800 8698496 4566160
23:39:25 6092 8192000000 10244927760 10658250752 1345850240 14.110 100 39 76 0 1359974832 5653888 8470704
23:40:35 30432 8192000000 10263299344 10681761792 1357841920 14.218 100 37 70 0 1373817056 7788720 8186416
23:42:14 28428 8192000000 10271534832 10687201280 1339623968 14.054 98 46 92 1 1361700608 11864704 10211936
23:43:54 15712 8192000000 10289119248 10710720512 1377856496 14.397 100 39 78 1 1406631280 19769440 9005344
23:45:23 13006 8192000000 10313116448 10730110976 1385586672 14.466 100 61 119 0 1422121152 22827056 13707424
23:46:46 8612 8192000000 10309105152 10732179456 1372533024 14.350 99 40 78 0 1405930144 24087360 9309760
23:48:02 19014 8192000000 10289337248 10723803136 1372683088 14.351 99 28 54 0 1403630832 24355504 6592240
23:49:29 2383 8192000000 10310553472 10728583168 1371697776 14.342 99 59 117 0 1412779600 27077856 14003968
23:50:42 6963 8192000000 10320362704 10734907392 1382881360 14.442 100 62 122 0 1426735472 29732384 14121728
23:51:57 6759 8192000000 10322377200 10733301760 1381712912 14.432 100 70 140 0 1429877728 32105792 16059024
23:53:07 4799 8192000000 10310281360 10712485888 1357913184 14.219 99 85 167 1 1413519744 35919696 19686864
23:54:19 2255 8192000000 10324746000 10734538752 1369365168 14.321 100 64 127 1 1428173152 42963664 15844320
23:55:34 3418 8192000000 10331454784 10741329920 1373624288 14.360 99 65 126 1 1429750640 40516896 15609456
23:56:42 2774 8192000000 10353834880 10761281536 1389873616 14.505 100 84 164 1 1458630544 48264576 20492352
23:57:51 2385 8192000000 10374242192 10781212672 1403596096 14.627 100 78 150 1 1474383248 51443120 19344032
23:59:00 1748 8192000000 10366225184 10770845696 1389777280 14.504 100 80 158 2 1469293264 59242560 20273424
00:00:07 3658 8192000000 10360878304 10776956928 1396562896 14.564 100 62 122 2 1468025696 55476960 15985840
00:01:20 7073 8192000000 10362720880 10788331520 1409592800 14.680 100 46 92 1 1468783808 46535520 12655488
00:02:40 1571 8192000000 10388440496 10808397824 1418233936 14.757 100 72 143 0 1487829328 51628624 17966768
00:03:48 9284 8192000000 10366173024 10799079424 1401774208 14.611 100 53 107 1 1478160832 62143952 14242672
00:05:01 25962 8192000000 10336143136 10771820544 1376584464 14.386 98 34 66 0 1424923184 39906000 8432720
00:06:32 27093 8192000000 10333071760 10774044672 1386511760 14.475 100 25 50 0 1438887376 45175232 7200384
00:08:34 40283 8192000000 10300697872 10751922176 1355470144 14.197 99 16 31 0 1410456720 50632304 4354272
00:10:19 99193 8192000000 10299397840 10745790464 1364904096 14.281 99 13 27 0 1379861120 11830064 3126960
00:13:02 86518 8192000000 10305247872 10743078912 1348791632 14.137 97 12 21 0 1363879232 13122720 1964880
00:15:30 68349 8192000000 10308803456 10747219968 1378783216 14.406 99 39 77 1 1397483616 9336224 9364176
00:17:40 44995 8192000000 10287543360 10744209408 1386405296 14.474 100 12 24 0 1398830384 9375136 3049952
00:19:51 115805 8192000000 10303922176 10772840448 1393317024 14.535 100 6 12 0 1411156192 16706832 1132336
00:23:07 42085 8192000000 10307064032 10764529664 1398004624 14.577 100 19 37 0 1411853744 9080160 4768960
00:24:50 43007 8192000000 10327824688 10774847488 1403937424 14.630 100 51 100 0 1429229584 13356448 11935712
00:26:35 53667 8192000000 10303815328 10766692352 1388955312 14.497 99 22 44 1 1403372416 9398192 5018912
00:29:25 30133 8192000000 10294572432 10756472832 1387378928 14.482 100 10 20 0 1400980368 10944640 2656800
00:31:03 68102 8192000000 10274907216 10745970688 1368450512 14.313 99 9 18 0 1380152000 9559440 2142048
00:33:24 52632 8192000000 10271177568 10735480832 1364207696 14.275 98 18 36 0 1377573456 9014560 4351200
00:36:14 52159 8192000000 10268342864 10726469632 1340324192 14.060 97 14 28 0 1351346352 8071808 2950352
00:38:07 50153 8192000000 10288513840 10747326464 1355137520 14.194 98 19 37 0 1366431168 7602912 3690736
00:39:57 70900 8192000000 10279141536 10736545792 1329414704 13.962 95 11 22 0 1347995072 16123616 2456752
00:42:08 76048 8192000000 10266807136 10706587648 1275306080 13.470 93 12 25 0 1286320208 8889056 2125072
00:44:24 26957 8192000000 10283873600 10732724224 1365039296 14.283 100 27 53 1 1383735056 13084512 5611248
00:45:52 93550 8192000000 10288670528 10758791168 1322296816 13.897 96 10 20 0 1348759232 23350800 3111616
00:48:26 90482 8192000000 10270519392 10714058752 1266659504 13.391 92 10 20 1 1287689104 19002736 2026864
00:50:57 86857 8192000000 10272521968 10721759232 1347030272 14.121 99 33 66 0 1367605616 12340624 8234720
00:53:30 30598 8192000000 10271768192 10716348416 1352806672 14.173 99 36 69 1 1373051696 12295568 7949456
00:55:02 50534 8192000000 10266213888 10725335040 1334687504 14.009 97 14 28 1 1354857872 16791136 3379232
00:56:53 27856 8192000000 10268976800 10717179904 1360710000 14.244 100 16 31 0 1371470704 7099152 3661552
00:58:21 48962 8192000000 10261380288 10719928320 1363234640 14.266 99 21 42 0 1377533264 9206960 5091664
01:00:19 65680 8192000000 10266807648 10731941888 1354551600 14.188 99 19 35 1 1368115728 9787968 3776160
01:02:40 62550 8192000000 10270125008 10730881024 1358013984 14.220 99 8 16 0 1369044736 8804176 2226576
01:04:43 40598 8192000000 10269005424 10730729472 1358237120 14.222 99 23 46 0 1377245232 13640256 5367856
01:07:02 49753 8192000000 10312648752 10770923520 1396434288 14.563 100 24 48 0 1413859360 12056256 5368816
01:08:58 69004 8192000000 10302659632 10780639232 1376249488 14.383 98 11 22 0 1398536544 19758832 2528224
01:11:42 48268 8192000000 10307045040 10754957312 1348721280 14.136 96 30 60 0 1369718864 14584032 6413552
01:13:34 52578 8192000000 10297167552 10763186176 1356727936 14.208 97 15 30 1 1374329648 14255888 3345824
01:15:34 33558 8192000000 10318754560 10768711680 1371072800 14.337 98 43 86 0 1396957632 16550864 9333968
01:17:10 95312 8192000000 10308300464 10769637376 1385619024 14.467 99 13 26 0 1402972768 14035488 3318256
01:19:49 54939 8192000000 10289943760 10763624448 1368564224 14.314 98 20 40 1 1385886144 13279776 4042144
01:21:45 59682 8192000000 10277248880 10740228096 1350984736 14.156 97 16 32 0 1364332720 9681504 3666480
01:23:45 45919 8192000000 10277883536 10738769920 1360474784 14.242 98 16 30 0 1380132112 16464032 3193296
01:25:36 59571 8192000000 10279410880 10742026240 1361433664 14.250 98 19 38 0 1376242224 10576800 4231760
01:27:39 48969 8192000000 10260764912 10723581952 1344220112 14.095 97 16 33 0 1359958032 11661168 4076752
01:29:28 82887 8192000000 10266249040 10738388992 1362621264 14.261 99 9 18 0 1378260032 13086192 2552576
01:31:52 33044 8192000000 10285218448 10735927296 1353044656 14.175 98 23 46 0 1365592176 7751248 4796272
01:33:31 53274 8192000000 10270136752 10728648704 1332514192 13.990 97 24 49 0 1349406288 11174128 5717968
01:35:36 41230 8192000000 10278346416 10729955328 1345509520 14.107 98 25 50 0 1361204400 10404000 5290880
01:37:18 64047 8192000000 10266553264 10723393536 1305740736 13.747 95 23 47 0 1322952048 11762544 5448768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-01-2023 01:39:37 to 09-01-2023 01:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 09-01-2023 01:39:53 to 09-01-2023 01:39:55.

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          245034
MON$OLDEST_ACTIVE               246716
MON$OLDEST_SNAPSHOT             246716
MON$NEXT_TRANSACTION            246716
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                       2048224
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:177: 
:178: mscs-test-161.budzdorov.ru    Mon Jan  9 01:38:38 2023
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Mon Jan  9 01:38:39 2023
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
--- end of diff output ---

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

09.01.23 01:39:56. 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
2023-01-09 01:39:59 No severe PSQL-related problems occured
09.01.23 01:40:00. End of report.