Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2022 19:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15222748 kB
MemAvailable 30439304 kB
Buffers 0 kB
Cached 16509592 kB
SwapCached 11604 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-12-30 19:38:35
DTS_END                         2022-12-30 19:38:35.7810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41752
TRN_ID                          243015
ATT_ID                          719
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: 9632.69 24106 1155923
customer order: creation 174.22 332 20906
customer order: refuse 15.22 317 1826
order to supplier: creation 45.74 1237 5489
order to supplier: removal 3.51 99291 421
invoice (draft): creation 46.91 3366 5629
invoice (draft): removal 53.26 763 6391
invoice accept: apply 43.88 16385 5265
invoice accept: cancel 48.61 736 5833
customer reserve: creation 4096.56 160 491587
customer reserve: removal 4801.71 58 576205
realization accept: apply 84.58 172 10150
realization accept: cancel 88.39 102 10607
payment from customer: creation 59.71 96 7165
payment from customer: removal 5.96 78 715
payment to supplier: creation 48.76 110 5851
payment to supplier: removal 3.58 72 430
service: total inventory turnovers 4.51 1295 541
service: total monetary turnovers 6.57 69 788
service: aggregate perf. data 1.00 15595 120
service: refresh index statistics 0.03 341881 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 19:41:08 to 30-12-2022 19:41:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2842
TEST_TIME 2 2841
TEST_TIME 3 4076
TEST_TIME 4 3875
TEST_TIME 5 3870
TEST_TIME 6 4612
TEST_TIME 7 4483
TEST_TIME 8 4482
TEST_TIME 9 4799
TEST_TIME 10 5151
TEST_TIME 11 5123
TEST_TIME 12 5583
TEST_TIME 13 5877
TEST_TIME 14 6249
TEST_TIME 15 6534
TEST_TIME 16 7160
TEST_TIME 17 7100
TEST_TIME 18 7499
TEST_TIME 19 7767
TEST_TIME 20 8385
TEST_TIME 21 8620
TEST_TIME 22 8756
TEST_TIME 23 9266
TEST_TIME 24 9244
TEST_TIME 25 9452
TEST_TIME 26 9732
TEST_TIME 27 10028
TEST_TIME 28 10616
TEST_TIME 29 11065
TEST_TIME 30 11034
TEST_TIME 31 10989
TEST_TIME 32 11018
TEST_TIME 33 10955
TEST_TIME 34 11089
TEST_TIME 35 10986
TEST_TIME 36 10797
TEST_TIME 37 10563
TEST_TIME 38 10470
TEST_TIME 39 10415
TEST_TIME 40 10376
TEST_TIME 41 10320
TEST_TIME 42 10289
TEST_TIME 43 10227
TEST_TIME 44 10155
TEST_TIME 45 10156
TEST_TIME 46 10196
TEST_TIME 47 10279
TEST_TIME 48 10182
TEST_TIME 49 10239
TEST_TIME 50 10067
TEST_TIME 51 9956
TEST_TIME 52 10032
TEST_TIME 53 9935
TEST_TIME 54 9960
TEST_TIME 55 9931
TEST_TIME 56 9895
TEST_TIME 57 9909
TEST_TIME 58 9919
TEST_TIME 59 9967
TEST_TIME 60 9915
TEST_TIME 61 10057
TEST_TIME 62 10057
TEST_TIME 63 10121
TEST_TIME 64 10092
TEST_TIME 65 10261
TEST_TIME 66 10247
TEST_TIME 67 10230
TEST_TIME 68 10145
TEST_TIME 69 10133
TEST_TIME 70 10108
TEST_TIME 71 10050
TEST_TIME 72 10040
TEST_TIME 73 9980
TEST_TIME 74 9909
TEST_TIME 75 9915
TEST_TIME 76 9867
TEST_TIME 77 9785
TEST_TIME 78 9827
TEST_TIME 79 9834
TEST_TIME 80 9817
TEST_TIME 81 9809
TEST_TIME 82 9853
TEST_TIME 83 9910
TEST_TIME 84 9893
TEST_TIME 85 9852
TEST_TIME 86 9845
TEST_TIME 87 9946
TEST_TIME 88 10005
TEST_TIME 89 10011
TEST_TIME 90 10034
TEST_TIME 91 10020
TEST_TIME 92 9948
TEST_TIME 93 9907
TEST_TIME 94 9915
TEST_TIME 95 9834
TEST_TIME 96 9831
TEST_TIME 97 9802
TEST_TIME 98 9780
TEST_TIME 99 9810
TEST_TIME 100 9786
TEST_TIME 101 9767
TEST_TIME 102 9761
TEST_TIME 103 9768
TEST_TIME 104 9796
TEST_TIME 105 9788
TEST_TIME 106 9772
TEST_TIME 107 9811
TEST_TIME 108 9838
TEST_TIME 109 9840
TEST_TIME 110 9885
TEST_TIME 111 9911
TEST_TIME 112 9859
TEST_TIME 113 9794
TEST_TIME 114 9783
TEST_TIME 115 9758
TEST_TIME 116 9743
TEST_TIME 117 9706
TEST_TIME 118 9699
TEST_TIME 119 9691
TEST_TIME 120 9635

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2022 19:41:12 to 30-12-2022 19:41:15.

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 1610452 1610452 0 0.00 0 20063 5 0 0
doc_list_biud 1610452 1610452 0 0.00 0 84707 26 0 0
sp_add_invoice_to_stock 5366 5265 101 1.88 46 141293 16386 0 101
sp_cancel_adding_invoice 5833 5833 0 0.00 4 54178 736 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 9827 318 0 0
sp_cancel_customer_reserve 576205 576205 0 0.00 0 20738 58 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 2 7766 79 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 3358 72 0 0
sp_cancel_supplier_invoice 6401 6391 10 0.15 1 84717 763 0 10
sp_cancel_supplier_order 421 421 0 0.00 8 1118891 99291 0 0
sp_cancel_write_off 10607 10607 0 0.00 1 7575 103 0 0
sp_client_order 20906 20906 0 0.00 3 20074 333 0 0
sp_customer_reserve 491587 491587 0 0.00 0 31210 161 0 0
sp_fill_shopping_cart 32014 32014 0 0.00 1 11668 37 0 0
sp_fill_shopping_cart_clo_res 491571 491571 0 0.00 0 12027 14 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 23117 292 0 0
sp_kill_cost_storno 17585 17585 0 0.00 0 6635 53 0 0
sp_kill_qstorno_ret_qs2qd 1169829 1169829 0 0.00 0 84697 31 0 0
sp_kill_qty_storno 616682 616682 0 0.00 0 84704 68 0 0
sp_lock_selected_doc 21349 21349 0 0.00 0 7815 6 0 0
sp_make_cost_storno 28415 28415 0 0.00 0 8509 45 0 0
sp_multiply_rows_for_pdistr 28415 28415 0 0.00 0 9236 24 0 0
sp_multiply_rows_for_qdistr 20906 20906 0 0.00 0 14970 156 0 0
sp_pay_from_customer 7165 7165 0 0.00 4 11862 97 0 0
sp_pay_to_supplier 5851 5851 0 0.00 4 11459 110 0 0
sp_payment_common 13016 13016 0 0.00 1 9172 28 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 9818 288 0 0
sp_qd_handle_on_invoice_upd_sts 11082 11082 0 0.00 0 31075 311 0 0
sp_qd_handle_on_reserve_upd_sts 10150 10150 0 0.00 0 14719 60 0 0
sp_reserve_write_off 10150 10150 0 0.00 6 17227 172 0 0
sp_supplier_invoice 5629 5629 0 0.00 7 91103 3367 0 0
sp_supplier_order 5489 5489 0 0.00 4 29630 1237 0 0
srv_aggregate_perf_data 120 120 0 0.00 2218 142415 15595 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1535 121015 43598 0 0
srv_find_qd_qs_mism 1106490 1106490 0 0.00 0 20062 4 0 0
srv_make_invnt_saldo 541 541 0 0.00 42 77895 1295 0 0
srv_make_money_saldo 788 788 0 0.00 0 28989 69 0 0
srv_recalc_idx_stat 4 4 0 0.00 224434 409555 341881 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5266 5266 0 0.00 0 13549 154 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 4584 119 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 3037 10 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 3373 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 775 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 7753 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1033 13 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 15448 1449 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 3464 8 0 0
t$perf-norm:sp_client_order 20907 20907 0 0.00 0 8049 17 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 1572 6 0 0
t$perf-norm:sp_pay_from_customer 7165 7165 0 0.00 0 10101 11 0 0
t$perf-norm:sp_pay_to_supplier 5852 5852 0 0.00 0 11230 13 0 0
t$perf-norm:sp_reserve_write_off 10150 10150 0 0.00 0 7216 10 0 0
t$perf-norm:sp_supplier_invoice 5629 5629 0 0.00 0 6828 18 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 6108 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 531 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 217 4 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 830 22 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 1087 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 51 15 0 0
v_add_invoice_to_stock 10700 10498 202 1.88 0 5993 18 0 202
v_all_customers 38372 38372 0 0.00 0 22 1 0 0
v_all_suppliers 30436 30436 0 0.00 0 27 1 0 0
v_all_wares 41812 41812 0 0.00 0 196 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1542 7 0 0
v_cancel_client_order 3652 3652 0 0.00 0 9434 6 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 2529 6 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1280 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3346 8 0 0
v_cancel_supplier_invoice 814 794 20 2.45 0 315 13 0 20
v_cancel_supplier_order 842 842 0 0.00 0 595 4 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2129 13 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 243 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 2367 17 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 5595 7 0 0
v_max_non_paid_invoice 1740 1740 0 0.00 0 5544 8 0 0
v_max_non_paid_realizn 3606 3606 0 0.00 0 2276 5 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 1768 4 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 3451 20 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 5332 5 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 5803 22 0 0
v_min_non_paid_realizn 7165 7165 0 0.00 0 8640 17 0 0
v_our_firm 10558 10558 0 0.00 0 17 1 0 0
v_reserve_write_off 20300 20300 0 0.00 0 9867 6 0 0
x_lock_depdocs_on_canc_invoice 11827 11827 0 0.00 0 18296 107 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 7901 330 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 1 29625 1173 0 0
x_make_qstorno_1200_2000 5629 5629 0 0.00 3 91010 3225 0 0
x_make_qstorno_2100_3300 491587 491587 0 0.00 0 31208 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 19:41:16 to 30-12-2022 19:41:20.

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
17:08:39 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:40 24979 8192000000 9904445392 10214678528 984165872 10.725 100 86 172 0 1012683408 6347616 22169920
17:11:11 18209 8192000000 9942432000 10263216128 1023014368 11.101 100 89 178 0 1054345200 8395472 22935360
17:12:31 15994 8192000000 9978102912 10309885952 1062050736 11.476 100 86 172 0 1093605360 9661744 21892880
17:13:59 9128 8192000000 10020268816 10365530112 1106691056 11.901 100 72 144 0 1134159136 10075504 17392576
17:15:11 5145 8192000000 10077915824 10435768320 1164005536 12.441 100 78 154 1 1193781328 12288992 17486800
17:16:25 3702 8192000000 10117811728 10486239232 1207011312 12.841 100 82 162 0 1239389008 13898640 18479056
17:17:32 2053 8192000000 10146476656 10518548480 1236546192 13.114 100 83 166 2 1267810608 12564128 18700288
17:18:36 1497 8192000000 10186073200 10567512064 1275454176 13.471 100 84 168 0 1307018784 12325472 19239136
17:19:38 7331 8192000000 10203068832 10596679680 1300806976 13.703 100 70 141 1 1327006656 10077072 16122608
17:20:48 16766 8192000000 10195161136 10607902720 1306609584 13.755 100 23 45 0 1322491568 10450544 5431440
17:22:05 11869 8192000000 10199809888 10613080064 1312775520 13.811 100 17 34 0 1323198352 6620240 3802592
17:23:18 11130 8192000000 10214023152 10626310144 1322611344 13.900 100 24 49 0 1334641856 6303856 5726656
17:24:33 7091 8192000000 10219789552 10630795264 1329333072 13.961 100 36 73 1 1342885040 5827392 7724576
17:25:42 21496 8192000000 10226992432 10647834624 1332182080 13.987 100 24 48 1 1343262048 6259600 4820368
17:27:06 4357 8192000000 10224659488 10643197952 1327317344 13.943 100 29 58 0 1340391568 6355696 6718528
17:28:11 5392 8192000000 10215459040 10636865536 1326987104 13.940 100 28 55 0 1339171152 5620528 6563520
17:29:16 61832 8192000000 10175454192 10590597120 1243772128 13.181 95 20 40 1 1253344800 5229360 4343312
17:31:20 5392 8192000000 10145969664 10543788032 1213359264 12.900 96 27 54 0 1225835152 5762720 6713168
17:32:27 9786 8192000000 10137314832 10534678528 1214027568 12.906 97 18 36 0 1224050480 5733008 4289904
17:33:37 65006 8192000000 10162260592 10576859136 1268825808 13.411 99 35 71 0 1282618096 4747664 9044624
17:35:45 4684 8192000000 10191389872 10604351488 1298298176 13.680 100 23 45 0 1309805488 6587536 4919776
17:36:50 12371 8192000000 10196407136 10616889344 1310677760 13.792 100 20 38 1 1321509696 6121744 4710192
17:38:03 57551 8192000000 10234474304 10646949888 1327313888 13.943 100 31 62 0 1340130432 5403856 7412688
17:40:08 44385 8192000000 10252746192 10668826624 1345908240 14.111 100 44 88 0 1363992096 8436592 9647264
17:42:16 38386 8192000000 10282096992 10705068032 1372768656 14.352 100 33 63 1 1393163984 13295696 7099632
17:44:10 20847 8192000000 10311696448 10733740032 1391916352 14.523 100 35 69 0 1417883808 17496640 8470816
17:45:39 8695 8192000000 10326822992 10750758912 1410916672 14.692 100 47 94 2 1442305552 20328544 11060336
17:46:52 13675 8192000000 10347826880 10770460672 1415291936 14.731 100 64 127 2 1456081824 25850048 14939840
17:48:13 6286 8192000000 10335857472 10766790656 1407736752 14.664 99 44 85 0 1441081840 22301920 11043168
17:49:23 5426 8192000000 10363512800 10786181120 1428391664 14.847 100 71 139 1 1474232000 28695136 17145200
17:50:38 5467 8192000000 10346699536 10775490560 1414219888 14.721 100 53 102 0 1453875504 27726176 11929440
17:51:50 5903 8192000000 10355700352 10775683072 1414190864 14.721 100 66 131 0 1460806320 30374592 16240864
17:53:00 1532 8192000000 10358005648 10770444288 1406544640 14.653 100 76 150 0 1455168080 30187136 18436304
17:54:07 1542 8192000000 10341261136 10752380928 1388737904 14.495 100 79 158 2 1447526208 39509216 19279088
17:55:13 6763 8192000000 10336173024 10753847296 1384292240 14.455 100 62 121 1 1438875120 39403936 15178944
17:56:27 2418 8192000000 10356362528 10775904256 1398038864 14.578 100 65 130 0 1460074464 44854704 17180896
17:57:33 10907 8192000000 10347275152 10774491136 1395065648 14.551 100 44 88 0 1455229712 47758608 12405456
17:58:56 7783 8192000000 10329266960 10758139904 1375230000 14.374 99 47 93 0 1433624496 46055216 12339280
18:00:09 10345 8192000000 10322364832 10745679872 1361521744 14.251 99 40 78 2 1419826768 47025376 11279648
18:01:27 15070 8192000000 10325472176 10763624448 1392208784 14.526 100 31 62 0 1444380464 43572256 8599424
18:02:46 10437 8192000000 10333157056 10763915264 1377345376 14.393 99 28 56 0 1426317184 40906304 8065504
18:04:11 28213 8192000000 10306359296 10743074816 1363537520 14.269 99 20 41 1 1409228720 40492096 5199104
18:05:45 40336 8192000000 10312802112 10758438912 1349469808 14.143 98 13 26 0 1394253792 41345584 3438400
18:07:26 67870 8192000000 10293439648 10741383168 1356921312 14.210 99 18 36 0 1377661136 16828560 3911264
18:09:38 121015 8192000000 10268602768 10710179840 1275328784 13.470 93 17 35 0 1292301344 13764176 3208384
18:12:44 77303 8192000000 10273804208 10720997376 1361244672 14.249 100 5 9 1 1377570272 15341648 983952
18:15:02 84663 8192000000 10298339280 10733867008 1363350240 14.267 99 34 67 0 1378139008 7809648 6979120
18:17:33 19217 8192000000 10301977392 10740326400 1371419376 14.340 99 53 104 0 1392181984 9012496 11750112
18:18:55 24194 8192000000 10303966512 10755448832 1390392688 14.509 100 26 52 0 1409114208 13039952 5681568
18:20:22 13610 8192000000 10306894752 10756542464 1387392720 14.483 99 19 38 0 1402298240 9926768 4978752
18:21:38 20517 8192000000 10343275312 10772566016 1407247264 14.659 100 42 81 0 1430503824 13254048 10002512
18:22:58 37291 8192000000 10351958048 10790969344 1419030160 14.764 100 47 94 0 1449541616 19315744 11195712
18:25:11 30661 8192000000 10316663072 10783350784 1407264992 14.660 100 10 20 0 1429017024 19164144 2587888
18:26:46 23393 8192000000 10313547648 10778030080 1408398880 14.670 100 15 29 0 1425111952 12936656 3776416
18:28:10 68673 8192000000 10307291088 10770374656 1397739808 14.575 99 16 31 0 1407947744 6711760 3496176
18:30:25 50676 8192000000 10285589888 10744631296 1363039504 14.265 97 20 40 0 1381220368 13037344 5143520
18:33:00 50647 8192000000 10299118208 10763190272 1359014384 14.229 97 5 10 0 1372952400 12899952 1038064
18:34:56 63820 8192000000 10308083056 10773606400 1386951824 14.479 99 11 22 0 1403089488 13434272 2703392
18:37:14 40184 8192000000 10289695536 10744954880 1368933952 14.317 99 20 40 0 1381039216 7838336 4266928
18:38:55 58088 8192000000 10280189920 10735579136 1340703472 14.064 97 13 26 0 1358324352 14933520 2687360
18:41:31 64385 8192000000 10288478480 10735632384 1329906592 13.966 96 7 14 0 1344436368 13055456 1474320
18:43:36 98953 8192000000 10286560880 10742571008 1336434448 14.025 97 5 10 0 1357978432 20347536 1196448
18:46:15 113888 8192000000 10293193344 10732806144 1370557760 14.332 99 30 59 2 1383603152 6869728 6175664
18:49:13 23409 8192000000 10267614640 10713632768 1325151840 13.923 96 35 66 0 1341492592 9489984 6850768
18:50:37 87414 8192000000 10264937664 10733834240 1330211776 13.969 97 9 18 1 1346741840 14909824 1620240
18:53:05 45916 8192000000 10257794032 10705235968 1339657840 14.054 99 13 26 0 1351227328 8389136 3180352
18:54:52 57851 8192000000 10234581616 10679664640 1317076128 13.850 97 24 46 1 1332628368 10668256 4883984
18:56:54 68680 8192000000 10257365696 10714906624 1317254096 13.852 97 12 24 0 1334178096 14474016 2449984
18:59:03 30077 8192000000 10275474208 10720047104 1347938336 14.129 99 38 75 1 1369421024 11874656 9608032
19:00:34 47716 8192000000 10268488464 10713890816 1336480848 14.026 98 20 40 0 1352709744 11809792 4419104
19:02:44 40798 8192000000 10288969072 10729852928 1372264240 14.347 100 24 48 0 1387761040 10480016 5016784
19:04:25 46766 8192000000 10288381328 10745061376 1369392768 14.322 99 30 60 0 1394057776 17539856 7125152
19:06:19 38749 8192000000 10295646640 10749202432 1379242240 14.410 100 17 34 1 1394341536 11216240 3883056
19:08:01 49406 8192000000 10301323008 10758397952 1382825488 14.442 99 30 60 0 1404844608 15089040 6930080
19:09:52 118404 8192000000 10301541424 10748395520 1385435232 14.465 99 27 54 0 1400126384 8262784 6428368
19:12:53 59092 8192000000 10303527072 10772164608 1407760064 14.664 100 12 24 0 1421112464 10570336 2782064
19:14:52 62211 8192000000 10295620400 10764853248 1389498672 14.501 99 11 22 0 1401687760 9518208 2670880
19:17:03 55228 8192000000 10302422064 10776219648 1392615328 14.529 99 13 26 0 1406790768 11182992 2992448
19:18:58 29192 8192000000 10319506544 10772922368 1384223536 14.454 98 36 68 0 1399615952 7933888 7458528
19:20:28 33756 8192000000 10308319440 10765733888 1379973712 14.416 98 25 48 0 1400965552 14616320 6375520
19:22:14 50427 8192000000 10294856032 10754490368 1360540672 14.242 97 20 40 0 1375552112 10928720 4082720
19:24:15 46270 8192000000 10276656768 10722512896 1300681296 13.701 94 29 58 0 1319791488 12835200 6274992
19:26:07 114799 8192000000 10277835104 10738245632 1267226736 13.396 92 17 33 0 1281153840 10501616 3425488
19:29:12 71988 8192000000 10280051056 10736033792 1347594640 14.126 98 9 18 0 1359160544 9674528 1891376
19:31:24 101868 8192000000 10286289536 10751295488 1385717568 14.468 100 30 60 0 1402638672 9910800 7010304
19:34:08 50797 8192000000 10260151344 10722287616 1326666880 13.937 96 20 40 0 1338637824 7265520 4705424
19:35:59 37024 8192000000 10261753280 10722181120 1351339984 14.160 99 13 26 0 1361386720 7214096 2832640
19:37:37 38534 8192000000 10265205488 10712104960 1331487360 13.981 98 20 40 0 1351368464 14710608 5170496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-12-2022 19:41:22 to 30-12-2022 19:41:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2022 19:41:39 to 30-12-2022 19:41:41.

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          241607
MON$OLDEST_ACTIVE               243194
MON$OLDEST_SNAPSHOT             243194
MON$NEXT_TRANSACTION            243194
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                       2047328
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1437: 
:1438: mscs-test-161.budzdorov.ru    Fri Dec 30 19:38:43 2022
:1439:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1440: 
:1441: 
:1442: mscs-test-161.budzdorov.ru    Fri Dec 30 19:38:58 2022
:1443:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1444: 
:1445: 
:1446: mscs-test-161.budzdorov.ru    Fri Dec 30 19:38:58 2022
:1447:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1448: 
--- end of diff output ---

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

30.12.22 19:41:42. 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-12-30 19:41:44 No severe PSQL-related problems occured
30.12.22 19:41:46. End of report.