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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-09 16:38:27
DTS_END                         2022-07-09 16:38:27.3150
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/38568
TRN_ID                          244522
ATT_ID                          730
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: 9356.46 23522 1122775
customer order: creation 174.38 374 20925
customer order: refuse 15.10 354 1812
order to supplier: creation 47.28 1257 5673
order to supplier: removal 3.37 100567 404
invoice (draft): creation 46.97 3461 5636
invoice (draft): removal 52.58 808 6310
invoice accept: apply 42.91 17086 5149
invoice accept: cancel 47.68 787 5722
customer reserve: creation 3923.19 170 470783
customer reserve: removal 4699.33 56 563920
realization accept: apply 84.69 158 10163
realization accept: cancel 85.71 101 10285
payment from customer: creation 61.39 112 7367
payment from customer: removal 5.76 83 691
payment to supplier: creation 49.73 124 5967
payment to supplier: removal 3.58 82 430
service: total inventory turnovers 4.72 936 566
service: total monetary turnovers 7.32 45 878
service: aggregate perf. data 0.75 25670 90
service: refresh index statistics 0.03 318210 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8594
TEST_TIME 2 5715
TEST_TIME 3 5556
TEST_TIME 4 5571
TEST_TIME 5 5469
TEST_TIME 6 5676
TEST_TIME 7 5765
TEST_TIME 8 5866
TEST_TIME 9 5835
TEST_TIME 10 6242
TEST_TIME 11 6379
TEST_TIME 12 6763
TEST_TIME 13 7129
TEST_TIME 14 7018
TEST_TIME 15 6999
TEST_TIME 16 7021
TEST_TIME 17 7314
TEST_TIME 18 7257
TEST_TIME 19 7581
TEST_TIME 20 7920
TEST_TIME 21 8116
TEST_TIME 22 9020
TEST_TIME 23 9272
TEST_TIME 24 9253
TEST_TIME 25 9164
TEST_TIME 26 9316
TEST_TIME 27 9832
TEST_TIME 28 10446
TEST_TIME 29 11052
TEST_TIME 30 10899
TEST_TIME 31 11020
TEST_TIME 32 11032
TEST_TIME 33 10994
TEST_TIME 34 10790
TEST_TIME 35 10795
TEST_TIME 36 10702
TEST_TIME 37 10534
TEST_TIME 38 10528
TEST_TIME 39 10430
TEST_TIME 40 10289
TEST_TIME 41 10222
TEST_TIME 42 10146
TEST_TIME 43 10151
TEST_TIME 44 10163
TEST_TIME 45 10106
TEST_TIME 46 9972
TEST_TIME 47 9991
TEST_TIME 48 10152
TEST_TIME 49 9991
TEST_TIME 50 9994
TEST_TIME 51 9917
TEST_TIME 52 9876
TEST_TIME 53 9955
TEST_TIME 54 9925
TEST_TIME 55 9873
TEST_TIME 56 9937
TEST_TIME 57 9902
TEST_TIME 58 9874
TEST_TIME 59 10032
TEST_TIME 60 10018
TEST_TIME 61 10084
TEST_TIME 62 10041
TEST_TIME 63 10185
TEST_TIME 64 10170
TEST_TIME 65 10114
TEST_TIME 66 10107
TEST_TIME 67 9983
TEST_TIME 68 9925
TEST_TIME 69 9895
TEST_TIME 70 9807
TEST_TIME 71 9796
TEST_TIME 72 9730
TEST_TIME 73 9674
TEST_TIME 74 9626
TEST_TIME 75 9600
TEST_TIME 76 9532
TEST_TIME 77 9526
TEST_TIME 78 9514
TEST_TIME 79 9512
TEST_TIME 80 9567
TEST_TIME 81 9559
TEST_TIME 82 9522
TEST_TIME 83 9594
TEST_TIME 84 9574
TEST_TIME 85 9637
TEST_TIME 86 9674
TEST_TIME 87 9696
TEST_TIME 88 9703
TEST_TIME 89 9772
TEST_TIME 90 9726
TEST_TIME 91 9645
TEST_TIME 92 9616
TEST_TIME 93 9549
TEST_TIME 94 9579
TEST_TIME 95 9527
TEST_TIME 96 9513
TEST_TIME 97 9469
TEST_TIME 98 9467
TEST_TIME 99 9532
TEST_TIME 100 9546
TEST_TIME 101 9611
TEST_TIME 102 9613
TEST_TIME 103 9643
TEST_TIME 104 9621
TEST_TIME 105 9669
TEST_TIME 106 9631
TEST_TIME 107 9635
TEST_TIME 108 9691
TEST_TIME 109 9647
TEST_TIME 110 9600
TEST_TIME 111 9559
TEST_TIME 112 9531
TEST_TIME 113 9511
TEST_TIME 114 9469
TEST_TIME 115 9441
TEST_TIME 116 9422
TEST_TIME 117 9406
TEST_TIME 118 9388
TEST_TIME 119 9353
TEST_TIME 120 9359

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-07-2022 16:39:56 to 09-07-2022 16:40:00.

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 1559241 1559241 0 0.00 0 12600 6 0 0
doc_list_biud 1559241 1559241 0 0.00 0 31929 26 0 0
sp_add_invoice_to_stock 5240 5149 91 1.73 31 171726 17086 0 91
sp_cancel_adding_invoice 5722 5722 0 0.00 4 70631 788 0 0
sp_cancel_client_order 1812 1812 0 0.00 5 7497 355 0 0
sp_cancel_customer_reserve 563920 563920 0 0.00 0 30731 56 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 8937 84 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 3413 83 0 0
sp_cancel_supplier_invoice 6318 6310 8 0.12 1 26873 808 0 8
sp_cancel_supplier_order 404 404 0 0.00 5 1203091 100568 0 0
sp_cancel_write_off 10285 10285 0 0.00 1 8435 102 0 0
sp_client_order 20925 20925 0 0.00 3 41453 375 0 0
sp_customer_reserve 470783 470783 0 0.00 0 68051 170 0 0
sp_fill_shopping_cart 32233 32233 0 0.00 1 17010 42 0 0
sp_fill_shopping_cart_clo_res 470782 470782 0 0.00 0 16570 13 0 0
sp_get_clo_for_invoice 5146 5146 0 0.00 0 27508 274 0 0
sp_kill_cost_storno 17128 17128 0 0.00 0 12338 54 0 0
sp_kill_qstorno_ret_qs2qd 1144839 1144839 0 0.00 0 30684 30 0 0
sp_kill_qty_storno 603762 603762 0 0.00 0 31928 66 0 0
sp_lock_selected_doc 21170 21170 0 0.00 0 7403 5 0 0
sp_make_cost_storno 28643 28643 0 0.00 0 12341 53 0 0
sp_multiply_rows_for_pdistr 28643 28643 0 0.00 0 7481 27 0 0
sp_multiply_rows_for_qdistr 20925 20925 0 0.00 0 18755 177 0 0
sp_pay_from_customer 7367 7367 0 0.00 4 11960 112 0 0
sp_pay_to_supplier 5967 5967 0 0.00 4 8462 125 0 0
sp_payment_common 13334 13334 0 0.00 1 6879 24 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 7491 327 0 0
sp_qd_handle_on_invoice_upd_sts 10868 10868 0 0.00 0 31925 335 0 0
sp_qd_handle_on_reserve_upd_sts 10163 10163 0 0.00 0 11426 49 0 0
sp_reserve_write_off 10163 10163 0 0.00 6 12357 158 0 0
sp_supplier_invoice 5636 5636 0 0.00 5 84303 3462 0 0
sp_supplier_order 5673 5673 0 0.00 4 33827 1257 0 0
srv_aggregate_perf_data 90 90 0 0.00 2822 444586 25670 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 850 125087 43471 0 0
srv_find_qd_qs_mism 1074275 1074275 0 0.00 0 12584 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 41 29712 936 0 0
srv_make_money_saldo 878 878 0 0.00 0 12902 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 77462 549297 318210 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5149 5149 0 0.00 0 28468 192 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 3118 73 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 1758 13 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1400 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 766 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 150 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1359 19 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 26580 1736 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 2110 8 0 0
t$perf-norm:sp_client_order 20925 20925 0 0.00 0 29080 19 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 319 3 0 0
t$perf-norm:sp_pay_from_customer 7367 7367 0 0.00 0 10444 11 0 0
t$perf-norm:sp_pay_to_supplier 5967 5967 0 0.00 0 4390 9 0 0
t$perf-norm:sp_reserve_write_off 10163 10163 0 0.00 0 23656 15 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 10337 23 0 0
t$perf-norm:sp_supplier_order 5673 5673 0 0.00 0 9381 16 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 131 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 2296 32 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 4286 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10474 10292 182 1.73 0 7430 20 0 182
v_all_customers 38294 38294 0 0.00 0 157 1 0 0
v_all_suppliers 31096 31096 0 0.00 0 19 1 0 0
v_all_wares 41850 41850 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4740 18 0 0
v_cancel_client_order 3624 3624 0 0.00 0 1795 3 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 2236 7 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 2240 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3395 12 0 0
v_cancel_supplier_invoice 884 868 16 1.80 0 1784 16 0 16
v_cancel_supplier_order 808 808 0 0.00 0 721 4 0 0
v_cancel_write_off 1988 1988 0 0.00 0 6360 16 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 210 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 2459 13 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 6055 6 0 0
v_max_non_paid_invoice 1727 1727 0 0.00 0 191 2 0 0
v_max_non_paid_realizn 3831 3831 0 0.00 0 810 3 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 5239 9 0 0
v_min_id_clo_res 985 985 0 0.00 0 1170 4 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 3802 6 0 0
v_min_non_paid_invoice 5967 5967 0 0.00 0 4985 16 0 0
v_min_non_paid_realizn 7367 7367 0 0.00 0 6120 19 0 0
v_our_firm 10628 10628 0 0.00 0 16 1 0 0
v_reserve_write_off 20326 20326 0 0.00 0 6895 6 0 0
x_lock_depdocs_on_canc_invoice 11598 11598 0 0.00 0 19879 115 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 3862 345 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 1 33813 1178 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 2 84277 3305 0 0
x_make_qstorno_2100_3300 470783 470783 0 0.00 0 68050 154 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:30 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:31 20394 8192000000 9890889840 10194698240 974769280 10.633 100 86 172 1 1003951216 5533392 23648544
14:10:56 24460 8192000000 9952059104 10271948800 1026246528 11.132 100 85 170 0 1055797072 7475760 22074784
14:12:26 12644 8192000000 9984824528 10315083776 1068656208 11.539 100 86 172 0 1099537088 9694528 21186352
14:13:46 12319 8192000000 10035456704 10381336576 1123253216 12.058 100 80 160 2 1152554224 10204992 19096016
14:15:01 7394 8192000000 10093788064 10451476480 1182516144 12.614 100 78 154 0 1211710000 11703536 17490320
14:16:11 4205 8192000000 10128073056 10496892928 1217708816 12.940 100 83 165 0 1250838640 14294432 18835392
14:17:19 2012 8192000000 10161542208 10537799680 1252167168 13.258 100 81 162 0 1282452800 12549312 17736320
14:18:22 704 8192000000 10197218960 10575687680 1280242240 13.515 100 93 184 2 1314725392 14006944 20476208
14:19:25 1198 8192000000 10222052464 10608873472 1307099936 13.760 100 85 166 0 1339160448 13700016 18360496
14:20:28 33848 8192000000 10198488960 10610548736 1313120128 13.814 100 30 60 0 1326580064 6424032 7035904
14:22:02 5574 8192000000 10213439344 10625552384 1323199648 13.906 100 28 56 0 1336558208 7288576 6069984
14:23:12 12163 8192000000 10213355600 10629517312 1330358816 13.970 100 21 42 1 1344747328 9812816 4575696
14:24:27 9240 8192000000 10221286912 10638430208 1333951504 14.003 100 21 43 1 1344908608 6227456 4729648
14:25:43 53464 8192000000 10250578848 10657546240 1337847568 14.038 100 36 72 0 1351102896 5243440 8011888
14:27:38 7395 8192000000 10228832176 10647257088 1325924096 13.930 99 23 45 1 1336875632 5773120 5178416
14:28:46 6747 8192000000 10222649264 10632351744 1320335936 13.880 100 31 61 0 1333253360 5807648 7109776
14:29:54 66327 8192000000 10125429296 10526781440 1131004688 12.131 88 25 48 1 1141053936 4674336 5374912
14:32:04 9101 8192000000 10125524512 10522501120 1224229792 13.001 99 23 44 0 1239242848 9524928 5488128
14:33:15 8203 8192000000 10153788128 10553466880 1259013664 13.321 100 22 42 1 1269497776 5133552 5350560
14:34:26 58851 8192000000 10172351792 10583638016 1282579392 13.537 100 30 60 2 1295127792 5523936 7024464
14:36:26 6476 8192000000 10187400112 10601967616 1298826256 13.685 100 27 51 1 1309731808 5430240 5475312
14:37:33 6392 8192000000 10194594352 10609905664 1304718848 13.738 100 17 32 0 1313161792 4713856 3729088
14:38:41 6651 8192000000 10241385360 10649804800 1335218176 14.014 100 57 113 0 1353574064 5941008 12414880
14:39:50 24711 8192000000 10254724592 10668843008 1336345552 14.024 99 53 103 0 1357230256 8821376 12063328
14:41:30 3272 8192000000 10276928000 10690420736 1366189104 14.293 100 57 113 2 1392863568 14093920 12580544
14:42:36 36326 8192000000 10289934208 10710614016 1376102496 14.382 100 47 96 0 1403426240 16221232 11102512
14:44:19 5857 8192000000 10303341120 10720608256 1376900144 14.389 99 56 112 0 1409548944 19333952 13314848
14:45:30 6393 8192000000 10324118048 10743980032 1400135888 14.596 100 55 111 1 1436117552 22801808 13179856
14:46:50 8213 8192000000 10344367648 10764177408 1411724256 14.699 100 56 108 2 1452307136 27555936 13026944
14:48:09 20828 8192000000 10334994448 10768216064 1408526960 14.671 100 40 80 0 1449156512 30641488 9988064
14:49:33 10981 8192000000 10337098304 10760982528 1398899840 14.585 100 55 108 1 1446925440 34336272 13689328
14:50:49 9093 8192000000 10344983264 10774757376 1400706592 14.601 100 45 91 0 1451253920 39051552 11495776
14:52:01 4999 8192000000 10335377232 10762166272 1388094480 14.489 99 55 108 0 1433357312 31901504 13361328
14:53:12 3207 8192000000 10337383568 10756780032 1394405824 14.545 100 60 120 1 1444352304 35357824 14588656
14:54:21 4273 8192000000 10339610896 10761322496 1389186272 14.499 99 51 101 0 1444225664 42279072 12760320
14:55:35 848 8192000000 10341437216 10739937280 1371155200 14.337 100 89 175 0 1440899376 48093424 21650752
14:56:41 9337 8192000000 10338515728 10747031552 1368412160 14.313 100 72 143 0 1441086784 54480112 18194512
14:57:56 1202 8192000000 10333386992 10735468544 1361693728 14.253 99 85 164 0 1434867248 52419552 20753968
14:59:02 2470 8192000000 10344205760 10752319488 1378586688 14.404 100 73 146 0 1452509888 55105920 18817280
15:00:14 1978 8192000000 10360816208 10768273408 1379731888 14.414 100 79 158 1 1459440640 60044784 19663968
15:01:20 8526 8192000000 10281651376 10701213696 1326286192 13.934 97 39 78 0 1375460288 38439104 10734992
15:02:34 22702 8192000000 10305204080 10734706688 1365078016 14.283 100 24 48 0 1410310624 38823584 6409024
15:03:59 20773 8192000000 10314479296 10746576896 1361141008 14.248 100 25 49 0 1417129552 48871952 7116592
15:05:25 19672 8192000000 10313714320 10750951424 1338279664 14.042 98 27 52 0 1404450688 58226096 7944928
15:07:55 40750 8192000000 10270676560 10704691200 1336247552 14.024 98 17 34 0 1355026544 14863280 3915712
15:09:39 125087 8192000000 10290658288 10737553408 1356253824 14.204 99 7 14 1 1385418592 27741952 1422816
15:13:46 112424 8192000000 10283459040 10734026752 1365969760 14.291 99 22 44 0 1383046016 11757280 5318976
15:16:52 55488 8192000000 10295138432 10751025152 1377031056 14.390 100 32 64 1 1407742112 23744144 6966912
15:18:55 35517 8192000000 10288969392 10731753472 1364386224 14.277 99 32 63 1 1381076016 9595968 7093824
15:20:36 31088 8192000000 10308149728 10745454592 1382020464 14.435 100 42 78 4 1401478432 10568864 8889104
15:22:08 22466 8192000000 10298371840 10760511488 1393979264 14.541 100 16 32 0 1413974800 15932624 4062912
15:23:31 67337 8192000000 10311458848 10774618112 1400961680 14.604 100 13 26 0 1421380528 17326192 3092656
15:25:42 42537 8192000000 10302014320 10769891328 1396735968 14.566 100 18 36 0 1421196256 19600896 4859392
15:27:50 31957 8192000000 10306798304 10769395712 1405122768 14.641 100 20 40 0 1422993776 13604560 4266448
15:29:23 40163 8192000000 10308948320 10783318016 1402986896 14.622 100 14 28 0 1425154960 18712608 3455456
15:31:20 65281 8192000000 10304017136 10767454208 1389197232 14.499 99 14 28 0 1406927888 14547280 3183376
15:33:30 74070 8192000000 10287773808 10752995328 1336033152 14.022 95 11 22 0 1351450928 12761664 2656112
15:35:44 38514 8192000000 10296468416 10754760704 1382326736 14.437 100 31 62 0 1404088576 14667008 7094832
15:37:30 69397 8192000000 10268911424 10713022464 1270430224 13.426 92 9 18 0 1283800816 11592128 1778464
15:39:43 45907 8192000000 10269580496 10706714624 1295819520 13.657 94 27 55 0 1315553936 13896272 5838144
15:41:29 96884 8192000000 10266224688 10714746880 1309979696 13.786 96 9 18 0 1325476016 13895040 1601280
15:44:09 76771 8192000000 10280389760 10720718848 1343983904 14.093 98 29 58 0 1360377696 9659152 6734640
15:46:42 43918 8192000000 10269988784 10733375488 1378150848 14.400 100 39 78 1 1397062704 10106624 8805232
15:48:28 71691 8192000000 10256995088 10716565504 1326643232 13.937 97 12 24 0 1344358864 14573152 3142480
15:51:00 46853 8192000000 10244876688 10699571200 1315587200 13.837 97 14 28 0 1330145424 11487056 3071168
15:52:52 71449 8192000000 10243857584 10702348288 1332019072 13.985 98 16 32 0 1352284208 16774608 3490528
15:55:19 58492 8192000000 10258226896 10710798336 1313333520 13.816 96 9 16 0 1330697456 15092368 2271568
15:57:17 39354 8192000000 10256426128 10702966784 1334928944 14.012 99 33 66 2 1359803072 17024752 7849376
15:58:57 75442 8192000000 10262191856 10710794240 1337818880 14.038 98 14 28 0 1349563632 9104912 2639840
16:01:18 46820 8192000000 10279150176 10741170176 1367590384 14.305 100 24 48 1 1385248528 12722288 4935856
16:03:11 60886 8192000000 10273476976 10736087040 1352877808 14.173 99 15 30 0 1368069728 11627216 3564704
16:05:14 88827 8192000000 10285778832 10746314752 1340334208 14.060 97 20 41 0 1360500016 15983696 4182112
16:07:46 113908 8192000000 10287244992 10741346304 1375073728 14.372 99 12 24 0 1389703296 12311888 2317680
16:10:46 45778 8192000000 10313396288 10769911808 1403857280 14.629 100 32 64 0 1426794448 16232928 6704240
16:12:32 42354 8192000000 10294314368 10770292736 1399231824 14.588 100 9 18 1 1413543664 12372960 1938880
16:14:17 80213 8192000000 10307405648 10771312640 1374550752 14.368 98 10 20 0 1386086400 9379008 2156640
16:16:37 57698 8192000000 10317289888 10781003776 1399764496 14.593 99 18 36 0 1416715424 12650304 4300624
16:18:35 72389 8192000000 10299992016 10779856896 1387551456 14.484 99 14 26 0 1406158704 15252096 3355152
16:20:48 19434 8192000000 10312339056 10755670016 1384418352 14.456 99 54 105 2 1404386304 8743120 11224832
16:22:11 18379 8192000000 10292935664 10740232192 1348164784 14.131 97 29 58 1 1362511728 7737552 6609392
16:23:37 81547 8192000000 10288716608 10737561600 1327763520 13.947 96 21 42 0 1345993808 13650688 4579600
16:26:07 96983 8192000000 10287127136 10729738240 1316379568 13.844 95 38 76 0 1337008096 12482384 8146144
16:28:44 61885 8192000000 10257934640 10716958720 1351011280 14.157 99 40 80 0 1369128592 9013456 9103856
16:31:16 53512 8192000000 10259431872 10719322112 1342367712 14.079 98 13 26 0 1355373280 10301696 2703872
16:33:10 43035 8192000000 10257713344 10711617536 1342786944 14.083 99 10 21 0 1359796176 14315984 2693248
16:34:53 68220 8192000000 10255106336 10716377088 1338634880 14.045 98 18 36 1 1357944512 15099440 4210192
16:37:09 62571 8192000000 10260390016 10718789632 1328004800 13.949 97 13 26 0 1343989968 12882176 3102992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-07-2022 16:40:07 to 09-07-2022 16:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-07-2022 16:40:23 to 09-07-2022 16:40:25.

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          243608
MON$OLDEST_ACTIVE               244801
MON$OLDEST_SNAPSHOT             244801
MON$NEXT_TRANSACTION            244801
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                       2052320
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:164: 
:165: mscs-test-161.budzdorov.ru    Sat Jul  9 16:38:41 2022
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
--- end of diff output ---

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

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