Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2021 13: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 16341796 kB
MemAvailable 31395944 kB
Buffers 0 kB
Cached 15378176 kB
SwapCached 2388 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2021-12-30 13:37:10
DTS_END                         2021-12-30 13:37:10.2690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57704
TRN_ID                          241108
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: 9305.89 15973 1116707
customer order: creation 176.82 401 21218
customer order: refuse 15.02 334 1802
order to supplier: creation 47.83 1419 5740
order to supplier: removal 3.37 105369 404
invoice (draft): creation 47.37 3514 5684
invoice (draft): removal 51.45 870 6174
invoice accept: apply 43.69 18321 5243
invoice accept: cancel 46.49 834 5579
customer reserve: creation 3980.53 184 477664
customer reserve: removal 4593.20 61 551184
realization accept: apply 85.76 194 10291
realization accept: cancel 83.51 111 10021
payment from customer: creation 60.13 134 7216
payment from customer: removal 5.84 113 701
payment to supplier: creation 50.33 148 6040
payment to supplier: removal 3.62 71 434
service: total inventory turnovers 3.80 3795 456
service: total monetary turnovers 6.14 493 737
service: aggregate perf. data 0.96 14216 115
service: refresh index statistics 0.03 168879 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2021 13:38:52 to 30-12-2021 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8073
TEST_TIME 2 6836
TEST_TIME 3 7230
TEST_TIME 4 6582
TEST_TIME 5 6138
TEST_TIME 6 6434
TEST_TIME 7 6947
TEST_TIME 8 6720
TEST_TIME 9 7082
TEST_TIME 10 7383
TEST_TIME 11 7743
TEST_TIME 12 8038
TEST_TIME 13 8222
TEST_TIME 14 8234
TEST_TIME 15 8027
TEST_TIME 16 8439
TEST_TIME 17 8417
TEST_TIME 18 9066
TEST_TIME 19 9092
TEST_TIME 20 9052
TEST_TIME 21 9477
TEST_TIME 22 9959
TEST_TIME 23 9979
TEST_TIME 24 9957
TEST_TIME 25 10295
TEST_TIME 26 10029
TEST_TIME 27 10253
TEST_TIME 28 10349
TEST_TIME 29 10586
TEST_TIME 30 11485
TEST_TIME 31 11482
TEST_TIME 32 11445
TEST_TIME 33 11301
TEST_TIME 34 11247
TEST_TIME 35 11270
TEST_TIME 36 11051
TEST_TIME 37 10988
TEST_TIME 38 11138
TEST_TIME 39 10903
TEST_TIME 40 10683
TEST_TIME 41 10710
TEST_TIME 42 10580
TEST_TIME 43 10524
TEST_TIME 44 10423
TEST_TIME 45 10374
TEST_TIME 46 10380
TEST_TIME 47 10345
TEST_TIME 48 10340
TEST_TIME 49 10263
TEST_TIME 50 10205
TEST_TIME 51 10105
TEST_TIME 52 9993
TEST_TIME 53 9890
TEST_TIME 54 9848
TEST_TIME 55 9874
TEST_TIME 56 9856
TEST_TIME 57 9881
TEST_TIME 58 9883
TEST_TIME 59 9851
TEST_TIME 60 9903
TEST_TIME 61 9910
TEST_TIME 62 9979
TEST_TIME 63 9991
TEST_TIME 64 10033
TEST_TIME 65 10032
TEST_TIME 66 10044
TEST_TIME 67 10087
TEST_TIME 68 10148
TEST_TIME 69 10142
TEST_TIME 70 10119
TEST_TIME 71 10151
TEST_TIME 72 10083
TEST_TIME 73 10007
TEST_TIME 74 9969
TEST_TIME 75 9942
TEST_TIME 76 9930
TEST_TIME 77 9873
TEST_TIME 78 9813
TEST_TIME 79 9781
TEST_TIME 80 9769
TEST_TIME 81 9709
TEST_TIME 82 9707
TEST_TIME 83 9735
TEST_TIME 84 9756
TEST_TIME 85 9723
TEST_TIME 86 9751
TEST_TIME 87 9743
TEST_TIME 88 9747
TEST_TIME 89 9842
TEST_TIME 90 9818
TEST_TIME 91 9794
TEST_TIME 92 9793
TEST_TIME 93 9821
TEST_TIME 94 9735
TEST_TIME 95 9691
TEST_TIME 96 9656
TEST_TIME 97 9620
TEST_TIME 98 9551
TEST_TIME 99 9547
TEST_TIME 100 9493
TEST_TIME 101 9505
TEST_TIME 102 9506
TEST_TIME 103 9482
TEST_TIME 104 9469
TEST_TIME 105 9488
TEST_TIME 106 9474
TEST_TIME 107 9550
TEST_TIME 108 9543
TEST_TIME 109 9542
TEST_TIME 110 9534
TEST_TIME 111 9571
TEST_TIME 112 9616
TEST_TIME 113 9604
TEST_TIME 114 9525
TEST_TIME 115 9533
TEST_TIME 116 9491
TEST_TIME 117 9434
TEST_TIME 118 9409
TEST_TIME 119 9366
TEST_TIME 120 9313

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-12-2021 13:38:55 to 30-12-2021 13:38:59.

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 1557234 1557234 0 0.00 0 22896 7 0 0
doc_list_biud 1557234 1557234 0 0.00 0 58025 27 0 0
sp_add_invoice_to_stock 5311 5243 68 1.28 27 162975 18321 0 68
sp_cancel_adding_invoice 5579 5579 0 0.00 6 75192 835 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 12998 335 0 0
sp_cancel_customer_reserve 551184 551184 0 0.00 1 58027 61 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 8070 113 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 1845 71 0 0
sp_cancel_supplier_invoice 6176 6174 2 0.03 2 14224 871 0 2
sp_cancel_supplier_order 404 404 0 0.00 7 970303 105369 0 0
sp_cancel_write_off 10021 10021 0 0.00 1 8797 112 0 0
sp_client_order 21218 21218 0 0.00 4 29323 401 0 0
sp_customer_reserve 477664 477664 0 0.00 0 41238 185 0 0
sp_fill_shopping_cart 32629 32629 0 0.00 1 9147 43 0 0
sp_fill_shopping_cart_clo_res 477651 477651 0 0.00 0 20201 14 0 0
sp_get_clo_for_invoice 5230 5230 0 0.00 0 25425 294 0 0
sp_kill_cost_storno 16735 16735 0 0.00 0 17338 58 0 0
sp_kill_qstorno_ret_qs2qd 1118967 1118967 0 0.00 0 55903 33 0 0
sp_kill_qty_storno 590685 590685 0 0.00 0 58024 71 0 0
sp_lock_selected_doc 21354 21354 0 0.00 0 7200 4 0 0
sp_make_cost_storno 28777 28777 0 0.00 0 18879 64 0 0
sp_multiply_rows_for_pdistr 28777 28777 0 0.00 0 14315 34 0 0
sp_multiply_rows_for_qdistr 21218 21218 0 0.00 0 19374 184 0 0
sp_pay_from_customer 7216 7216 0 0.00 4 17734 134 0 0
sp_pay_to_supplier 6040 6040 0 0.00 5 18808 148 0 0
sp_payment_common 13256 13256 0 0.00 1 18750 30 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 12990 297 0 0
sp_qd_handle_on_invoice_upd_sts 10809 10809 0 0.00 0 30760 343 0 0
sp_qd_handle_on_reserve_upd_sts 10291 10291 0 0.00 0 12899 51 0 0
sp_reserve_write_off 10291 10291 0 0.00 7 22169 195 0 0
sp_supplier_invoice 5684 5684 0 0.00 8 65547 3515 0 0
sp_supplier_order 5740 5740 0 0.00 6 47188 1420 0 0
srv_aggregate_perf_data 115 115 0 0.00 3259 131490 14216 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1236 107852 41556 0 0
srv_find_qd_qs_mism 1066775 1066775 0 0.00 0 21961 6 0 0
srv_make_invnt_saldo 456 456 0 0.00 54 95615 3795 0 0
srv_make_money_saldo 737 737 0 0.00 1 17297 494 0 0
srv_recalc_idx_stat 4 4 0 0.00 134260 271694 168880 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5243 5243 0 0.00 0 10345 160 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5960 132 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 16273 23 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4066 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 828 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 798 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2319 20 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 17439 1660 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 10542 17 0 0
t$perf-norm:sp_client_order 21218 21218 0 0.00 0 8336 17 0 0
t$perf-norm:sp_customer_reserve 1077 1077 0 0.00 0 679 3 0 0
t$perf-norm:sp_pay_from_customer 7216 7216 0 0.00 0 10054 11 0 0
t$perf-norm:sp_pay_to_supplier 6040 6040 0 0.00 0 10563 11 0 0
t$perf-norm:sp_reserve_write_off 10291 10291 0 0.00 0 8037 9 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 9811 20 0 0
t$perf-norm:sp_supplier_order 5740 5740 0 0.00 0 4823 15 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 702 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5302 104 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 7130 33 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 6142 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10592 10456 136 1.28 0 16201 20 0 136
v_all_customers 38398 38398 0 0.00 0 21 1 0 0
v_all_suppliers 31642 31642 0 0.00 0 14 1 0 0
v_all_wares 42436 42436 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2258 10 0 0
v_cancel_client_order 3604 3604 0 0.00 0 7242 10 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1965 8 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 263 3 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 1145 5 0 0
v_cancel_supplier_invoice 816 812 4 0.49 0 6475 29 0 4
v_cancel_supplier_order 808 808 0 0.00 0 8634 18 0 0
v_cancel_write_off 2012 2012 0 0.00 0 2991 17 0 0
v_max_id_clo_ord 5737 5737 0 0.00 0 8742 3 0 0
v_max_id_clo_res 1077 1077 0 0.00 0 1416 10 0 0
v_max_id_ord_sup 5674 5674 0 0.00 0 4368 4 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 533 2 0 0
v_max_non_paid_realizn 4092 4092 0 0.00 0 5400 6 0 0
v_min_id_clo_ord 5737 5737 0 0.00 0 4409 6 0 0
v_min_id_clo_res 1077 1077 0 0.00 0 6164 22 0 0
v_min_id_ord_sup 5674 5674 0 0.00 0 8284 10 0 0
v_min_non_paid_invoice 6040 6040 0 0.00 0 17830 21 0 0
v_min_non_paid_realizn 7216 7216 0 0.00 0 17715 22 0 0
v_our_firm 10286 10286 0 0.00 0 18 1 0 0
v_reserve_write_off 20582 20582 0 0.00 0 15141 10 0 0
x_lock_depdocs_on_canc_invoice 11347 11347 0 0.00 0 12839 105 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 11255 351 0 0
x_make_qstorno_1000_1200 5740 5740 0 0.00 2 47125 1338 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 4 63487 3355 0 0
x_make_qstorno_2100_3300 477664 477664 0 0.00 0 41236 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2021 13:39:00 to 30-12-2021 13:39:04.

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
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 21451 8192000000 9722258032 10020188160 803000048 8.927 100 87 174 0 831406912 7346736 21060128
11:09:44 18202 8192000000 9774874192 10083078144 852703280 9.427 100 89 178 0 885730176 12250960 20775936
11:11:05 17959 8192000000 9806992448 10126635008 876937552 9.669 100 87 174 0 914619856 17860688 19821616
11:12:25 15851 8192000000 9865642608 10200682496 912935232 10.026 100 79 158 0 978502288 47776656 17790400
11:13:44 8949 8192000000 9915922336 10257100800 955666352 10.447 100 85 169 0 1026423120 52737120 18019648
11:14:56 4250 8192000000 9965098992 10319134720 988099728 10.763 100 74 146 1 1075782416 72511536 15171152
11:16:02 2666 8192000000 9988081584 10348830720 1022500480 11.096 100 89 176 0 1103273120 63031376 17741264
11:17:07 1862 8192000000 9991705984 10359930880 1044712240 11.310 100 82 165 1 1107440912 46140864 16587808
11:18:11 1764 8192000000 10026927888 10401030144 1064722368 11.502 100 84 165 3 1143356880 62774576 15859936
11:19:14 4757 8192000000 9993639088 10378059776 1065898624 11.513 100 39 76 2 1103640496 30037216 7704656
11:20:21 6519 8192000000 9993453664 10386489344 1082267520 11.669 100 32 67 1 1107436288 17986800 7181968
11:21:28 7015 8192000000 10002060960 10403889152 1085302240 11.698 100 23 47 1 1111000032 20919904 4777888
11:22:40 32353 8192000000 10008207504 10396639232 1089620400 11.739 100 54 110 1 1111800080 10816384 11363296
11:24:13 3188 8192000000 10052931968 10442178560 1095126864 11.791 100 66 130 1 1153705136 43481792 15096480
11:25:17 5505 8192000000 10006681984 10415554560 1073360496 11.584 98 25 49 0 1106502432 27989584 5152352
11:26:24 7759 8192000000 10003967680 10422685696 1081131056 11.658 99 20 40 1 1130774400 45519024 4124320
11:27:37 7298 8192000000 9985033520 10377539584 1068059392 11.534 98 30 60 4 1090455216 16137472 6258352
11:28:45 4654 8192000000 9963148896 10343849984 1017791280 11.051 96 47 92 0 1044876288 17621264 9463744
11:29:51 44330 8192000000 9920759072 10293116928 922059392 10.116 88 44 89 0 940831776 9640016 9132368
11:31:37 5195 8192000000 9931606288 10307829760 1004997968 10.927 97 49 98 0 1027639984 11233040 11408976
11:32:44 3765 8192000000 9962074720 10342936576 1043591312 11.299 100 47 92 2 1067768848 14055456 10122080
11:33:49 7670 8192000000 9964270576 10353045504 1051439728 11.374 100 32 64 0 1078283888 19612176 7231984
11:34:57 40205 8192000000 9969914368 10377748480 1063372768 11.489 100 30 59 0 1084112304 14120864 6618672
11:36:38 7934 8192000000 9993703952 10393530368 1074507120 11.595 100 39 76 1 1101503648 18450384 8546144
11:38:02 48751 8192000000 10067129024 10469019648 1109177744 11.925 100 49 98 4 1179866608 61215072 9473792
11:39:55 50654 8192000000 10072470480 10490335232 1129586880 12.117 100 34 66 0 1206920960 69016688 8317392
11:41:49 17134 8192000000 10107505232 10515296256 1142100176 12.235 100 45 88 0 1208210592 56679824 9430592
11:43:15 4654 8192000000 10142766224 10550116352 1153058080 12.338 100 61 120 2 1244491280 78682448 12750752
11:44:23 17802 8192000000 10191727760 10614312960 1165437712 12.454 100 51 102 0 1295659600 118250000 11971888
11:45:47 2517 8192000000 10213191920 10613501952 1164490240 12.445 100 73 145 3 1315841296 136104496 15246560
11:46:54 3597 8192000000 10230555920 10639888384 1174808672 12.542 100 78 155 2 1330522544 140061488 15652384
11:48:02 7914 8192000000 10252771296 10669199360 1176298480 12.556 100 57 114 1 1353383376 163876784 13208112
11:49:12 4824 8192000000 10278648048 10696761344 1178138256 12.573 100 73 145 0 1372744272 178958272 15647744
11:50:26 6134 8192000000 10236553024 10649161728 1166772096 12.467 100 60 119 1 1345623440 165606592 13244752
11:51:39 5911 8192000000 10245788464 10674675712 1170856864 12.505 100 47 93 0 1353650448 172031760 10761824
11:52:47 8177 8192000000 10298893456 10704969728 1160582000 12.409 100 74 146 1 1392274224 215796208 15896016
11:53:58 18369 8192000000 10299267328 10732466176 1147702624 12.288 100 35 70 2 1402498880 246227472 8568784
11:55:19 7516 8192000000 10319383088 10729988096 1137268608 12.190 100 52 103 3 1422337312 272637536 12431168
11:56:33 1234 8192000000 10293053056 10695933952 1137824480 12.195 100 75 150 0 1390725760 235659520 17241760
11:57:42 2069 8192000000 10302462384 10701295616 1145792560 12.270 100 89 180 2 1397544544 231312992 20438992
11:58:48 16809 8192000000 10291270624 10712281088 1132739472 12.147 100 40 80 0 1398972640 256517488 9715680
12:00:08 5074 8192000000 10269101072 10680143872 1133073504 12.150 99 56 113 1 1368021264 221228816 13718944
12:01:16 7084 8192000000 10292756592 10701664256 1119625712 12.023 99 35 69 0 1388720016 260303712 8790592
12:02:26 8284 8192000000 10303461936 10751193088 1130737888 12.128 100 29 58 0 1432661184 294530512 7392784
12:04:16 13845 8192000000 10369291552 10800439296 1134384608 12.163 100 32 64 0 1470307392 327565264 8357520
12:05:33 23235 8192000000 10325302048 10796527616 1130304592 12.124 100 22 44 1 1413353888 277482144 5567152
12:07:25 46739 8192000000 10070383616 10602000384 1079024592 11.638 97 8 16 0 1200816512 120035072 1756848
12:09:21 70187 8192000000 10061381056 10476019712 1105800512 11.893 99 30 54 3 1125973920 14331232 5842176
12:11:31 59077 8192000000 10064571152 10541367296 1121511872 12.041 100 12 24 0 1188909312 64741616 2655824
12:13:32 28674 8192000000 10105463696 10525270016 1137956656 12.196 100 39 77 0 1179783328 33078928 8747744
12:15:08 101270 8192000000 10086516208 10561736704 1133684320 12.156 100 13 25 2 1183194064 47123520 2386224
12:17:49 52799 8192000000 10115989600 10543071232 1128634288 12.108 99 44 89 0 1188670560 50164192 9872080
12:19:43 23127 8192000000 10091937168 10533810176 1140103440 12.217 100 30 59 2 1194547184 48503392 5940352
12:21:17 28608 8192000000 10096069520 10532503552 1144782016 12.260 99 24 48 3 1193732304 44057456 4892832
12:22:46 55788 8192000000 10106375248 10567458816 1144748112 12.260 100 28 56 0 1198622704 48230400 5644192
12:24:53 43412 8192000000 10071798720 10579214336 1138237872 12.199 99 20 40 0 1221561952 79139328 4184752
12:26:39 42882 8192000000 10083401328 10549555200 1149813360 12.308 100 14 28 1 1191948800 39274064 2861376
12:28:22 55755 8192000000 10083021008 10547322880 1147158208 12.283 100 17 34 0 1180813856 29938928 3716720
12:30:43 86617 8192000000 10115113648 10609090560 1154780496 12.354 100 13 25 1 1198638624 41227424 2630704
12:33:16 31836 8192000000 10068403472 10516553728 1105830864 11.893 96 16 32 1 1151726848 42572096 3323888
12:34:52 4563 8192000000 10054391088 10474897408 1110476368 11.937 99 81 158 2 1142726640 14494416 17755856
12:35:59 35096 8192000000 10074409760 10521784320 1094806256 11.788 97 23 46 1 1146158816 46613520 4739040
12:37:39 35673 8192000000 10059393088 10488483840 1103114016 11.867 98 28 56 0 1152449264 43979936 5355312
12:39:16 47586 8192000000 10077310192 10542874624 1075814240 11.608 96 36 72 2 1162610368 79081936 7714192
12:41:07 30869 8192000000 10052281600 10508947456 1095430096 11.794 99 26 52 2 1140173200 39374928 5368176
12:42:41 69869 8192000000 10076181568 10534232064 1075188576 11.602 97 14 28 0 1153467280 75215600 3063104
12:44:55 33463 8192000000 10113285280 10549813248 1106407088 11.898 99 30 60 0 1177957920 65519504 6031328
12:46:35 105621 8192000000 10093114352 10596360192 1100023008 11.838 98 31 60 0 1203996848 97112192 6861648
12:49:32 58458 8192000000 10070895312 10536247296 1115172416 11.981 99 21 42 0 1151933664 32193232 4568016
12:51:31 47502 8192000000 10047445136 10540118016 1088376912 11.727 97 16 32 0 1156477552 64687968 3412672
12:53:21 86247 8192000000 10046903328 10481373184 1083292992 11.679 97 15 30 0 1122304832 36087312 2924528
12:55:47 77660 8192000000 10052300896 10550292480 1100919664 11.846 98 13 26 3 1154552816 50618960 3014192
12:58:10 77109 8192000000 10089639328 10576166912 1101676320 11.854 98 16 32 0 1177561808 72278208 3607280
13:00:40 71411 8192000000 10062911648 10536624128 1104897552 11.884 98 27 54 1 1173999552 62610208 6491792
13:02:55 11126 8192000000 10100841472 10541514752 1133074320 12.150 100 48 96 4 1194973600 51358288 10540992
13:04:06 56456 8192000000 10139483712 10594598912 1130277808 12.124 99 29 58 0 1234803968 98620592 5905568
13:06:03 63908 8192000000 10068667472 10540666880 1129927248 12.121 99 16 32 1 1174930304 41991072 3011984
13:08:07 19371 8192000000 10121950800 10557366272 1142497728 12.239 99 49 99 2 1189128704 37361408 9269568
13:09:31 79620 8192000000 10094522720 10589257728 1152969792 12.337 99 33 66 1 1215404432 56157040 6277600
13:11:52 62925 8192000000 10102706528 10554171392 1142404960 12.238 98 22 44 0 1170029888 23445216 4179712
13:14:06 74570 8192000000 10108574064 10575314944 1153571056 12.343 99 16 32 0 1205715104 48674528 3469520
13:16:49 55483 8192000000 10102270080 10558025728 1154828368 12.355 99 14 27 0 1205887312 48377392 2681552
13:18:50 57970 8192000000 10097417888 10570027008 1150343616 12.313 99 26 52 0 1188950256 33607520 4999120
13:20:48 54601 8192000000 10101881488 10560733184 1119000016 12.018 96 17 34 1 1170363776 47872048 3491712
13:22:44 105839 8192000000 10099272960 10595954688 1104469568 11.880 96 10 20 0 1223224432 116060800 2694064
13:25:31 59934 8192000000 10092299248 10532368384 1074581968 11.596 94 31 62 0 1131173680 50353872 6237840
13:27:33 36379 8192000000 10082772976 10510848000 1071915584 11.570 95 34 68 0 1137305728 58193488 7196656
13:29:15 107851 8192000000 10070037472 10583220224 1094257120 11.783 98 5 10 1 1146489408 51213840 1018448
13:32:22 69600 8192000000 10057037984 10544447488 1102268256 11.859 98 16 32 0 1139697328 34465920 2963152
13:35:03 37470 8192000000 10055018896 10534309888 1126357744 12.087 100 32 64 0 1173228992 39166688 7704560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2021 13:39:06 to 30-12-2021 13:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 68 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2021 13:39:22 to 30-12-2021 13:39:24.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239650
MON$OLDEST_ACTIVE               241215
MON$OLDEST_SNAPSHOT             241215
MON$NEXT_TRANSACTION            241215
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-30 07:30:08.6030 GMT
MON$PAGES                       2062496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

30.12.21 13:39:25. There are 3 logs in /var/tmp/logs-oltp40 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
2021-12-30 13:39:27 No severe PSQL-related problems occured
30.12.21 13:39:29. End of report.