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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15240252 kB
MemAvailable 30377440 kB
Buffers 0 kB
Cached 16443912 kB
SwapCached 3564 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-06 01:38:39
DTS_END                         2023-02-06 01:38:39.9410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47964
TRN_ID                          244804
ATT_ID                          625
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: 9635.57 18359 1156268
customer order: creation 178.97 326 21476
customer order: refuse 14.92 326 1790
order to supplier: creation 47.09 1154 5651
order to supplier: removal 3.37 87712 404
invoice (draft): creation 47.88 3137 5746
invoice (draft): removal 54.22 650 6506
invoice accept: apply 43.93 15883 5272
invoice accept: cancel 48.82 718 5858
customer reserve: creation 4082.68 157 489921
customer reserve: removal 4807.38 48 576885
realization accept: apply 86.76 170 10411
realization accept: cancel 85.29 95 10235
payment from customer: creation 60.23 112 7228
payment from customer: removal 5.81 116 697
payment to supplier: creation 51.37 172 6164
payment to supplier: removal 3.78 54 454
service: total inventory turnovers 5.23 969 627
service: total monetary turnovers 6.87 20 824
service: aggregate perf. data 0.96 16769 115
service: refresh index statistics 0.03 238601 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2023 01:39:12 to 06-02-2023 01:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4136
TEST_TIME 2 4891
TEST_TIME 3 3934
TEST_TIME 4 4104
TEST_TIME 5 3986
TEST_TIME 6 4190
TEST_TIME 7 4841
TEST_TIME 8 5384
TEST_TIME 9 5179
TEST_TIME 10 5539
TEST_TIME 11 5658
TEST_TIME 12 6025
TEST_TIME 13 5973
TEST_TIME 14 6128
TEST_TIME 15 6207
TEST_TIME 16 6517
TEST_TIME 17 7077
TEST_TIME 18 7432
TEST_TIME 19 8397
TEST_TIME 20 8273
TEST_TIME 21 8745
TEST_TIME 22 9138
TEST_TIME 23 9453
TEST_TIME 24 10030
TEST_TIME 25 9954
TEST_TIME 26 9863
TEST_TIME 27 10300
TEST_TIME 28 10251
TEST_TIME 29 10149
TEST_TIME 30 10228
TEST_TIME 31 10327
TEST_TIME 32 10274
TEST_TIME 33 10174
TEST_TIME 34 10266
TEST_TIME 35 10265
TEST_TIME 36 10335
TEST_TIME 37 10327
TEST_TIME 38 10183
TEST_TIME 39 10454
TEST_TIME 40 10431
TEST_TIME 41 10378
TEST_TIME 42 10170
TEST_TIME 43 10140
TEST_TIME 44 10217
TEST_TIME 45 10140
TEST_TIME 46 10051
TEST_TIME 47 10008
TEST_TIME 48 9970
TEST_TIME 49 9885
TEST_TIME 50 9969
TEST_TIME 51 9969
TEST_TIME 52 9860
TEST_TIME 53 9795
TEST_TIME 54 9778
TEST_TIME 55 9939
TEST_TIME 56 9895
TEST_TIME 57 9938
TEST_TIME 58 10008
TEST_TIME 59 10013
TEST_TIME 60 10016
TEST_TIME 61 9913
TEST_TIME 62 10135
TEST_TIME 63 10091
TEST_TIME 64 10117
TEST_TIME 65 10173
TEST_TIME 66 10167
TEST_TIME 67 10191
TEST_TIME 68 10177
TEST_TIME 69 10234
TEST_TIME 70 10099
TEST_TIME 71 10070
TEST_TIME 72 10004
TEST_TIME 73 9925
TEST_TIME 74 9940
TEST_TIME 75 9986
TEST_TIME 76 9942
TEST_TIME 77 9881
TEST_TIME 78 9836
TEST_TIME 79 9809
TEST_TIME 80 9821
TEST_TIME 81 9796
TEST_TIME 82 9710
TEST_TIME 83 9683
TEST_TIME 84 9634
TEST_TIME 85 9603
TEST_TIME 86 9540
TEST_TIME 87 9551
TEST_TIME 88 9688
TEST_TIME 89 9664
TEST_TIME 90 9686
TEST_TIME 91 9698
TEST_TIME 92 9712
TEST_TIME 93 9724
TEST_TIME 94 9752
TEST_TIME 95 9761
TEST_TIME 96 9795
TEST_TIME 97 9820
TEST_TIME 98 9858
TEST_TIME 99 9888
TEST_TIME 100 9828
TEST_TIME 101 9784
TEST_TIME 102 9749
TEST_TIME 103 9721
TEST_TIME 104 9691
TEST_TIME 105 9655
TEST_TIME 106 9619
TEST_TIME 107 9566
TEST_TIME 108 9597
TEST_TIME 109 9600
TEST_TIME 110 9626
TEST_TIME 111 9607
TEST_TIME 112 9609
TEST_TIME 113 9653
TEST_TIME 114 9640
TEST_TIME 115 9650
TEST_TIME 116 9690
TEST_TIME 117 9650
TEST_TIME 118 9661
TEST_TIME 119 9666
TEST_TIME 120 9638

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2023 01:39:16 to 06-02-2023 01:39:19.

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 1608286 1608286 0 0.00 0 19108 6 0 0
doc_list_biud 1608286 1608286 0 0.00 0 40537 22 0 0
sp_add_invoice_to_stock 5380 5272 108 2.00 32 202392 15883 0 108
sp_cancel_adding_invoice 5858 5858 0 0.00 5 75309 719 0 0
sp_cancel_client_order 1790 1790 0 0.00 3 12822 327 0 0
sp_cancel_customer_reserve 576885 576885 0 0.00 0 24401 49 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 10575 116 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 2 2824 54 0 0
sp_cancel_supplier_invoice 6512 6506 6 0.09 1 40548 651 0 6
sp_cancel_supplier_order 404 404 0 0.00 7 896537 87712 0 0
sp_cancel_write_off 10235 10235 0 0.00 1 10354 95 0 0
sp_client_order 21476 21476 0 0.00 3 23820 327 0 0
sp_customer_reserve 489921 489921 0 0.00 0 81987 158 0 0
sp_fill_shopping_cart 32860 32860 0 0.00 1 13003 34 0 0
sp_fill_shopping_cart_clo_res 489917 489917 0 0.00 0 9274 11 0 0
sp_get_clo_for_invoice 5263 5263 0 0.00 0 22397 275 0 0
sp_kill_cost_storno 17244 17244 0 0.00 0 10726 48 0 0
sp_kill_qstorno_ret_qs2qd 1170915 1170915 0 0.00 0 40532 26 0 0
sp_kill_qty_storno 617352 617352 0 0.00 0 40535 57 0 0
sp_lock_selected_doc 21641 21641 0 0.00 0 10349 5 0 0
sp_make_cost_storno 29066 29066 0 0.00 0 19085 60 0 0
sp_multiply_rows_for_pdistr 29066 29066 0 0.00 0 11570 26 0 0
sp_multiply_rows_for_qdistr 21476 21476 0 0.00 0 11832 146 0 0
sp_pay_from_customer 7228 7228 0 0.00 4 17095 112 0 0
sp_pay_to_supplier 6164 6164 0 0.00 4 18771 172 0 0
sp_payment_common 13392 13392 0 0.00 1 14319 38 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 12805 293 0 0
sp_qd_handle_on_invoice_upd_sts 11121 11121 0 0.00 0 29586 285 0 0
sp_qd_handle_on_reserve_upd_sts 10411 10411 0 0.00 0 23228 43 0 0
sp_reserve_write_off 10411 10411 0 0.00 6 29460 171 0 0
sp_supplier_invoice 5746 5746 0 0.00 5 81457 3138 0 0
sp_supplier_order 5651 5651 0 0.00 5 29312 1155 0 0
srv_aggregate_perf_data 115 115 0 0.00 2393 257638 16770 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2587 119473 49471 0 0
srv_find_qd_qs_mism 1105555 1105555 0 0.00 0 9027 5 0 0
srv_make_invnt_saldo 627 627 0 0.00 42 30252 970 0 0
srv_make_money_saldo 824 824 0 0.00 1 918 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 124607 347161 238602 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5273 5273 0 0.00 0 10912 145 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4275 110 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 3465 12 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 3714 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 277 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 860 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 1602 18 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 33161 1625 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 4534 10 0 0
t$perf-norm:sp_client_order 21476 21476 0 0.00 0 8642 15 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 2177 8 0 0
t$perf-norm:sp_pay_from_customer 7228 7228 0 0.00 0 3529 10 0 0
t$perf-norm:sp_pay_to_supplier 6164 6164 0 0.00 0 6011 11 0 0
t$perf-norm:sp_reserve_write_off 10411 10411 0 0.00 0 5958 8 0 0
t$perf-norm:sp_supplier_invoice 5746 5746 0 0.00 0 5769 14 0 0
t$perf-norm:sp_supplier_order 5651 5651 0 0.00 0 4924 13 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 502 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 615 11 0 0
t$perf-norm:srv_make_invnt_saldo 627 627 0 0.00 0 4778 28 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 1201 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10742 10526 216 2.01 0 17828 20 0 216
v_all_customers 39112 39112 0 0.00 0 188 0 0 0
v_all_suppliers 31488 31488 0 0.00 0 22 1 0 0
v_all_wares 42952 42952 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 4064 12 0 0
v_cancel_client_order 3580 3580 0 0.00 0 6116 12 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3905 8 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1236 4 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 2108 4 0 0
v_cancel_supplier_invoice 874 862 12 1.37 0 2271 15 0 12
v_cancel_supplier_order 808 808 0 0.00 0 3811 13 0 0
v_cancel_write_off 2160 2160 0 0.00 0 5814 16 0 0
v_max_id_clo_ord 5651 5651 0 0.00 0 2830 2 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2658 16 0 0
v_max_id_ord_sup 5733 5733 0 0.00 0 2704 5 0 0
v_max_non_paid_invoice 1804 1804 0 0.00 0 5601 9 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 7171 5 0 0
v_min_id_clo_ord 5651 5651 0 0.00 0 5821 7 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 3086 15 0 0
v_min_id_ord_sup 5733 5733 0 0.00 0 12683 18 0 0
v_min_non_paid_invoice 6164 6164 0 0.00 0 14031 34 0 0
v_min_non_paid_realizn 7228 7228 0 0.00 0 14280 27 0 0
v_our_firm 10714 10714 0 0.00 0 13 1 0 0
v_reserve_write_off 20822 20822 0 0.00 0 17084 10 0 0
x_lock_depdocs_on_canc_invoice 11933 11933 0 0.00 0 17274 93 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 4697 263 0 0
x_make_qstorno_1000_1200 5651 5651 0 0.00 1 29290 1091 0 0
x_make_qstorno_1200_2000 5746 5746 0 0.00 2 81413 3007 0 0
x_make_qstorno_2100_3300 489921 489921 0 0.00 0 81984 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2023 01:39:20 to 06-02-2023 01:39:24.

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:43 4 8192000000 8802922608 8815611904 8575600 0.104 4 1 2 0 8677920 59536 42784
23:09:45 30816 8192000000 9914798144 10229014528 991908240 10.800 100 88 176 0 1020867008 6500272 22458496
23:11:24 20269 8192000000 9968264544 10294870016 1048847264 11.350 100 87 174 2 1080389488 9515248 22026976
23:12:50 20301 8192000000 9997300048 10330578944 1076119984 11.610 100 84 168 0 1107570256 10581248 20869024
23:14:17 10672 8192000000 10027567808 10373156864 1115563200 11.985 100 84 168 0 1147728544 12356176 19809168
23:15:29 7593 8192000000 10066885904 10425061376 1161892304 12.421 100 75 150 0 1195350512 15995200 17463008
23:16:41 2787 8192000000 10114856992 10480582656 1201369440 12.789 100 87 174 2 1234122736 12215008 20538288
23:17:46 3191 8192000000 10156275968 10530881536 1244749168 13.190 100 78 154 1 1273934832 11897136 17288528
23:18:51 1904 8192000000 10181862720 10563366912 1273383200 13.453 100 87 172 1 1305984528 13600224 19001104
23:19:57 1210 8192000000 10209724592 10593992704 1298590576 13.682 100 77 153 1 1327419856 12161456 16667824
23:20:59 17327 8192000000 10220770128 10615808000 1309122464 13.778 100 58 116 0 1331489120 9204720 13161936
23:22:17 4970 8192000000 10213427024 10624049152 1319790880 13.875 100 36 72 0 1335402032 7127664 8483488
23:23:23 4780 8192000000 10214668576 10629746688 1326777568 13.938 100 32 64 1 1340490720 6750960 6962192
23:24:29 12541 8192000000 10221380688 10638372864 1330523136 13.972 100 12 24 0 1338368784 5524944 2320704
23:25:44 33655 8192000000 10239508992 10655322112 1338297280 14.042 100 51 101 1 1356047792 6202592 11547920
23:27:19 9694 8192000000 10226775584 10653995008 1339495456 14.053 100 18 37 0 1349341024 5492944 4352624
23:28:30 7672 8192000000 10219003984 10638999552 1307182176 13.760 98 18 36 2 1316777728 5421168 4174384
23:29:39 7334 8192000000 10172668480 10581184512 1263937088 13.366 98 26 51 0 1275146048 5250496 5958464
23:30:47 18286 8192000000 10158514144 10569703424 1235097072 13.101 95 17 34 0 1244544624 5021088 4426464
23:32:06 5741 8192000000 10154638928 10548801536 1231825280 13.071 98 41 82 1 1247764624 6556192 9383152
23:33:16 8615 8192000000 10154063408 10560262144 1257173376 13.304 100 20 38 0 1271266480 9854816 4238288
23:34:27 13426 8192000000 10166218640 10570567680 1274488896 13.463 100 17 31 0 1283218240 4899664 3829680
23:35:40 32349 8192000000 10172934304 10579677184 1271429440 13.435 99 20 40 0 1281001952 5335744 4236768
23:37:16 5226 8192000000 10186806560 10599825408 1296754560 13.666 100 28 56 0 1309316272 6331888 6229824
23:38:23 9352 8192000000 10216496096 10629623808 1321057152 13.886 100 19 36 0 1329861456 4377744 4426560
23:39:40 21223 8192000000 10252088144 10662670336 1343158288 14.086 100 42 84 0 1362797344 9890704 9748352
23:41:06 82106 8192000000 10262594592 10690408448 1361314736 14.249 100 47 94 0 1387613456 14776688 11522032
23:43:43 12943 8192000000 10289172432 10710495232 1376196320 14.383 100 36 72 0 1401555024 17479760 7878944
23:45:03 7677 8192000000 10301943488 10724179968 1383861568 14.451 100 48 96 1 1414117952 18178608 12077776
23:46:13 9191 8192000000 10318199888 10740486144 1395314512 14.553 100 46 93 0 1426677664 20779296 10583856
23:47:43 9220 8192000000 10342107904 10764230656 1409580656 14.680 100 60 118 0 1452504032 29426224 13497152
23:48:58 5041 8192000000 10371571520 10780667904 1423315536 14.802 100 75 147 1 1474821808 33584560 17921712
23:50:15 5142 8192000000 10366442448 10794270720 1424498544 14.813 100 54 106 1 1478246656 40900048 12848064
23:51:27 6959 8192000000 10359899936 10783563776 1419406192 14.767 100 57 114 0 1469876000 34990816 15478992
23:52:48 11293 8192000000 10374818400 10794819584 1422133712 14.792 100 61 120 0 1476586496 38724672 15728112
23:54:03 4533 8192000000 10359205232 10774888448 1396809776 14.567 99 59 116 0 1455409488 42838704 15761008
23:55:12 2586 8192000000 10355273984 10763886592 1393334816 14.536 100 76 153 1 1457260032 44755552 19169664
23:56:17 10586 8192000000 10337031088 10759454720 1386022176 14.470 100 50 99 1 1443876752 44324416 13530160
23:57:46 18525 8192000000 10322258208 10761199616 1388497632 14.492 100 26 52 0 1438080368 42995264 6587472
23:59:22 12246 8192000000 10296849392 10730217472 1338884832 14.047 98 34 64 2 1388084288 40048480 9150976
00:00:41 13481 8192000000 10276043904 10697441280 1323643392 13.910 98 35 68 0 1363451552 30540032 9268128
00:02:10 11484 8192000000 10259911488 10679685120 1320922080 13.885 99 48 92 4 1355758256 23032160 11804016
00:03:24 50862 8192000000 10259671248 10700500992 1341619072 14.072 100 27 54 0 1368992192 20913600 6459520
00:05:40 14349 8192000000 10251402928 10707771392 1327125264 13.941 99 15 31 1 1344291136 13518848 3647024
00:06:58 55863 8192000000 10261462816 10708525056 1349616512 14.144 100 7 14 0 1366973536 15480832 1876192
00:08:56 81159 8192000000 10261604480 10697723904 1338893808 14.047 98 19 38 0 1356026944 13483408 3649728
00:11:17 48770 8192000000 10282012224 10723164160 1362239888 14.257 100 8 16 0 1374649056 11076800 1332368
00:13:06 87694 8192000000 10290097440 10727170048 1277727040 13.492 93 15 30 0 1290720224 9835472 3157712
00:15:35 50362 8192000000 10305528544 10726535168 1369239056 14.320 100 39 77 1 1389461824 11568576 8654192
00:17:31 86570 8192000000 10287083696 10722238464 1349322224 14.141 98 9 18 0 1366302352 15066016 1914112
00:20:13 94080 8192000000 10301665136 10737930240 1370645296 14.333 99 30 60 0 1387262592 9678896 6938400
00:23:01 75152 8192000000 10296271856 10756763648 1387269536 14.481 100 18 34 1 1406222608 14871520 4081552
00:25:19 44791 8192000000 10318890656 10774315008 1402379664 14.616 100 33 64 0 1422346944 12290096 7677184
00:27:06 13656 8192000000 10322731936 10777124864 1413702016 14.717 100 37 74 0 1436514320 14373328 8438976
00:28:24 48694 8192000000 10330908128 10789711872 1412845968 14.709 100 13 26 1 1434752848 18407536 3499344
00:30:13 67345 8192000000 10314533424 10771705856 1389694880 14.503 98 13 26 0 1407759712 14908672 3156160
00:32:22 32246 8192000000 10319349680 10789404672 1407050336 14.658 100 11 22 0 1428246448 18492960 2703152
00:33:55 55404 8192000000 10331880304 10789437440 1413117616 14.712 100 22 44 0 1431610400 13572064 4920720
00:35:51 115582 8192000000 10318066976 10776166400 1370015264 14.327 97 17 33 0 1386695360 13103616 3576480
00:38:55 66255 8192000000 10305828832 10756755456 1349440432 14.142 96 16 32 0 1367150720 14445680 3264608
00:41:25 20149 8192000000 10293962496 10744999936 1381434832 14.429 100 36 67 0 1399117824 8940304 8742688
00:42:46 50713 8192000000 10295797360 10750136320 1356974640 14.210 99 10 20 0 1376463344 17467680 2021024
00:44:46 101342 8192000000 10284353792 10725351424 1297912464 13.676 94 18 36 0 1311329072 9406416 4010192
00:47:28 107272 8192000000 10256656416 10707791872 1309515664 13.782 96 24 48 1 1329652512 14718192 5418656
00:50:22 57783 8192000000 10244927056 10689019904 1295939632 13.658 96 24 47 0 1312409888 11262224 5208032
00:52:20 60862 8192000000 10235683536 10705211392 1321081104 13.886 99 19 38 0 1342289776 17081392 4127280
00:54:22 48284 8192000000 10225784960 10671505408 1294487376 13.645 97 17 33 0 1309279424 11105968 3686080
00:56:14 44970 8192000000 10254403952 10709852160 1347649328 14.126 100 10 20 0 1365664544 15713680 2301536
00:58:00 53462 8192000000 10272426960 10724114432 1359617376 14.234 100 26 51 1 1380506272 14640912 6247984
00:59:55 86959 8192000000 10252819664 10701127680 1333093408 13.995 98 26 52 0 1345569040 6354768 6120864
01:02:22 73677 8192000000 10272080224 10730184704 1350358656 14.151 99 14 28 0 1359680304 6475616 2846032
01:04:37 55956 8192000000 10287804352 10750517248 1363051792 14.265 99 18 36 0 1384888784 17576144 4260848
01:06:41 38472 8192000000 10270056528 10714742784 1328221200 13.951 98 14 28 0 1341213088 9763232 3228656
01:08:23 33218 8192000000 10296028560 10734432256 1370493168 14.331 100 25 50 1 1384257296 8548208 5215920
01:10:01 79870 8192000000 10307128352 10762805248 1360214816 14.239 98 24 48 0 1376063792 11126480 4722496
01:12:22 106526 8192000000 10300155200 10762547200 1328818288 13.956 95 8 16 0 1342102096 11471456 1812352
01:15:09 18242 8192000000 10315604624 10763730944 1393121264 14.534 100 37 74 1 1411738480 10256096 8361120
01:16:30 119473 8192000000 10306983072 10769731584 1358667152 14.225 97 8 16 0 1375108624 14913888 1527584
01:19:32 72119 8192000000 10305748112 10769027072 1400747744 14.602 100 22 44 0 1415087232 9363184 4976304
01:21:44 47779 8192000000 10284549664 10751782912 1380705728 14.423 99 14 24 1 1390587936 6702016 3180192
01:23:39 77957 8192000000 10289699936 10762969088 1382393008 14.438 99 10 18 0 1394734080 9968864 2372208
01:26:29 60406 8192000000 10297812528 10764406784 1378751584 14.405 99 17 33 0 1397915248 15656976 3506688
01:28:36 46161 8192000000 10288365328 10740330496 1345813808 14.110 97 13 22 0 1355610592 7032528 2764256
01:30:22 83984 8192000000 10291245392 10749366272 1365880848 14.290 99 14 26 0 1381656224 12864704 2910672
01:32:46 63624 8192000000 10259113664 10711732224 1277708496 13.492 93 11 22 0 1296437312 16146240 2582576
01:35:09 30638 8192000000 10266188688 10699030528 1276499648 13.481 93 26 52 0 1289657424 7265904 5891872
01:36:41 94160 8192000000 10270817216 10712080384 1310048752 13.787 97 33 66 0 1328518448 11797056 6672640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-02-2023 01:39:26 to 06-02-2023 01:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2023 01:39:42 to 06-02-2023 01:39:44.

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          242776
MON$OLDEST_ACTIVE               244960
MON$OLDEST_SNAPSHOT             244960
MON$NEXT_TRANSACTION            244960
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                       2033376
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Feb  6 01:38:45 2023
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
--- end of diff output ---

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

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