Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2022 16: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 16605416 kB
MemAvailable 31324988 kB
Buffers 0 kB
Cached 15108944 kB
SwapCached 1204 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-09 16:38:03
DTS_END                         2022-01-09 16:38:03.1550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57738
TRN_ID                          233867
ATT_ID                          591
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: 9071.30 22060 1088556
customer order: creation 166.15 366 19938
customer order: refuse 14.23 388 1708
order to supplier: creation 43.94 1276 5273
order to supplier: removal 3.34 94966 401
invoice (draft): creation 44.12 3491 5294
invoice (draft): removal 52.01 660 6241
invoice accept: apply 40.54 19239 4865
invoice accept: cancel 47.37 766 5684
customer reserve: creation 3745.73 190 449488
customer reserve: removal 4623.68 54 554842
realization accept: apply 81.78 193 9813
realization accept: cancel 84.80 122 10176
payment from customer: creation 57.10 136 6852
payment from customer: removal 5.36 95 643
payment to supplier: creation 46.54 140 5585
payment to supplier: removal 3.23 109 388
service: total inventory turnovers 4.20 1283 504
service: total monetary turnovers 6.35 36 762
service: aggregate perf. data 0.79 17643 95
service: refresh index statistics 0.03 300041 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 16:39:51 to 09-01-2022 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4215
TEST_TIME 2 3928
TEST_TIME 3 3344
TEST_TIME 4 3991
TEST_TIME 5 4730
TEST_TIME 6 5694
TEST_TIME 7 5741
TEST_TIME 8 6325
TEST_TIME 9 6458
TEST_TIME 10 6438
TEST_TIME 11 6722
TEST_TIME 12 6731
TEST_TIME 13 6761
TEST_TIME 14 6758
TEST_TIME 15 6955
TEST_TIME 16 7579
TEST_TIME 17 7719
TEST_TIME 18 8026
TEST_TIME 19 8408
TEST_TIME 20 8755
TEST_TIME 21 8620
TEST_TIME 22 8730
TEST_TIME 23 8877
TEST_TIME 24 9104
TEST_TIME 25 9158
TEST_TIME 26 9382
TEST_TIME 27 9610
TEST_TIME 28 9667
TEST_TIME 29 9784
TEST_TIME 30 10297
TEST_TIME 31 10237
TEST_TIME 32 10102
TEST_TIME 33 9917
TEST_TIME 34 9916
TEST_TIME 35 9830
TEST_TIME 36 9668
TEST_TIME 37 9706
TEST_TIME 38 9520
TEST_TIME 39 9394
TEST_TIME 40 9632
TEST_TIME 41 9549
TEST_TIME 42 9416
TEST_TIME 43 9321
TEST_TIME 44 9529
TEST_TIME 45 9340
TEST_TIME 46 9324
TEST_TIME 47 9248
TEST_TIME 48 9189
TEST_TIME 49 9130
TEST_TIME 50 9074
TEST_TIME 51 9030
TEST_TIME 52 9044
TEST_TIME 53 9010
TEST_TIME 54 8942
TEST_TIME 55 9005
TEST_TIME 56 8937
TEST_TIME 57 9119
TEST_TIME 58 9159
TEST_TIME 59 9208
TEST_TIME 60 9291
TEST_TIME 61 9307
TEST_TIME 62 9421
TEST_TIME 63 9438
TEST_TIME 64 9386
TEST_TIME 65 9401
TEST_TIME 66 9416
TEST_TIME 67 9489
TEST_TIME 68 9445
TEST_TIME 69 9343
TEST_TIME 70 9366
TEST_TIME 71 9312
TEST_TIME 72 9248
TEST_TIME 73 9231
TEST_TIME 74 9266
TEST_TIME 75 9203
TEST_TIME 76 9148
TEST_TIME 77 9137
TEST_TIME 78 9100
TEST_TIME 79 9049
TEST_TIME 80 9087
TEST_TIME 81 9049
TEST_TIME 82 9001
TEST_TIME 83 9013
TEST_TIME 84 9036
TEST_TIME 85 9026
TEST_TIME 86 9010
TEST_TIME 87 8985
TEST_TIME 88 9166
TEST_TIME 89 9175
TEST_TIME 90 9136
TEST_TIME 91 9217
TEST_TIME 92 9252
TEST_TIME 93 9266
TEST_TIME 94 9228
TEST_TIME 95 9274
TEST_TIME 96 9359
TEST_TIME 97 9274
TEST_TIME 98 9191
TEST_TIME 99 9211
TEST_TIME 100 9184
TEST_TIME 101 9143
TEST_TIME 102 9093
TEST_TIME 103 9058
TEST_TIME 104 9073
TEST_TIME 105 9039
TEST_TIME 106 8999
TEST_TIME 107 8994
TEST_TIME 108 9016
TEST_TIME 109 9052
TEST_TIME 110 9054
TEST_TIME 111 9048
TEST_TIME 112 9060
TEST_TIME 113 9137
TEST_TIME 114 9109
TEST_TIME 115 9112
TEST_TIME 116 9123
TEST_TIME 117 9141
TEST_TIME 118 9158
TEST_TIME 119 9148
TEST_TIME 120 9073

