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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-01 01:38:29
DTS_END                         2022-10-01 01:38:29.1150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57020
TRN_ID                          252599
ATT_ID                          659
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: 9939.58 19307 1192750
customer order: creation 184.78 325 22174
customer order: refuse 15.65 303 1878
order to supplier: creation 49.28 1192 5913
order to supplier: removal 3.66 102210 439
invoice (draft): creation 48.00 2952 5760
invoice (draft): removal 56.80 792 6816
invoice accept: apply 44.58 17109 5350
invoice accept: cancel 51.08 724 6130
customer reserve: creation 4137.65 169 496518
customer reserve: removal 5037.58 57 604509
realization accept: apply 88.32 171 10598
realization accept: cancel 88.24 116 10589
payment from customer: creation 61.05 110 7326
payment from customer: removal 5.87 91 704
payment to supplier: creation 51.13 123 6136
payment to supplier: removal 3.33 63 400
service: total inventory turnovers 4.93 1029 591
service: total monetary turnovers 6.66 30 799
service: aggregate perf. data 0.97 18909 116
service: refresh index statistics 0.03 239669 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2022 01:39:19 to 01-10-2022 01:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4016
TEST_TIME 2 3629
TEST_TIME 3 4443
TEST_TIME 4 4169
TEST_TIME 5 4441
TEST_TIME 6 5314
TEST_TIME 7 5095
TEST_TIME 8 5038
TEST_TIME 9 5106
TEST_TIME 10 5164
TEST_TIME 11 5223
TEST_TIME 12 5808
TEST_TIME 13 5837
TEST_TIME 14 6313
TEST_TIME 15 6933
TEST_TIME 16 7085
TEST_TIME 17 7289
TEST_TIME 18 7757
TEST_TIME 19 8221
TEST_TIME 20 8052
TEST_TIME 21 8192
TEST_TIME 22 8588
TEST_TIME 23 8444
TEST_TIME 24 9007
TEST_TIME 25 8957
TEST_TIME 26 9264
TEST_TIME 27 9269
TEST_TIME 28 9168
TEST_TIME 29 9524
TEST_TIME 30 9770
TEST_TIME 31 11126
TEST_TIME 32 10911
TEST_TIME 33 10954
TEST_TIME 34 11006
TEST_TIME 35 10844
TEST_TIME 36 10741
TEST_TIME 37 10576
TEST_TIME 38 10520
TEST_TIME 39 10532
TEST_TIME 40 10511
TEST_TIME 41 10499
TEST_TIME 42 10379
TEST_TIME 43 10339
TEST_TIME 44 10343
TEST_TIME 45 10286
TEST_TIME 46 10247
TEST_TIME 47 10339
TEST_TIME 48 10353
TEST_TIME 49 10240
TEST_TIME 50 10191
TEST_TIME 51 10181
TEST_TIME 52 10154
TEST_TIME 53 10101
TEST_TIME 54 10039
TEST_TIME 55 10010
TEST_TIME 56 9918
TEST_TIME 57 9910
TEST_TIME 58 9911
TEST_TIME 59 9922
TEST_TIME 60 9903
TEST_TIME 61 10004
TEST_TIME 62 10063
TEST_TIME 63 10077
TEST_TIME 64 10105
TEST_TIME 65 10051
TEST_TIME 66 10101
TEST_TIME 67 10163
TEST_TIME 68 10199
TEST_TIME 69 10162
TEST_TIME 70 10182
TEST_TIME 71 10184
TEST_TIME 72 10275
TEST_TIME 73 10167
TEST_TIME 74 10123
TEST_TIME 75 10094
TEST_TIME 76 10055
TEST_TIME 77 10018
TEST_TIME 78 10010
TEST_TIME 79 9927
TEST_TIME 80 9907
TEST_TIME 81 9801
TEST_TIME 82 9932
TEST_TIME 83 9952
TEST_TIME 84 9927
TEST_TIME 85 9953
TEST_TIME 86 9952
TEST_TIME 87 9959
TEST_TIME 88 9998
TEST_TIME 89 9978
TEST_TIME 90 10031
TEST_TIME 91 10013
TEST_TIME 92 10074
TEST_TIME 93 10097
TEST_TIME 94 10116
TEST_TIME 95 10148
TEST_TIME 96 10229
TEST_TIME 97 10131
TEST_TIME 98 10140
TEST_TIME 99 10092
TEST_TIME 100 10022
TEST_TIME 101 10029
TEST_TIME 102 10000
TEST_TIME 103 9978
TEST_TIME 104 9933
TEST_TIME 105 9914
TEST_TIME 106 9901
TEST_TIME 107 9858
TEST_TIME 108 9846
TEST_TIME 109 9799
TEST_TIME 110 9881
TEST_TIME 111 9856
TEST_TIME 112 9868
TEST_TIME 113 9830
TEST_TIME 114 9850
TEST_TIME 115 9886
TEST_TIME 116 9925
TEST_TIME 117 9902
TEST_TIME 118 9923
TEST_TIME 119 9901
TEST_TIME 120 9940

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2022 01:39:23 to 01-10-2022 01:39:26.

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 1651880 1651880 0 0.00 0 32078 6 0 0
doc_list_biud 1651879 1651879 0 0.00 0 26034 27 0 0
sp_add_invoice_to_stock 5488 5350 138 2.51 64 139567 17109 0 138
sp_cancel_adding_invoice 6130 6130 0 0.00 5 62115 725 0 0
sp_cancel_client_order 1878 1878 0 0.00 4 7806 304 0 0
sp_cancel_customer_reserve 604509 604509 0 0.00 0 21823 58 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 12334 92 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 2258 63 0 0
sp_cancel_supplier_invoice 6823 6816 7 0.10 1 17706 793 0 7
sp_cancel_supplier_order 439 439 0 0.00 7 988813 102211 0 0
sp_cancel_write_off 10589 10589 0 0.00 1 25709 116 0 0
sp_client_order 22174 22174 0 0.00 3 18627 325 0 0
sp_customer_reserve 496518 496518 0 0.00 0 66436 169 0 0
sp_fill_shopping_cart 33838 33838 0 0.00 1 11860 32 0 0
sp_fill_shopping_cart_clo_res 496517 496517 0 0.00 0 18315 13 0 0
sp_get_clo_for_invoice 5349 5349 0 0.00 0 28469 256 0 0
sp_kill_cost_storno 17823 17823 0 0.00 0 5813 53 0 0
sp_kill_qstorno_ret_qs2qd 1226862 1226862 0 0.00 0 25657 31 0 0
sp_kill_qty_storno 646308 646308 0 0.00 0 26033 68 0 0
sp_lock_selected_doc 22005 22005 0 0.00 0 8788 4 0 0
sp_make_cost_storno 29409 29409 0 0.00 0 22856 51 0 0
sp_multiply_rows_for_pdistr 29409 29409 0 0.00 0 9165 27 0 0
sp_multiply_rows_for_qdistr 22174 22174 0 0.00 0 18617 137 0 0
sp_pay_from_customer 7326 7326 0 0.00 4 13479 111 0 0
sp_pay_to_supplier 6136 6136 0 0.00 4 22906 124 0 0
sp_payment_common 13461 13461 0 0.00 1 11736 32 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 5650 278 0 0
sp_qd_handle_on_invoice_upd_sts 11479 11479 0 0.00 0 26032 330 0 0
sp_qd_handle_on_reserve_upd_sts 10598 10598 0 0.00 0 13349 57 0 0
sp_reserve_write_off 10598 10598 0 0.00 6 13446 172 0 0
sp_supplier_invoice 5760 5760 0 0.00 5 77296 2953 0 0
sp_supplier_order 5913 5913 0 0.00 4 36310 1192 0 0
srv_aggregate_perf_data 116 116 0 0.00 2386 461964 18910 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3605 121074 46419 0 0
srv_find_qd_qs_mism 1141816 1141816 0 0.00 0 32076 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 49 30727 1029 0 0
srv_make_money_saldo 799 799 0 0.00 1 1586 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 168041 317775 239669 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 5350 5350 0 0.00 0 9932 167 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 4386 106 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 3357 13 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 1036 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 2960 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 303 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2450 20 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 36879 1731 0 0
t$perf-norm:sp_cancel_write_off 1094 1094 0 0.00 0 2295 12 0 0
t$perf-norm:sp_client_order 22174 22174 0 0.00 0 4919 15 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 6686 17 0 0
t$perf-norm:sp_pay_from_customer 7326 7326 0 0.00 0 19268 17 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 3946 9 0 0
t$perf-norm:sp_reserve_write_off 10598 10598 0 0.00 0 6394 12 0 0
t$perf-norm:sp_supplier_invoice 5760 5760 0 0.00 0 9082 24 0 0
t$perf-norm:sp_supplier_order 5913 5913 0 0.00 0 11877 22 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 517 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 66 2 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 1384 24 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 1188 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 10970 10694 276 2.51 0 6240 20 0 276
v_all_customers 40182 40182 0 0.00 0 169 1 0 0
v_all_suppliers 32630 32630 0 0.00 0 28 1 0 0
v_all_wares 44348 44348 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2723 10 0 0
v_cancel_client_order 3756 3756 0 0.00 0 1272 4 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 12294 15 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 2111 6 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2245 9 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 13308 33 0 14
v_cancel_supplier_order 878 878 0 0.00 0 7292 12 0 0
v_cancel_write_off 2188 2188 0 0.00 0 1261 13 0 0
v_max_id_clo_ord 5907 5907 0 0.00 0 11850 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 1447 11 0 0
v_max_id_ord_sup 5757 5757 0 0.00 0 8549 5 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 2259 5 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 7854 7 0 0
v_min_id_clo_ord 5907 5907 0 0.00 0 3459 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 2617 6 0 0
v_min_id_ord_sup 5757 5757 0 0.00 0 6621 8 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 8131 20 0 0
v_min_non_paid_realizn 7325 7325 0 0.00 0 7496 27 0 0
v_our_firm 11210 11210 0 0.00 0 28 1 0 0
v_reserve_write_off 21194 21194 0 0.00 0 13309 8 0 0
x_lock_depdocs_on_canc_invoice 12553 12553 0 0.00 0 28703 124 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 25660 465 0 0
x_make_qstorno_1000_1200 5913 5913 0 0.00 1 36303 1121 0 0
x_make_qstorno_1200_2000 5760 5760 0 0.00 3 75520 2844 0 0
x_make_qstorno_2100_3300 496518 496518 0 0.00 0 66433 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2022 01:39:27 to 01-10-2022 01:39:31.

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 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:33 11589 8192000000 9898733264 10208448512 980520720 10.689 100 83 166 0 1007384672 5390432 21473520
23:10:51 26362 8192000000 9937633168 10260832256 1024434512 11.115 100 85 170 1 1054299840 8038720 21826608
23:12:23 17257 8192000000 9981663072 10316283904 1065932912 11.513 100 81 162 0 1095988240 9760768 20294560
23:13:53 12393 8192000000 10032962272 10381520896 1120676432 12.033 100 82 165 1 1151658768 11508960 19473376
23:15:08 6759 8192000000 10075038192 10429325312 1158891024 12.393 100 82 164 0 1190836976 13121072 18824880
23:16:21 3623 8192000000 10108332096 10479300608 1203545680 12.809 100 72 142 2 1231448992 11545472 16357840
23:17:27 1790 8192000000 10157136640 10533535744 1248751920 13.227 100 71 140 0 1275440752 11153760 15535072
23:18:31 1290 8192000000 10195601696 10573340672 1286459168 13.572 100 96 192 0 1320487456 12968624 21059664
23:19:33 869 8192000000 10210698672 10596519936 1298639296 13.683 100 86 176 0 1330364192 11853952 19870944
23:20:35 15465 8192000000 10209066320 10607439872 1306382224 13.753 100 61 121 2 1329063024 7970768 14710032
23:21:51 10870 8192000000 10185900080 10604421120 1307084144 13.760 100 26 50 1 1318593824 5692400 5817280
23:23:02 10622 8192000000 10196175280 10614706176 1317380464 13.853 100 15 30 0 1326163824 5628160 3155200
23:24:18 9289 8192000000 10207129648 10626428928 1322963904 13.904 100 15 27 1 1331738000 5350048 3424048
23:25:29 26382 8192000000 10208949664 10631020544 1312536528 13.809 99 23 44 0 1322813776 5726736 4550512
23:26:57 7764 8192000000 10222446800 10639151104 1331295968 13.979 100 39 76 0 1346711392 5277568 10137856
23:28:06 7730 8192000000 10197688080 10613624832 1289217440 13.597 98 14 28 0 1297961680 5529312 3214928
23:29:17 58999 8192000000 10153573120 10557132800 1189763024 12.681 92 35 68 1 1202585744 5060400 7762320
23:31:16 5236 8192000000 10123354896 10520555520 1233397344 13.085 100 25 50 0 1245599616 6642288 5559984
23:32:23 8289 8192000000 10134810896 10529140736 1220952416 12.970 97 14 26 0 1229056368 4979936 3124016
23:33:32 5796 8192000000 10160937536 10565337088 1269110928 13.413 100 22 44 1 1280223104 5910160 5202016
23:34:40 32757 8192000000 10169492832 10579173376 1276962448 13.485 100 20 40 0 1286539632 4757808 4819376
23:36:15 7138 8192000000 10188064608 10600861696 1300718848 13.702 100 21 43 1 1311060576 5562928 4778800
23:37:25 5127 8192000000 10210340720 10624167936 1315840560 13.839 100 39 78 0 1330723024 4881360 10001104
23:38:33 43959 8192000000 10245259488 10657411072 1336386848 14.025 100 34 64 2 1348638880 5325888 6926144
23:40:31 61646 8192000000 10262308896 10683936768 1359213968 14.230 100 39 78 0 1379340656 10508080 9618608
23:42:44 23432 8192000000 10279458544 10701721600 1360707280 14.244 99 34 67 0 1381592896 13507200 7378416
23:44:17 29546 8192000000 10288052080 10714214400 1373928624 14.362 100 37 69 2 1397937088 16556048 7452416
23:45:51 11847 8192000000 10314325680 10736619520 1397318064 14.571 100 52 104 0 1427346656 17961088 12067504
23:47:09 8811 8192000000 10311364848 10732380160 1392535360 14.528 100 51 99 0 1425672608 20720928 12416320
23:48:25 6350 8192000000 10320387424 10744954880 1398627744 14.583 100 45 88 2 1429718000 20392112 10698144
23:49:36 3600 8192000000 10331667184 10744860672 1393986128 14.541 100 67 132 0 1440606672 30545232 16075312
23:50:45 9926 8192000000 10328698992 10760560640 1407580688 14.662 100 48 95 0 1443740320 24600800 11558832
23:52:02 5254 8192000000 10328524672 10738565120 1381530112 14.430 100 82 164 0 1432036016 30106496 20399408
23:53:14 4562 8192000000 10299923856 10705137664 1353982624 14.183 100 72 141 0 1406762304 35009552 17770128
23:54:24 5955 8192000000 10298959920 10704801792 1351162656 14.158 100 76 145 0 1399199376 30126656 17910064
23:55:37 3965 8192000000 10304830288 10719035392 1365155440 14.284 100 64 122 2 1413896176 31877472 16863264
23:56:48 4640 8192000000 10289006176 10699898880 1342944496 14.084 100 56 110 1 1396967776 39637408 14385872
23:58:06 7509 8192000000 10310742528 10719080448 1370362560 14.330 100 58 116 0 1415329392 29692896 15273936
23:59:35 4298 8192000000 10344923936 10753277952 1388645616 14.494 100 73 145 1 1446442272 39954672 17841984
00:00:46 9450 8192000000 10334501904 10758631424 1391582592 14.520 100 27 53 0 1434151456 35125104 7443760
00:02:00 10704 8192000000 10366688048 10793271296 1407844032 14.665 100 50 100 0 1475098080 53120624 14133424
00:03:13 25271 8192000000 10356171584 10779930624 1388065808 14.489 99 35 70 0 1443315376 45526064 9723504
00:04:58 24019 8192000000 10351804272 10786852864 1392886016 14.532 99 27 54 0 1447271184 46799312 7585856
00:06:27 37103 8192000000 10347814752 10791645184 1391335760 14.518 100 30 60 0 1456892016 56773680 8782576
00:08:06 35939 8192000000 10328222496 10771857408 1335100368 14.013 98 19 36 0 1403292560 62609520 5582672
00:09:43 30708 8192000000 10294386304 10725752832 1363843856 14.272 99 31 61 0 1382065408 11385504 6836048
00:11:20 68899 8192000000 10295150400 10751016960 1385699904 14.467 100 12 24 0 1398919008 10841888 2377216
00:13:29 98201 8192000000 10310960752 10752724992 1373259936 14.356 98 34 69 1 1394249968 13279760 7710272
00:17:21 92193 8192000000 10302622384 10752753664 1340926416 14.066 96 35 70 0 1361088848 12090688 8071744
00:21:06 15649 8192000000 10317367872 10762633216 1394876016 14.549 100 39 77 0 1418908032 15200000 8832016
00:22:27 34336 8192000000 10319373792 10774315008 1402172928 14.614 100 18 36 0 1420396432 14015248 4208256
00:24:05 15246 8192000000 10332196240 10777206784 1406301328 14.651 100 54 101 3 1434193808 16438240 11454240
00:25:22 95255 8192000000 10293730304 10761867264 1354501280 14.188 96 11 22 0 1371954144 14822272 2630592
00:27:58 75843 8192000000 10304132848 10773118976 1393088560 14.533 99 20 41 0 1409448896 11338352 5021984
00:30:16 78541 8192000000 10282726512 10745749504 1372366112 14.348 98 18 34 0 1384457024 8602080 3488832
00:33:02 83963 8192000000 10281887472 10746048512 1355023696 14.193 97 16 32 0 1366946832 8055488 3867648
00:35:28 27679 8192000000 10289825344 10743808000 1374607984 14.368 99 16 30 1 1384492624 6627104 3257536
00:37:05 28391 8192000000 10294474320 10744254464 1383013920 14.443 100 20 40 0 1396178928 8749280 4415728
00:38:37 33305 8192000000 10298510000 10756014080 1372510720 14.350 98 15 30 0 1386019648 10346096 3162832
00:40:14 60010 8192000000 10259180352 10716073984 1293786384 13.639 93 9 18 1 1303834544 8280048 1768112
00:42:17 43241 8192000000 10268174736 10720747520 1308590544 13.773 95 15 30 0 1320481936 8840848 3050544
00:44:01 31486 8192000000 10265688992 10707996672 1317022992 13.850 97 31 62 1 1337585184 13801344 6760848
00:45:44 86310 8192000000 10265184400 10706518016 1289271072 13.598 94 16 32 0 1306235616 13506656 3457888
00:48:11 85639 8192000000 10291137728 10736660480 1346858512 14.119 98 20 38 0 1365223504 14286336 4078656
00:50:37 95499 8192000000 10270215760 10718707712 1346082144 14.112 98 19 38 1 1358172448 7836512 4253792
00:53:15 55084 8192000000 10281483456 10732732416 1360150976 14.239 99 33 64 0 1378329296 10180032 7998288
00:55:18 20380 8192000000 10299203520 10740264960 1378298592 14.401 100 42 83 0 1397818064 10456000 9063472
00:56:49 64173 8192000000 10278158192 10729353216 1345435616 14.106 98 16 32 0 1361252704 11859568 3957520
00:58:54 93429 8192000000 10298768144 10754228224 1377140240 14.391 100 16 32 0 1399698160 18903472 3654448
01:01:31 100984 8192000000 10256647120 10712264704 1296512176 13.664 95 14 28 0 1311255120 11482624 3260320
01:04:12 27981 8192000000 10291967168 10730364928 1371222016 14.338 100 50 94 1 1390130928 8969616 9939296
01:05:40 53480 8192000000 10270180288 10726219776 1347845248 14.128 98 25 51 1 1362973296 9603456 5524592
01:07:35 46758 8192000000 10286882080 10741530624 1373631856 14.360 100 17 33 0 1387013584 9739792 3641936
01:09:22 48508 8192000000 10287824880 10746609664 1365644608 14.288 98 17 34 0 1378638864 9395408 3598848
01:11:10 121074 8192000000 10286887984 10751225856 1323315456 13.907 95 11 22 0 1345289520 19650928 2323136
01:14:18 105005 8192000000 10296849104 10747441152 1384299536 14.455 100 14 28 0 1402992048 15509456 3183056
01:17:03 46544 8192000000 10287934096 10758758400 1384904032 14.460 100 7 14 0 1402250960 16214256 1132672
01:18:50 85505 8192000000 10291254800 10764836864 1371405440 14.340 99 15 30 0 1397424592 22843648 3175504
01:21:17 80530 8192000000 10279830560 10740617216 1345548000 14.107 97 19 38 0 1361721952 11704224 4469728
01:23:38 66316 8192000000 10268343792 10735271936 1337765088 14.037 97 16 32 0 1350667200 9593392 3308720
01:25:49 30835 8192000000 10268644416 10728464384 1351672704 14.163 98 15 30 0 1363773536 8201456 3899376
01:27:20 74079 8192000000 10255294624 10720948224 1348559424 14.135 98 20 40 1 1365478736 12053920 4865392
01:29:40 34536 8192000000 10271926864 10721259520 1331278704 13.979 97 39 78 0 1349372816 9011328 9082784
01:31:18 60969 8192000000 10263267584 10707767296 1284061824 13.550 94 26 51 0 1302075808 12224512 5789472
01:33:22 100220 8192000000 10252442176 10714374144 1290912784 13.613 95 9 18 0 1303117840 10529520 1675536
01:36:04 26381 8192000000 10278387360 10717646848 1347896096 14.129 99 45 89 1 1372650464 14807392 9946976
01:37:32 69795 8192000000 9582245600 9932161024 694434640 7.814 51 10 20 0 709039584 11822256 2782688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-10-2022 01:39:33 to 01-10-2022 01:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 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 01-10-2022 01:39:50 to 01-10-2022 01:39:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          252598
MON$OLDEST_ACTIVE               252831
MON$OLDEST_SNAPSHOT             252831
MON$NEXT_TRANSACTION            252831
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                       2053792
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:305: 
:306: mscs-test-161.budzdorov.ru    Sat Oct  1 01:38:49 2022
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:308: 
:309: 
:310: mscs-test-161.budzdorov.ru    Sat Oct  1 01:38:49 2022
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:312: 
:313: 
:314: mscs-test-161.budzdorov.ru    Sat Oct  1 01:38:50 2022
:315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:316: 
--- end of diff output ---

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

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