Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 22:39
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 16080560 kB
MemAvailable 31096420 kB
Buffers 0 kB
Cached 15620040 kB
SwapCached 2516 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-07-13 22:38:28
DTS_END                         2022-07-13 22:38:28.1510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44870
TRN_ID                          252086
ATT_ID                          712
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: 9489.60 18617 1138752
customer order: creation 186.14 300 22337
customer order: refuse 15.63 304 1876
order to supplier: creation 49.91 1181 5989
order to supplier: removal 3.45 102096 414
invoice (draft): creation 49.70 3051 5964
invoice (draft): removal 52.58 779 6310
invoice accept: apply 44.97 16634 5396
invoice accept: cancel 47.94 826 5753
customer reserve: creation 4083.62 168 490034
customer reserve: removal 4632.65 60 555918
realization accept: apply 91.43 152 10972
realization accept: cancel 90.68 106 10881
payment from customer: creation 64.89 100 7787
payment from customer: removal 5.96 65 715
payment to supplier: creation 53.43 122 6412
payment to supplier: removal 3.63 65 436
service: total inventory turnovers 5.15 970 618
service: total monetary turnovers 6.82 39 818
service: aggregate perf. data 0.98 18923 118
service: refresh index statistics 0.03 226406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-07-2022 22:39:35 to 13-07-2022 22:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5256
TEST_TIME 2 5998
TEST_TIME 3 6481
TEST_TIME 4 6215
TEST_TIME 5 6205
TEST_TIME 6 6071
TEST_TIME 7 5901
TEST_TIME 8 6130
TEST_TIME 9 6113
TEST_TIME 10 6287
TEST_TIME 11 6583
TEST_TIME 12 6400
TEST_TIME 13 6965
TEST_TIME 14 7195
TEST_TIME 15 7340
TEST_TIME 16 7399
TEST_TIME 17 7618
TEST_TIME 18 7689
TEST_TIME 19 7785
TEST_TIME 20 8072
TEST_TIME 21 8584
TEST_TIME 22 8694
TEST_TIME 23 8737
TEST_TIME 24 9289
TEST_TIME 25 9187
TEST_TIME 26 9321
TEST_TIME 27 9796
TEST_TIME 28 9928
TEST_TIME 29 10729
TEST_TIME 30 10581
TEST_TIME 31 10372
TEST_TIME 32 10202
TEST_TIME 33 10352
TEST_TIME 34 10485
TEST_TIME 35 10516
TEST_TIME 36 10500
TEST_TIME 37 10504
TEST_TIME 38 10376
TEST_TIME 39 10365
TEST_TIME 40 10573
TEST_TIME 41 10383
TEST_TIME 42 10228
TEST_TIME 43 10128
TEST_TIME 44 10030
TEST_TIME 45 10006
TEST_TIME 46 9939
TEST_TIME 47 9918
TEST_TIME 48 9897
TEST_TIME 49 9940
TEST_TIME 50 10059
TEST_TIME 51 9987
TEST_TIME 52 9881
TEST_TIME 53 9758
TEST_TIME 54 9690
TEST_TIME 55 9672
TEST_TIME 56 9648
TEST_TIME 57 9615
TEST_TIME 58 9556
TEST_TIME 59 9552
TEST_TIME 60 9681
TEST_TIME 61 9715
TEST_TIME 62 9675
TEST_TIME 63 9753
TEST_TIME 64 9820
TEST_TIME 65 9948
TEST_TIME 66 9871
TEST_TIME 67 9943
TEST_TIME 68 9914
TEST_TIME 69 9851
TEST_TIME 70 9954
TEST_TIME 71 10016
TEST_TIME 72 10031
TEST_TIME 73 9906
TEST_TIME 74 9825
TEST_TIME 75 9869
TEST_TIME 76 9839
TEST_TIME 77 9819
TEST_TIME 78 9731
TEST_TIME 79 9754
TEST_TIME 80 9723
TEST_TIME 81 9658
TEST_TIME 82 9636
TEST_TIME 83 9584
TEST_TIME 84 9528
TEST_TIME 85 9462
TEST_TIME 86 9472
TEST_TIME 87 9461
TEST_TIME 88 9492
TEST_TIME 89 9490
TEST_TIME 90 9539
TEST_TIME 91 9541
TEST_TIME 92 9562
TEST_TIME 93 9572
TEST_TIME 94 9604
TEST_TIME 95 9631
TEST_TIME 96 9650
TEST_TIME 97 9651
TEST_TIME 98 9669
TEST_TIME 99 9657
TEST_TIME 100 9743
TEST_TIME 101 9753
TEST_TIME 102 9702
TEST_TIME 103 9667
TEST_TIME 104 9630
TEST_TIME 105 9626
TEST_TIME 106 9611
TEST_TIME 107 9598
TEST_TIME 108 9563
TEST_TIME 109 9536
TEST_TIME 110 9475
TEST_TIME 111 9452
TEST_TIME 112 9468
TEST_TIME 113 9526
TEST_TIME 114 9488
TEST_TIME 115 9533
TEST_TIME 116 9516
TEST_TIME 117 9540
TEST_TIME 118 9578
TEST_TIME 119 9528
TEST_TIME 120 9493

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-07-2022 22:39:39 to 13-07-2022 22:39:42.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1590992 1590992 0 0.00 0 19510 6 0 0
doc_list_biud 1590991 1590991 0 0.00 0 30507 27 0 0
sp_add_invoice_to_stock 5478 5396 82 1.49 36 184736 16634 0 82
sp_cancel_adding_invoice 5753 5753 0 0.00 3 85392 826 0 0
sp_cancel_client_order 1876 1876 0 0.00 3 9552 304 0 0
sp_cancel_customer_reserve 555918 555918 0 0.00 0 23425 60 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 2 4414 65 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 2016 65 0 0
sp_cancel_supplier_invoice 6317 6310 7 0.11 1 14057 779 0 7
sp_cancel_supplier_order 414 414 0 0.00 6 1001229 102097 0 0
sp_cancel_write_off 10881 10881 0 0.00 1 14909 106 0 0
sp_client_order 22337 22337 0 0.00 3 22608 301 0 0
sp_customer_reserve 490034 490034 0 0.00 0 88513 169 0 0
sp_fill_shopping_cart 34288 34288 0 0.00 1 23072 37 0 0
sp_fill_shopping_cart_clo_res 490026 490026 0 0.00 0 15165 12 0 0
sp_get_clo_for_invoice 5386 5386 0 0.00 0 30260 243 0 0
sp_kill_cost_storno 17785 17785 0 0.00 0 15807 53 0 0
sp_kill_qstorno_ret_qs2qd 1129441 1129441 0 0.00 0 23420 33 0 0
sp_kill_qty_storno 597510 597510 0 0.00 0 30505 70 0 0
sp_lock_selected_doc 22449 22449 0 0.00 0 7526 5 0 0
sp_make_cost_storno 30557 30557 0 0.00 0 13258 44 0 0
sp_multiply_rows_for_pdistr 30557 30557 0 0.00 0 19505 27 0 0
sp_multiply_rows_for_qdistr 22337 22337 0 0.00 0 17479 125 0 0
sp_pay_from_customer 7787 7787 0 0.00 4 19514 100 0 0
sp_pay_to_supplier 6412 6412 0 0.00 4 12787 122 0 0
sp_payment_common 14199 14199 0 0.00 1 8392 22 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 9538 272 0 0
sp_qd_handle_on_invoice_upd_sts 11139 11139 0 0.00 0 30502 324 0 0
sp_qd_handle_on_reserve_upd_sts 10972 10972 0 0.00 0 9708 51 0 0
sp_reserve_write_off 10972 10972 0 0.00 6 9781 153 0 0
sp_supplier_invoice 5964 5964 0 0.00 5 82833 3052 0 0
sp_supplier_order 5989 5989 0 0.00 4 35836 1182 0 0
srv_aggregate_perf_data 118 118 0 0.00 2585 403040 18923 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1981 125366 41391 0 0
srv_find_qd_qs_mism 1086581 1086581 0 0.00 0 14766 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 41 27358 970 0 0
srv_make_money_saldo 818 818 0 0.00 0 12410 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 144141 364985 226407 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5396 5396 0 0.00 0 18723 144 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 2495 85 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 2978 12 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1512 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 1261 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 1571 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 2100 24 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22101 1622 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 2129 6 0 0
t$perf-norm:sp_client_order 22337 22337 0 0.00 0 5915 15 0 0
t$perf-norm:sp_customer_reserve 1080 1080 0 0.00 0 866 3 0 0
t$perf-norm:sp_pay_from_customer 7787 7787 0 0.00 0 3241 6 0 0
t$perf-norm:sp_pay_to_supplier 6412 6412 0 0.00 0 3523 9 0 0
t$perf-norm:sp_reserve_write_off 10972 10972 0 0.00 0 6309 11 0 0
t$perf-norm:sp_supplier_invoice 5964 5964 0 0.00 0 3782 14 0 0
t$perf-norm:sp_supplier_order 5989 5989 0 0.00 0 5721 15 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 419 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 374 8 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 935 17 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 2223 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10934 10770 164 1.49 0 5512 17 0 164
v_all_customers 40530 40530 0 0.00 0 75 0 0 0
v_all_suppliers 33104 33104 0 0.00 0 71 1 0 0
v_all_wares 44674 44674 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1439 8 0 0
v_cancel_client_order 3752 3752 0 0.00 0 4682 7 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 3449 7 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 2371 7 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1261 4 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 1344 16 0 14
v_cancel_supplier_order 828 828 0 0.00 0 1337 6 0 0
v_cancel_write_off 2142 2142 0 0.00 0 2118 15 0 0
v_max_id_clo_ord 5989 5989 0 0.00 0 1349 2 0 0
v_max_id_clo_res 1080 1080 0 0.00 0 2013 12 0 0
v_max_id_ord_sup 5962 5962 0 0.00 0 6991 4 0 0
v_max_non_paid_invoice 1811 1811 0 0.00 0 304 2 0 0
v_max_non_paid_realizn 4173 4173 0 0.00 0 5159 6 0 0
v_min_id_clo_ord 5989 5989 0 0.00 0 1747 4 0 0
v_min_id_clo_res 1080 1080 0 0.00 0 5356 15 0 0
v_min_id_ord_sup 5962 5962 0 0.00 0 7515 13 0 0
v_min_non_paid_invoice 6412 6412 0 0.00 0 6336 15 0 0
v_min_non_paid_realizn 7787 7787 0 0.00 0 5731 14 0 0
v_our_firm 11372 11372 0 0.00 0 18 1 0 0
v_reserve_write_off 21944 21944 0 0.00 0 4873 6 0 0
x_lock_depdocs_on_canc_invoice 11652 11652 0 0.00 0 15492 114 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 11545 323 0 0
x_make_qstorno_1000_1200 5989 5989 0 0.00 1 35828 1112 0 0
x_make_qstorno_1200_2000 5964 5964 0 0.00 2 82816 2906 0 0
x_make_qstorno_2100_3300 490034 490034 0 0.00 0 88509 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 22:39:43 to 13-07-2022 22:39:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 25329 8192000000 9906906384 10218487808 986449760 10.747 100 82 164 0 1014025664 6348688 21227216
20:11:03 20577 8192000000 9953177536 10273984512 1025855856 11.129 100 93 186 0 1058490544 8683008 23951680
20:12:29 21651 8192000000 9986295280 10318258176 1060878640 11.465 100 83 166 0 1091825776 9801216 21145920
20:13:56 8143 8192000000 10024807808 10372984832 1113793632 11.968 100 77 153 0 1142168144 10330064 18044448
20:15:07 6711 8192000000 10082749344 10441383936 1172514736 12.520 100 75 143 1 1200725104 11647488 16562880
20:16:16 2811 8192000000 10127520816 10494586880 1213701024 12.903 100 85 170 1 1246411344 13796048 18914272
20:17:22 2224 8192000000 10156175536 10531991552 1244987472 13.192 100 85 166 1 1277044592 13665424 18391696
20:18:25 1973 8192000000 10191111648 10572230656 1276505904 13.481 100 80 159 1 1306599888 12699680 17394304
20:19:29 4135 8192000000 10206179280 10597834752 1294191936 13.642 100 72 144 0 1322456336 12394192 15870208
20:20:33 4333 8192000000 10187219792 10594729984 1298762656 13.684 100 40 80 0 1317846544 10673040 8410848
20:21:40 3784 8192000000 10202848384 10606686208 1310254400 13.788 100 50 101 0 1328877664 6774640 11848624
20:22:44 6215 8192000000 10199263680 10614185984 1314392336 13.826 100 20 41 0 1324572224 5810016 4369872
20:23:51 60436 8192000000 10229452496 10635743232 1322196704 13.897 100 53 104 1 1344904576 10525408 12182464
20:25:53 4748 8192000000 10214939728 10637217792 1325596288 13.927 100 21 43 0 1336281424 6229632 4455504
20:27:00 10682 8192000000 10208993792 10624962560 1308508944 13.773 99 24 47 0 1319981072 6626560 4845568
20:28:11 14842 8192000000 10198547232 10617077760 1313268832 13.816 100 18 37 1 1322708288 5006752 4432704
20:29:30 17968 8192000000 10173990688 10574278656 1244477936 13.187 96 35 70 0 1259694672 7911072 7305664
20:30:49 3377 8192000000 10159463856 10556903424 1259173040 13.322 99 35 70 0 1273752080 6479856 8099184
20:31:53 9938 8192000000 10139154208 10538872832 1228135856 13.037 98 22 43 0 1243811680 10143552 5532272
20:33:07 56653 8192000000 10145888672 10551291904 1237032176 13.119 98 35 68 1 1250458400 5187280 8238944
20:35:05 5259 8192000000 10173557040 10586865664 1283486992 13.545 100 26 53 2 1296052832 6188384 6377456
20:36:11 8755 8192000000 10184121008 10596753408 1294108704 13.642 100 14 27 0 1303183120 5894576 3179840
20:37:25 62106 8192000000 10197842544 10613989376 1305689872 13.747 100 25 47 1 1315507008 4562896 5254240
20:39:32 4976 8192000000 10237745696 10644230144 1333491888 13.999 100 51 102 0 1353189952 8116400 11581664
20:40:43 12993 8192000000 10261134144 10675630080 1353707328 14.181 100 57 111 1 1375812320 9789584 12315408
20:41:59 4042 8192000000 10270797104 10683289600 1363000128 14.264 100 56 109 0 1386898480 11418256 12480096
20:43:19 51006 8192000000 10275989072 10703683584 1372820032 14.352 100 43 85 1 1397529808 14756016 9953760
20:45:15 12251 8192000000 10296327216 10717786112 1383216496 14.445 100 43 86 0 1407554112 14466432 9871184
20:46:30 8921 8192000000 10322713632 10737709056 1394850832 14.549 100 57 110 3 1429659648 22026432 12782384
20:47:45 7038 8192000000 10321781600 10737111040 1394478912 14.546 100 61 122 0 1431402096 21908272 15014912
20:49:02 5222 8192000000 10310222800 10724712448 1380334448 14.420 100 68 134 1 1422441184 25466576 16640160
20:50:15 7038 8192000000 10314267824 10735239168 1389315648 14.500 100 42 83 0 1427091776 26714080 11062048
20:51:30 9074 8192000000 10310482736 10737979392 1390254368 14.508 100 42 84 0 1425013536 24065952 10693216
20:52:44 7056 8192000000 10314385520 10737307648 1382392688 14.438 100 42 84 0 1417889232 24937696 10558848
20:53:55 3840 8192000000 10308338144 10723012608 1368362944 14.312 99 59 116 1 1414829968 32249120 14217904
20:55:05 1972 8192000000 10319358480 10725531648 1368271200 14.312 100 69 133 0 1419215184 34329248 16614736
20:56:12 2310 8192000000 10326899600 10733989888 1372635760 14.351 100 75 150 0 1430126928 38633904 18857264
20:57:19 2391 8192000000 10333390848 10743881728 1364310576 14.276 99 71 140 0 1425942512 43656544 17975392
20:58:28 2662 8192000000 10331085760 10732363776 1362924736 14.264 100 80 158 0 1427556784 44024096 20607952
20:59:36 3236 8192000000 10318369424 10734166016 1366092464 14.292 100 63 119 2 1424078464 42540032 15445968
21:00:48 10477 8192000000 10310831920 10732535808 1366504800 14.296 100 41 80 1 1416692256 39095504 11091952
21:02:01 13828 8192000000 10318148144 10746912768 1372061936 14.346 100 35 69 0 1426358720 44471328 9825456
21:03:20 34712 8192000000 10303678016 10733146112 1358013232 14.220 99 28 56 1 1405292432 39144304 8134896
21:04:58 28434 8192000000 10297839056 10741854208 1367883424 14.308 100 24 47 0 1414738496 40333424 6521648
21:06:29 47090 8192000000 10292757040 10734817280 1360612112 14.243 100 15 32 0 1399828688 34841472 4375104
21:08:16 57871 8192000000 10293519264 10731941888 1362523152 14.260 99 18 37 0 1373543472 7147216 3873104
21:10:16 48281 8192000000 10291424768 10737733632 1374885632 14.371 99 19 38 0 1396860528 17718096 4256800
21:12:07 60521 8192000000 10287469904 10729439232 1329953424 13.967 96 15 30 0 1343720944 10735936 3031584
21:14:12 106873 8192000000 10300037712 10754027520 1362056640 14.256 98 7 14 0 1381052032 17074144 1921248
21:17:00 19823 8192000000 10309700848 10752294912 1390748784 14.513 100 30 58 0 1409399568 11863264 6787520
21:18:31 115160 8192000000 10298556960 10748862464 1388396528 14.492 100 20 40 1 1406265232 12746544 5122160
21:21:39 99693 8192000000 10307823776 10747101184 1367749632 14.307 98 48 96 1 1387687344 9653184 10284528
21:24:20 31988 8192000000 10304570960 10757443584 1396370064 14.563 100 17 34 0 1411600208 11234336 3995808
21:25:57 36650 8192000000 10312864496 10773749760 1397241088 14.570 99 24 46 0 1419542368 16224576 6076704
21:27:45 89594 8192000000 10285745776 10752598016 1357434576 14.214 97 12 24 0 1378956128 18798336 2723216
21:30:16 57665 8192000000 10302488720 10759073792 1390863456 14.514 99 35 68 1 1405081280 6643504 7574320
21:32:16 72804 8192000000 10277054000 10751021056 1340744384 14.064 96 10 20 0 1353406272 10357488 2304400
21:34:44 37877 8192000000 10275177616 10739286016 1365423840 14.286 99 3 6 0 1372952608 6236032 1292736
21:36:28 45116 8192000000 10292244816 10751098880 1372971616 14.354 99 13 27 0 1391773568 15464880 3337072
21:38:17 62054 8192000000 10293305792 10748952576 1369955456 14.327 99 11 22 1 1385222624 12886704 2380464
21:40:20 28373 8192000000 10286947904 10722324480 1343215648 14.086 98 26 50 0 1356524896 7920240 5389008
21:41:51 35239 8192000000 10284371792 10728796160 1350915744 14.156 98 29 58 2 1374009760 16420208 6673808
21:43:32 65771 8192000000 10286183696 10710855680 1269400672 13.416 92 32 62 2 1289992544 13351680 7240192
21:45:39 27346 8192000000 10281763872 10719256576 1330443872 13.971 97 32 63 0 1347020784 9781952 6794960
21:47:09 26294 8192000000 10276204560 10707034112 1339065168 14.049 99 51 100 0 1365184096 15303808 10815120
21:48:38 24522 8192000000 10282411968 10718568448 1346247552 14.114 99 27 54 0 1364537776 12220256 6069968
21:50:03 125361 8192000000 10278034496 10717904896 1312536128 13.809 96 7 14 0 1328009792 14080336 1393328
21:53:23 45648 8192000000 10276025216 10731012096 1372117616 14.346 100 26 52 0 1387651888 9577504 5956768
21:55:09 31897 8192000000 10253476032 10708525056 1346336592 14.115 98 28 56 1 1363452784 10453072 6663120
21:56:41 57994 8192000000 10266481904 10740850688 1368745088 14.316 100 6 12 0 1384131920 13891168 1495664
21:59:26 65380 8192000000 10272886720 10726477824 1351923744 14.165 98 15 32 0 1363475376 7934256 3617376
22:01:45 45270 8192000000 10269281632 10714525696 1361095056 14.247 100 29 55 1 1373556768 6282368 6179344
22:03:30 77488 8192000000 10267872624 10739384320 1369863344 14.326 99 9 16 0 1379872464 8326928 1682192
22:05:50 72287 8192000000 10272370176 10736476160 1364099328 14.274 99 19 38 0 1376676000 8745760 3830912
22:08:06 49413 8192000000 10278167376 10739576832 1367873248 14.308 99 26 52 0 1384893248 11334880 5685120
22:10:07 34972 8192000000 10308497472 10762301440 1383236096 14.445 99 30 60 0 1399316864 9980896 6099872
22:11:42 34403 8192000000 10320167328 10759544832 1369816896 14.325 98 30 59 0 1387345792 10990624 6538272
22:13:17 73511 8192000000 10309060544 10755993600 1368381168 14.313 98 20 38 0 1382976176 10469664 4125344
22:15:33 17998 8192000000 10316701984 10764300288 1396796352 14.566 100 42 86 0 1420498800 14248752 9453696
22:16:59 29551 8192000000 10304194432 10743877632 1356736832 14.208 98 38 77 0 1375679840 10363392 8579616
22:18:39 52618 8192000000 10288806352 10734710784 1328071856 13.950 96 21 42 0 1346011536 13516912 4422768
22:20:36 84826 8192000000 10278400096 10729574400 1336548624 14.026 97 24 48 1 1354535728 12571792 5415312
22:23:24 19816 8192000000 10272452512 10736578560 1354353840 14.187 98 18 36 0 1373400992 14736832 4310320
22:24:44 78042 8192000000 10262866032 10728026112 1369520624 14.323 100 10 20 0 1381526384 9616384 2389376
22:27:13 56778 8192000000 10272316224 10730438656 1327282240 13.943 96 22 44 0 1351772368 19387792 5102336
22:29:10 92128 8192000000 10270041056 10737594368 1324005248 13.913 96 19 38 0 1350224368 21615616 4603504
22:31:42 55078 8192000000 10254884272 10707034112 1315158736 13.833 97 11 22 0 1328637808 10921600 2557472
22:33:41 49082 8192000000 10254344944 10705575936 1317928000 13.858 97 15 30 0 1332367680 11268432 3171248
22:35:30 58818 8192000000 10261463104 10735779840 1344782176 14.101 99 9 18 1 1365342112 18834608 1725328
22:38:06 34663 8192000000 9456352544 9776869376 594213888 6.763 44 15 30 0 608834784 11481248 3139648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-07-2022 22:39:49 to 13-07-2022 22:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-07-2022 22:40:05 to 13-07-2022 22:40:07.

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          250114
MON$OLDEST_ACTIVE               252331
MON$OLDEST_SNAPSHOT             252331
MON$NEXT_TRANSACTION            252331
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                       2032352
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:1044: 
:1045: mscs-test-161.budzdorov.ru    Wed Jul 13 22:38:52 2022
:1046:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1047: 
:1048: 
:1049: mscs-test-161.budzdorov.ru    Wed Jul 13 22:38:53 2022
:1050:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1051: 
--- end of diff output ---

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

13.07.22 22:40:08. 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-07-13 22:40:11 No severe PSQL-related problems occured
13.07.22 22:40:12. End of report.