Performance per minute, chart


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

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 1504521 1504521 0 0.00 0 33149 7 0 0
doc_list_biud 1504520 1504520 0 0.00 0 37111 25 0 0
sp_add_invoice_to_stock 4968 4865 103 2.07 36 200028 19239 0 103
sp_cancel_adding_invoice 5684 5684 0 0.00 5 81902 766 0 0
sp_cancel_client_order 1708 1708 0 0.00 3 11486 388 0 0
sp_cancel_customer_reserve 554842 554842 0 0.00 1 28781 54 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 2 6866 96 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 2 6136 110 0 0
sp_cancel_supplier_invoice 6245 6241 4 0.06 2 12762 660 0 4
sp_cancel_supplier_order 401 401 0 0.00 7 956741 94967 0 0
sp_cancel_write_off 10176 10176 0 0.00 1 10015 123 0 0
sp_client_order 19938 19938 0 0.00 3 18364 366 0 0
sp_customer_reserve 449488 449488 0 0.00 0 70647 191 0 0
sp_fill_shopping_cart 30496 30496 0 0.00 1 15325 44 0 0
sp_fill_shopping_cart_clo_res 449484 449484 0 0.00 0 19970 15 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 29308 332 0 0
sp_kill_cost_storno 16891 16891 0 0.00 0 9961 62 0 0
sp_kill_qstorno_ret_qs2qd 1126502 1126502 0 0.00 0 28675 29 0 0
sp_kill_qty_storno 593726 593726 0 0.00 0 37108 63 0 0
sp_lock_selected_doc 20234 20234 0 0.00 0 8089 6 0 0
sp_make_cost_storno 27111 27111 0 0.00 0 22409 58 0 0
sp_multiply_rows_for_pdistr 27110 27110 0 0.00 0 22778 36 0 0
sp_multiply_rows_for_qdistr 19938 19938 0 0.00 0 10997 158 0 0
sp_pay_from_customer 6852 6852 0 0.00 4 22964 136 0 0
sp_pay_to_supplier 5585 5585 0 0.00 5 21984 141 0 0
sp_payment_common 12436 12436 0 0.00 1 22939 43 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 11481 355 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 37103 319 0 0
sp_qd_handle_on_reserve_upd_sts 9813 9813 0 0.00 0 20032 57 0 0
sp_reserve_write_off 9813 9813 0 0.00 6 22908 194 0 0
sp_supplier_invoice 5294 5294 0 0.00 6 112239 3491 0 0
sp_supplier_order 5273 5273 0 0.00 5 36929 1277 0 0
srv_aggregate_perf_data 95 95 0 0.00 2125 128466 17643 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2065 141459 50075 0 0
srv_find_qd_qs_mism 1042395 1042395 0 0.00 0 22679 6 0 0
srv_make_invnt_saldo 504 504 0 0.00 49 68595 1283 0 0
srv_make_money_saldo 762 762 0 0.00 0 8090 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 216549 386536 300042 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 0 17984 185 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 10634 160 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 15656 22 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1602 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1764 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 848 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 1539 28 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 53063 1727 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2356 8 0 0
t$perf-norm:sp_client_order 19938 19938 0 0.00 0 14245 23 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 5563 26 0 0
t$perf-norm:sp_pay_from_customer 6852 6852 0 0.00 0 5565 10 0 0
t$perf-norm:sp_pay_to_supplier 5585 5585 0 0.00 0 5179 11 0 0
t$perf-norm:sp_reserve_write_off 9813 9813 0 0.00 0 9064 13 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 8427 26 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 11700 21 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 296 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 8108 137 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 6980 39 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 599 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9928 9722 206 2.07 0 10286 24 0 206
v_all_customers 36254 36254 0 0.00 0 23 1 0 0
v_all_suppliers 28829 28829 0 0.00 0 19 1 0 0
v_all_wares 39876 39876 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1628 8 0 0
v_cancel_client_order 3416 3416 0 0.00 0 3613 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 2108 6 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 560 3 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 5097 10 0 0
v_cancel_supplier_invoice 764 756 8 1.04 0 1742 17 0 8
v_cancel_supplier_order 802 802 0 0.00 0 1969 8 0 0
v_cancel_write_off 2006 2006 0 0.00 0 7149 20 0 0
v_max_id_clo_ord 5273 5273 0 0.00 0 7227 4 0 0
v_max_id_clo_res 980 980 0 0.00 0 2462 12 0 0
v_max_id_ord_sup 5284 5284 0 0.00 0 6214 4 0 0
v_max_non_paid_invoice 1727 1727 0 0.00 0 1534 4 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 22932 11 0 0
v_min_id_clo_ord 5273 5273 0 0.00 0 4831 8 0 0
v_min_id_clo_res 980 980 0 0.00 0 1882 8 0 0
v_min_id_ord_sup 5284 5284 0 0.00 0 9068 5 0 0
v_min_non_paid_invoice 5584 5584 0 0.00 0 20156 27 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 22645 37 0 0
v_our_firm 9942 9942 0 0.00 0 31 1 0 0
v_reserve_write_off 19625 19625 0 0.00 0 10138 6 0 0
x_lock_depdocs_on_canc_invoice 11547 11547 0 0.00 0 16965 120 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 10371 425 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 1 36877 1183 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 3 112225 3334 0 0
x_make_qstorno_2100_3300 449488 449488 0 0.00 0 70644 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 16:39:59 to 09-01-2022 16:40:03.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:06 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:08 25537 8192000000 9879660064 10190217216 958602960 10.475 100 85 170 0 986983536 5568000 22812576
14:10:38 20565 8192000000 9947229376 10270785536 1029815824 11.167 100 87 174 0 1059744080 7744048 22184208
14:12:02 18605 8192000000 9976923552 10306236416 1061334512 11.469 100 90 181 1 1093484176 9466112 22683552
14:13:23 11860 8192000000 10018303584 10368299008 1110459200 11.937 100 73 146 0 1138117616 9926992 17731424
14:14:37 13321 8192000000 10092163072 10449756160 1168449424 12.482 100 83 164 0 1199207104 11615168 19142512
14:15:55 6549 8192000000 10130205184 10499772416 1216676816 12.931 100 86 173 1 1249060048 13124976 19258256
14:17:03 3781 8192000000 10151952432 10527698944 1241143920 13.157 100 80 160 0 1272879680 14343840 17391920
14:18:08 2009 8192000000 10186973088 10569474048 1271999072 13.440 100 94 188 2 1306152560 13607392 20546096
14:19:12 2708 8192000000 10219209616 10606129152 1298867808 13.685 100 83 167 1 1331326416 13252352 19206256
14:20:15 10715 8192000000 10191461968 10599657472 1303560880 13.728 100 25 50 0 1316481632 6374608 6546144
14:21:28 9559 8192000000 10197901808 10612121600 1308636640 13.774 100 28 57 0 1321629072 6541136 6451296
14:22:40 10737 8192000000 10202218944 10620186624 1315424320 13.835 100 25 50 0 1327332032 5855408 6052304
14:23:51 11318 8192000000 10213358208 10631065600 1322321744 13.898 100 29 53 2 1339541584 10657824 6562016
14:25:03 16060 8192000000 10221334512 10642542592 1329934272 13.967 100 30 59 3 1343048000 5534368 7579360
14:26:20 3948 8192000000 10225999440 10642001920 1318363312 13.862 99 38 76 1 1334611696 8199296 8049088
14:27:25 8143 8192000000 10203648544 10620723200 1290343824 13.607 97 27 54 1 1303082976 6279424 6459728
14:28:39 8020 8192000000 10199290960 10608619520 1259917632 13.329 96 20 41 2 1269547664 4973328 4656704
14:29:48 24841 8192000000 10175644592 10589143040 1228804336 13.043 94 31 61 0 1241687024 5356192 7526496
14:31:15 5864 8192000000 10137339056 10538266624 1228389248 13.039 98 30 60 0 1242193392 6187856 7616288
14:32:23 8299 8192000000 10118380592 10514485248 1208568960 12.856 98 39 78 0 1224460368 6357968 9533440
14:33:32 30685 8192000000 10160199280 10572136448 1258292672 13.314 99 27 54 1 1274518848 9557936 6668240
14:35:21 9405 8192000000 10172556784 10580865024 1276651328 13.482 100 32 64 0 1294682368 10727952 7303088
14:36:34 8432 8192000000 10190966224 10595786752 1289331984 13.598 100 31 62 1 1302324944 5722368 7270592
14:37:43 12281 8192000000 10190606400 10601684992 1297703072 13.674 100 20 40 1 1308125552 5280688 5141792
14:39:04 96666 8192000000 10239561824 10655903744 1336625440 14.027 100 38 76 1 1355511680 10257200 8629040
14:41:53 97298 8192000000 10268617712 10694619136 1358811920 14.227 100 38 76 0 1380437936 12871776 8754240
14:44:36 14711 8192000000 10279064912 10701914112 1369828512 14.326 100 48 94 3 1389523856 9907968 9787376
14:45:54 9947 8192000000 10305269936 10722717696 1372565264 14.350 99 59 118 2 1402525808 16547488 13413056
14:47:07 13579 8192000000 10312404928 10736701440 1393736016 14.539 100 57 113 1 1422292928 14282928 14273984
14:48:28 9378 8192000000 10323312544 10746638336 1402650368 14.619 100 68 136 1 1436217040 18005568 15561104
14:49:43 11744 8192000000 10331167536 10749673472 1404775008 14.637 100 53 102 1 1432474176 15093232 12605936
14:51:00 6493 8192000000 10356744800 10775920640 1419492304 14.768 100 80 160 1 1465820688 26446848 19881536
14:52:13 4532 8192000000 10362400480 10777915392 1419211376 14.766 100 74 143 3 1465183600 28379152 17593072
14:53:24 3320 8192000000 10343173296 10763583488 1400762544 14.602 100 70 140 1 1449539472 30766960 18009968
14:54:34 5664 8192000000 10343410336 10763796480 1385320976 14.464 99 72 144 3 1440277360 36681840 18274544
14:55:44 16335 8192000000 10311875200 10725355520 1355465632 14.197 99 66 132 0 1406584720 34460320 16658768
14:57:10 3956 8192000000 10324382720 10735276032 1375561440 14.377 100 74 148 0 1427155968 33548656 18045872
14:58:16 7606 8192000000 10302529280 10718760960 1361023680 14.247 99 52 104 4 1405359968 30847632 13488656
14:59:36 2064 8192000000 10303090256 10703962112 1344320512 14.096 99 89 176 1 1400453808 32925424 23207872
15:00:43 6408 8192000000 10304238304 10718248960 1358322192 14.222 100 57 114 1 1409767968 36735312 14710464
15:01:53 27730 8192000000 10275406560 10704822272 1340809456 14.065 100 19 38 0 1382955920 36673104 5473360
15:03:57 19957 8192000000 10273945648 10705670144 1330981488 13.976 99 20 40 1 1372698512 36101760 5615264
15:05:27 23867 8192000000 10256876112 10693730304 1321872272 13.894 99 16 32 1 1361339184 34903904 4563008
15:06:52 6109 8192000000 10276431792 10701193216 1344549168 14.098 100 37 74 0 1378832464 25000064 9283232
15:08:05 33693 8192000000 10265356752 10714857472 1338624352 14.045 99 9 18 1 1367223840 26624816 1974672
15:09:40 17834 8192000000 10292340352 10706706432 1365508928 14.287 100 51 98 0 1382184240 5805696 10869616
15:10:59 141459 8192000000 10262030912 10718789632 1346282352 14.114 98 24 48 0 1364549632 12844432 5422848
15:15:05 44713 8192000000 10276705504 10730885120 1368684416 14.315 99 18 36 0 1384663264 11867808 4111040
15:16:57 65561 8192000000 10301142432 10750423040 1394744192 14.548 100 43 88 0 1425285136 20278080 10262864
15:19:04 40114 8192000000 10273219552 10729127936 1357479376 14.215 98 6 12 0 1365230128 6571712 1179040
15:20:45 67574 8192000000 10301670528 10753437696 1395747056 14.557 100 24 48 1 1414269952 12767536 5755360
15:22:53 76067 8192000000 10284330576 10741682176 1378553056 14.404 99 9 18 0 1392437216 11612640 2271520
15:25:38 52490 8192000000 10293018912 10749267968 1395469696 14.555 100 22 44 0 1410871392 10273936 5127760
15:27:36 91020 8192000000 10294682320 10764779520 1396461728 14.563 100 9 18 0 1409728896 11214320 2052848
15:30:18 51918 8192000000 10290729248 10761908224 1394536736 14.546 100 14 28 1 1408512272 10522992 3452544
15:32:26 81986 8192000000 10303196656 10774360064 1404611696 14.636 100 16 32 1 1424743440 16656288 3475456
15:35:04 62130 8192000000 10311552960 10777841664 1406660960 14.654 100 9 18 0 1421077472 12563536 1852976
15:37:07 97039 8192000000 10302280960 10764967936 1375619616 14.377 98 8 16 0 1393322544 16233360 1469568
15:40:12 109138 8192000000 10297188976 10739683328 1336034848 14.022 96 7 14 0 1351302096 13883568 1383680
15:43:05 23072 8192000000 10298503360 10735505408 1367128320 14.301 99 52 104 1 1391467776 13358400 10981056
15:44:30 48509 8192000000 10286772560 10730573824 1332178704 13.987 97 32 64 1 1356301424 17183328 6939392
15:46:19 90832 8192000000 10266497184 10703163392 1312364544 13.808 96 17 34 1 1325622880 9328064 3930272
15:48:50 63568 8192000000 10260525600 10697056256 1342731120 14.082 98 36 72 1 1363767472 12476304 8560048
15:50:56 32762 8192000000 10228758480 10665312256 1273783776 13.456 93 31 62 0 1290900800 9833968 7283056
15:52:29 52620 8192000000 10236624560 10697760768 1339116176 14.049 100 5 10 0 1354826368 14536048 1174144
15:54:22 59037 8192000000 10237095408 10679848960 1295129840 13.651 96 14 27 1 1311044864 12931824 2983200
15:57:11 68708 8192000000 10244572672 10707845120 1336528352 14.026 99 11 22 0 1357771168 18812496 2430320
15:59:26 69419 8192000000 10249031120 10705854464 1347439056 14.124 99 3 6 0 1356015600 7430912 1145632
16:01:36 90085 8192000000 10251322816 10704826368 1298756928 13.684 95 14 28 0 1317629536 15643680 3228928
16:04:09 27131 8192000000 10265979696 10709467136 1322804880 13.902 97 20 39 1 1340181968 12548384 4828704
16:05:43 79250 8192000000 10273497968 10735386624 1315122912 13.833 96 18 36 3 1347125424 27849824 4152688
16:08:25 59053 8192000000 10293234800 10733162496 1362479184 14.260 99 37 72 1 1382123808 11805296 7839328
16:11:01 117163 8192000000 10301666976 10745241600 1364912176 14.281 98 11 22 0 1378755232 11483536 2359520
16:14:01 86374 8192000000 10296920560 10767073280 1390273856 14.508 100 7 14 0 1409575696 17799696 1502144
16:16:58 56606 8192000000 10268223760 10721665024 1347872064 14.128 97 13 26 0 1363692640 12887600 2932976
16:19:13 45406 8192000000 10274278736 10733158400 1371291872 14.339 99 13 27 0 1385099136 11116176 2691088
16:20:59 67178 8192000000 10290547632 10749542400 1387731904 14.486 100 12 24 0 1401719024 11524784 2462336
16:23:08 64676 8192000000 10276573200 10726580224 1354897808 14.192 98 14 28 0 1366213376 7972816 3342752
16:25:15 56907 8192000000 10287518064 10744147968 1378656864 14.405 100 12 24 1 1398141520 16675280 2809376
16:28:02 52545 8192000000 10292116544 10748456960 1378775152 14.406 99 23 46 1 1397451472 13023696 5652624
16:29:54 84020 8192000000 10297091200 10763227136 1382828832 14.442 99 16 32 0 1404132992 17158944 4145216
16:32:19 14903 8192000000 10293896080 10736959488 1388469152 14.492 100 38 75 1 1405054160 7642400 8942608
16:33:41 50540 8192000000 10293026976 10733678592 1347961872 14.129 97 22 44 0 1361140160 8485696 4692592
16:35:45 123860 8192000000 10266529152 10715852800 1266390208 13.389 92 13 26 0 1287057728 17959616 2707904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-01-2022 16:40:05 to 09-01-2022 16:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2022 16:40:20 to 09-01-2022 16:40:22.

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          233274
MON$OLDEST_ACTIVE               233984
MON$OLDEST_SNAPSHOT             233984
MON$NEXT_TRANSACTION            233984
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                       2021952
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:690: 
:691: mscs-test-161.budzdorov.ru    Sun Jan  9 16:38:05 2022
:692:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:693: 
:694: 
:695: mscs-test-161.budzdorov.ru    Sun Jan  9 16:38:17 2022
:696:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:697: 
:698: 
:699: mscs-test-161.budzdorov.ru    Sun Jan  9 16:38:17 2022
:700:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:701: 
--- end of diff output ---

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

09.01.22 16:40:23. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-09 16:40:26 No severe PSQL-related problems occured
09.01.22 16:40:27. End of report.