Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 04:37
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 16244644 kB
MemAvailable 31289456 kB
Buffers 20 kB
Cached 15344800 kB
SwapCached 1796 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-31 04:37:10
DTS_END                         2021-12-31 04:37:10.6740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39226
TRN_ID                          224302
ATT_ID                          595
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: 8663.98 28879 1039677
customer order: creation 158.82 432 19059
customer order: refuse 13.17 395 1580
order to supplier: creation 43.03 1622 5163
order to supplier: removal 3.28 120637 393
invoice (draft): creation 43.82 3922 5258
invoice (draft): removal 49.39 1005 5927
invoice accept: apply 38.85 19918 4662
invoice accept: cancel 44.43 966 5331
customer reserve: creation 3614.78 194 433774
customer reserve: removal 4372.48 71 524698
realization accept: apply 79.20 197 9504
realization accept: cancel 82.56 118 9907
payment from customer: creation 56.08 125 6729
payment from customer: removal 5.17 88 620
payment to supplier: creation 45.18 145 5422
payment to supplier: removal 3.51 78 421
service: total inventory turnovers 3.37 4298 404
service: total monetary turnovers 5.88 697 705
service: aggregate perf. data 0.97 13485 116
service: refresh index statistics 0.03 409181 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-12-2021 04:37:52 to 31-12-2021 04:37:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7250
TEST_TIME 2 6508
TEST_TIME 3 5939
TEST_TIME 4 6366
TEST_TIME 5 6149
TEST_TIME 6 6657
TEST_TIME 7 6339
TEST_TIME 8 6054
TEST_TIME 9 6216
TEST_TIME 10 6155
TEST_TIME 11 6224
TEST_TIME 12 6408
TEST_TIME 13 6689
TEST_TIME 14 6861
TEST_TIME 15 7037
TEST_TIME 16 7354
TEST_TIME 17 7516
TEST_TIME 18 7623
TEST_TIME 19 7807
TEST_TIME 20 7798
TEST_TIME 21 8321
TEST_TIME 22 8648
TEST_TIME 23 8707
TEST_TIME 24 8862
TEST_TIME 25 9004
TEST_TIME 26 8958
TEST_TIME 27 9258
TEST_TIME 28 9868
TEST_TIME 29 10097
TEST_TIME 30 10281
TEST_TIME 31 10276
TEST_TIME 32 10021
TEST_TIME 33 9804
TEST_TIME 34 9608
TEST_TIME 35 9537
TEST_TIME 36 9699
TEST_TIME 37 9485
TEST_TIME 38 9263
TEST_TIME 39 9239
TEST_TIME 40 9221
TEST_TIME 41 9048
TEST_TIME 42 9069
TEST_TIME 43 8971
TEST_TIME 44 9082
TEST_TIME 45 9105
TEST_TIME 46 9055
TEST_TIME 47 9002
TEST_TIME 48 9086
TEST_TIME 49 9083
TEST_TIME 50 9150
TEST_TIME 51 9096
TEST_TIME 52 9064
TEST_TIME 53 8955
TEST_TIME 54 9118
TEST_TIME 55 8953
TEST_TIME 56 8915
TEST_TIME 57 8908
TEST_TIME 58 8853
TEST_TIME 59 8856
TEST_TIME 60 8778
TEST_TIME 61 8717
TEST_TIME 62 8669
TEST_TIME 63 8601
TEST_TIME 64 8632
TEST_TIME 65 8745
TEST_TIME 66 8716
TEST_TIME 67 8706
TEST_TIME 68 8696
TEST_TIME 69 8698
TEST_TIME 70 8813
TEST_TIME 71 8803
TEST_TIME 72 8920
TEST_TIME 73 8882
TEST_TIME 74 8870
TEST_TIME 75 8891
TEST_TIME 76 8930
TEST_TIME 77 8860
TEST_TIME 78 8833
TEST_TIME 79 8831
TEST_TIME 80 8776
TEST_TIME 81 8773
TEST_TIME 82 8738
TEST_TIME 83 8714
TEST_TIME 84 8684
TEST_TIME 85 8601
TEST_TIME 86 8582
TEST_TIME 87 8564
TEST_TIME 88 8563
TEST_TIME 89 8539
TEST_TIME 90 8556
TEST_TIME 91 8631
TEST_TIME 92 8648
TEST_TIME 93 8728
TEST_TIME 94 8714
TEST_TIME 95 8738
TEST_TIME 96 8768
TEST_TIME 97 8751
TEST_TIME 98 8814
TEST_TIME 99 8791
TEST_TIME 100 8890
TEST_TIME 101 8863
TEST_TIME 102 8783
TEST_TIME 103 8750
TEST_TIME 104 8743
TEST_TIME 105 8696
TEST_TIME 106 8660
TEST_TIME 107 8620
TEST_TIME 108 8612
TEST_TIME 109 8618
TEST_TIME 110 8587
TEST_TIME 111 8610
TEST_TIME 112 8629
TEST_TIME 113 8615
TEST_TIME 114 8656
TEST_TIME 115 8673
TEST_TIME 116 8660
TEST_TIME 117 8709
TEST_TIME 118 8744
TEST_TIME 119 8726
TEST_TIME 120 8666

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-12-2021 04:37:56 to 31-12-2021 04:38: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 1439402 1439402 0 0.00 0 36314 7 0 0
doc_list_biud 1439402 1439402 0 0.00 0 33411 33 0 0
sp_add_invoice_to_stock 4763 4662 101 2.12 29 196061 19919 0 101
sp_cancel_adding_invoice 5331 5331 0 0.00 5 70340 966 0 0
sp_cancel_client_order 1580 1580 0 0.00 5 17414 396 0 0
sp_cancel_customer_reserve 524698 524698 0 0.00 1 33417 71 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 2957 88 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2077 79 0 0
sp_cancel_supplier_invoice 5939 5927 12 0.20 2 32612 1006 0 12
sp_cancel_supplier_order 393 393 0 0.00 13 1038352 120637 0 0
sp_cancel_write_off 9907 9907 0 0.00 1 8651 118 0 0
sp_client_order 19059 19059 0 0.00 4 22261 432 0 0
sp_customer_reserve 433774 433774 0 0.00 0 44996 194 0 0
sp_fill_shopping_cart 29477 29477 0 0.00 1 22853 55 0 0
sp_fill_shopping_cart_clo_res 433771 433771 0 0.00 0 16925 17 0 0
sp_get_clo_for_invoice 4658 4658 0 0.00 0 23361 424 0 0
sp_kill_cost_storno 16279 16279 0 0.00 0 36308 69 0 0
sp_kill_qstorno_ret_qs2qd 1065623 1065623 0 0.00 0 33376 39 0 0
sp_kill_qty_storno 561996 561996 0 0.00 0 33409 83 0 0
sp_lock_selected_doc 19502 19502 0 0.00 0 6453 3 0 0
sp_make_cost_storno 26311 26311 0 0.00 0 33768 62 0 0
sp_multiply_rows_for_pdistr 26311 26311 0 0.00 0 35577 35 0 0
sp_multiply_rows_for_qdistr 19059 19059 0 0.00 0 20466 232 0 0
sp_pay_from_customer 6729 6729 0 0.00 4 25191 125 0 0
sp_pay_to_supplier 5422 5422 0 0.00 5 35591 145 0 0
sp_payment_common 12151 12151 0 0.00 1 25179 23 0 0
sp_qd_handle_on_cancel_clo 1580 1580 0 0.00 0 9431 328 0 0
sp_qd_handle_on_invoice_upd_sts 9987 9987 0 0.00 0 22046 402 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 12436 60 0 0
sp_reserve_write_off 9504 9504 0 0.00 7 18555 197 0 0
sp_supplier_invoice 5258 5258 0 0.00 6 76571 3923 0 0
sp_supplier_order 5163 5163 0 0.00 5 90166 1622 0 0
srv_aggregate_perf_data 116 116 0 0.00 2903 163097 13486 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2651 91173 41746 0 0
srv_find_qd_qs_mism 994105 994105 0 0.00 0 12070 6 0 0
srv_make_invnt_saldo 404 404 0 0.00 24 115457 4298 0 0
srv_make_money_saldo 705 705 0 0.00 1 17955 697 0 0
srv_recalc_idx_stat 4 4 0 0.00 154385 943413 409181 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4662 4662 0 0.00 0 16583 206 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 10117 119 0 0
t$perf-norm:sp_cancel_client_order 1580 1580 0 0.00 0 5471 15 0 0
t$perf-norm:sp_cancel_customer_reserve 597 597 0 0.00 0 2208 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 566 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 789 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 1824 20 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 22344 1896 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 2624 9 0 0
t$perf-norm:sp_client_order 19059 19059 0 0.00 0 14174 23 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 9723 24 0 0
t$perf-norm:sp_pay_from_customer 6729 6729 0 0.00 0 6505 9 0 0
t$perf-norm:sp_pay_to_supplier 5422 5422 0 0.00 0 12080 14 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 16677 13 0 0
t$perf-norm:sp_supplier_invoice 5258 5258 0 0.00 0 14189 27 0 0
t$perf-norm:sp_supplier_order 5163 5163 0 0.00 0 14127 19 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 505 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 404 404 0 0.00 0 7006 39 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 1370 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 15 5 0 0
v_add_invoice_to_stock 9514 9312 202 2.12 0 15831 22 0 202
v_all_customers 35144 35144 0 0.00 0 29 1 0 0
v_all_suppliers 28618 28618 0 0.00 0 137 1 0 0
v_all_wares 38118 38118 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 18331 34 0 0
v_cancel_client_order 3160 3160 0 0.00 0 17028 27 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 2878 10 0 0
v_cancel_customer_reserve 1194 1194 0 0.00 0 16242 25 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1678 7 0 0
v_cancel_supplier_invoice 784 760 24 3.06 0 1641 16 0 24
v_cancel_supplier_order 786 786 0 0.00 0 532 4 0 0
v_cancel_write_off 1882 1882 0 0.00 0 3411 15 0 0
v_max_id_clo_ord 5163 5163 0 0.00 0 3135 2 0 0
v_max_id_clo_res 934 934 0 0.00 0 3520 16 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 4918 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 130 2 0 0
v_max_non_paid_realizn 3520 3520 0 0.00 0 25175 10 0 0
v_min_id_clo_ord 5163 5163 0 0.00 0 6866 8 0 0
v_min_id_clo_res 934 934 0 0.00 0 2604 6 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 7039 11 0 0
v_min_non_paid_invoice 5422 5422 0 0.00 0 5697 12 0 0
v_min_non_paid_realizn 6729 6729 0 0.00 0 8941 16 0 0
v_our_firm 9392 9392 0 0.00 0 81 1 0 0
v_reserve_write_off 19008 19008 0 0.00 0 18308 11 0 0
x_lock_depdocs_on_canc_invoice 10878 10878 0 0.00 0 22338 114 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 3615 396 0 0
x_make_qstorno_1000_1200 5163 5163 0 0.00 2 90112 1534 0 0
x_make_qstorno_1200_2000 5258 5258 0 0.00 2 73380 3707 0 0
x_make_qstorno_2100_3300 433774 433774 0 0.00 0 44993 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 04:38:01 to 31-12-2021 04:38: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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 13947 8192000000 9708607744 10009739264 801772976 8.914 100 78 156 0 827756048 7053568 18929504
02:09:33 17101 8192000000 9770063504 10077245440 841782928 9.318 100 82 164 0 871584592 10752128 19049536
02:10:57 14190 8192000000 9799068352 10112106496 869250848 9.593 100 85 170 0 906535056 17339472 19944736
02:12:15 13326 8192000000 9844447568 10172571648 901816464 9.916 100 79 158 0 955611856 36145456 17649936
02:13:31 10832 8192000000 9895097040 10235514880 944909296 10.341 100 79 158 1 1014783840 53129072 16745472
02:14:47 5652 8192000000 9944205024 10295988224 978044624 10.665 100 78 152 2 1051833184 58129440 15659120
02:15:59 3726 8192000000 9978996384 10334404608 1002940032 10.907 100 87 171 3 1092593360 71876544 17776784
02:17:05 7177 8192000000 10005585776 10376642560 1034487520 11.212 100 86 171 1 1116925776 65282000 17156256
02:18:14 1919 8192000000 10035252048 10403414016 1057754336 11.435 100 92 185 0 1145816640 69144048 18918256
02:19:16 1964 8192000000 10029718496 10402959360 1066619584 11.520 100 85 169 2 1137958416 53475168 17863664
02:20:19 7031 8192000000 9987885008 10384973824 1068985744 11.542 100 26 52 1 1112024128 37101696 5936688
02:21:28 4072 8192000000 9998515104 10396123136 1080859344 11.656 100 49 97 1 1116291344 25007744 10424256
02:22:35 24617 8192000000 10016238000 10411905024 1085926688 11.704 100 63 128 1 1121751600 22197616 13627296
02:24:01 5132 8192000000 10018965936 10414977024 1088223936 11.726 100 42 79 1 1123847008 28204064 7419008
02:25:07 4891 8192000000 10012655760 10406731776 1094424640 11.785 100 48 95 2 1116415424 12288624 9702160
02:26:13 9662 8192000000 9992951536 10402725888 1087576912 11.720 99 29 58 0 1122284928 28685968 6022048
02:27:27 27047 8192000000 10017135424 10431070208 1079427696 11.642 98 25 50 0 1114440944 30182032 4831216
02:29:01 6482 8192000000 9994077232 10384691200 1056286592 11.421 98 34 67 0 1086222528 22210880 7725056
02:30:09 8056 8192000000 9978468224 10370568192 1043029312 11.294 99 33 66 1 1067769408 17797152 6942944
02:31:17 68344 8192000000 9923246992 10318475264 961615824 10.505 93 32 62 0 983382752 14469440 7297488
02:33:27 12282 8192000000 9946288224 10330906624 1021258368 11.084 99 33 66 0 1048730624 19561216 7911040
02:34:42 5935 8192000000 9974130736 10361888768 1048859088 11.350 100 46 92 0 1080051776 21063136 10129552
02:35:49 29771 8192000000 9972679856 10383962112 1064239264 11.497 100 22 44 3 1089916176 20627056 5049856
02:37:47 9506 8192000000 10035461888 10426015744 1091014848 11.752 100 61 119 1 1129704944 26923312 11766784
02:39:04 21322 8192000000 10044286672 10447536128 1103120912 11.867 100 48 95 0 1157652208 45048672 9482624
02:40:35 3662 8192000000 10106021184 10503000064 1125004352 12.074 100 75 151 0 1210246704 68392560 16849792
02:41:45 23981 8192000000 10114675472 10527817728 1132675792 12.147 100 45 90 1 1234235232 91629072 9930368
02:43:19 17717 8192000000 10133173504 10550292480 1145692016 12.269 100 52 105 1 1245090624 88151520 11247088
02:44:46 12214 8192000000 10156764688 10569674752 1158829328 12.392 100 49 97 1 1264602416 95019536 10753552
02:46:03 13404 8192000000 10202112304 10622599168 1165485184 12.455 100 57 114 0 1308585984 130598192 12502608
02:47:24 8653 8192000000 10210958320 10630840320 1162106224 12.423 99 59 113 1 1308271552 132954128 13211200
02:48:36 4806 8192000000 10229664976 10641805312 1161021280 12.413 99 60 119 2 1335114896 161106992 12986624
02:49:47 4579 8192000000 10282432224 10684542976 1181471728 12.604 100 71 142 0 1376948352 179775680 15700944
02:51:01 4526 8192000000 10313625200 10714427392 1184489680 12.632 100 82 160 1 1410732336 208842720 17399936
02:52:10 5100 8192000000 10354712336 10769641472 1175706224 12.550 100 74 148 0 1448833824 255862976 17264624
02:53:19 9026 8192000000 10342573024 10774847488 1174502096 12.539 100 66 132 0 1456687344 266653216 15532032
02:54:39 2651 8192000000 10345621680 10771181568 1190452512 12.688 100 57 114 0 1450925760 246872544 13600704
02:55:46 5147 8192000000 10368993232 10795433984 1186195008 12.648 100 73 144 1 1462644288 259922688 16526592
02:56:55 4739 8192000000 10373760368 10799714304 1171740064 12.513 100 50 98 1 1480886000 297077680 12068256
02:58:04 17144 8192000000 10388322800 10817978368 1160508240 12.408 99 38 76 1 1494688448 324365632 9814576
02:59:31 18651 8192000000 10341484608 10772873216 1160458304 12.408 100 36 70 0 1452546016 283220304 8867408
03:01:08 15644 8192000000 10275703376 10699276288 1101446288 11.851 96 29 57 1 1337478992 229564432 6468272
03:02:31 27998 8192000000 10286044544 10732449792 1124711440 12.071 99 31 61 2 1381097840 248891760 7494640
03:04:02 26761 8192000000 10291821344 10717360128 1087608400 11.720 96 33 66 0 1370724448 275075456 8040592
03:05:31 36494 8192000000 10217119392 10707128320 1112863808 11.960 99 20 40 1 1350643504 232605344 5174352
03:07:10 15614 8192000000 10109760272 10521391104 1103956032 11.875 99 61 115 4 1206491632 90603328 11932272
03:08:26 33187 8192000000 10068978192 10561024000 1112896848 11.960 100 12 23 1 1178759680 63398576 2464256
03:10:15 80959 8192000000 10104789408 10528882688 1124722736 12.072 99 36 67 1 1182858848 51810736 6325376
03:12:39 62024 8192000000 10104249584 10604101632 1110043664 11.933 99 19 38 0 1235008832 120761328 4203840
03:14:42 61326 8192000000 10085022656 10512105472 1118261568 12.011 99 27 53 0 1159686096 35180864 6243664
03:16:49 58304 8192000000 10094894336 10567823360 1115272592 11.982 99 16 34 1 1220697824 101855552 3569680
03:19:24 68164 8192000000 10109517168 10594643968 1124226704 12.067 99 14 28 0 1211371376 84231824 2912848
03:21:32 91173 8192000000 10073026528 10525130752 1102038272 11.857 97 16 32 0 1198459216 92897376 3523568
03:24:04 35573 8192000000 10134498752 10573946880 1149702016 12.307 100 47 94 1 1211928288 52482832 9743440
03:25:54 69025 8192000000 10105562240 10576916480 1134007920 12.159 99 22 44 1 1198585936 60105872 4472144
03:28:28 79001 8192000000 10072292208 10505535488 1111568352 11.947 97 13 26 0 1137845712 23858912 2418448
03:30:48 35890 8192000000 10176045760 10665758720 1147659120 12.288 100 39 79 0 1277244432 121354320 8230992
03:32:28 84593 8192000000 10098555376 10562412544 1149300416 12.303 100 28 55 1 1237356224 81766688 6289120
03:35:06 52678 8192000000 10093206688 10525687808 1142076112 12.235 99 47 94 0 1178696144 26772496 9847536
03:37:03 66141 8192000000 10098347120 10550272000 1131351936 12.134 98 14 28 0 1179805728 45580832 2872960
03:39:14 41230 8192000000 10108370656 10554859520 1144681664 12.260 99 17 34 0 1200036032 52030064 3324304
03:40:56 31053 8192000000 10143016928 10626093056 1154173216 12.349 100 22 44 0 1243943440 85249904 4520320
03:42:29 68874 8192000000 10059808224 10532352000 1079214816 11.640 94 11 22 0 1156969760 75527136 2227808
03:44:38 28935 8192000000 10113085552 10539851776 1129093888 12.113 98 35 69 2 1196251824 59834480 7323456
03:46:12 75988 8192000000 10116835104 10620588032 1149438208 12.304 100 17 34 0 1266927280 113842880 3646192
03:48:35 51416 8192000000 10155241824 10605420544 1158006112 12.385 100 28 56 0 1238239264 74249120 5984032
03:50:44 15548 8192000000 10097263440 10520227840 1129541392 12.117 98 45 85 0 1167153760 28922704 8689664
03:52:02 54272 8192000000 10087145200 10539069440 1091312464 11.755 96 37 74 3 1180241040 81486112 7442464
03:53:56 19089 8192000000 10069579664 10496909312 1114812352 11.978 98 41 82 1 1148029408 24902288 8314768
03:55:19 78329 8192000000 10052896192 10510893056 1108250768 11.916 98 29 57 1 1170559040 55775088 6533184
03:57:39 27879 8192000000 10061649168 10491916288 1112549344 11.957 99 42 84 0 1145879552 25117520 8212688
03:59:07 50914 8192000000 10043138304 10496339968 1105768736 11.892 98 14 28 1 1136825376 28244656 2811984
04:00:58 60721 8192000000 10039283216 10495397888 1106399472 11.898 98 13 26 1 1141528176 32278128 2850576
04:03:01 74597 8192000000 10045875904 10522714112 1098439600 11.823 98 14 28 1 1161334624 59631248 3263776
04:05:45 54465 8192000000 10109771488 10546872320 1134207792 12.161 100 24 48 1 1198173984 58935248 5030944
04:07:40 60460 8192000000 10132202528 10567102464 1137485376 12.192 99 17 34 1 1224732272 83430800 3816096
04:09:40 41494 8192000000 10086922944 10548273152 1095021856 11.790 96 16 32 0 1153547936 55371280 3154800
04:11:30 23805 8192000000 10092530720 10521964544 1126383680 12.087 99 37 74 0 1187069040 52745184 7940176
04:13:00 44414 8192000000 10090751440 10536284160 1119570256 12.023 98 12 24 0 1172965312 51443904 1951152
04:14:52 27652 8192000000 10126386624 10545729536 1130219952 12.123 100 40 78 4 1182831520 44409136 8202432
04:16:23 63405 8192000000 10107253248 10577125376 1106512016 11.899 97 10 20 0 1166112864 57632416 1968432
04:18:28 90961 8192000000 10067476496 10511364096 1129292784 12.115 99 14 28 0 1177655968 45220432 3142752
04:21:06 71096 8192000000 10080025744 10568601600 1133411056 12.154 99 9 18 0 1184877296 49614928 1851312
04:23:17 82188 8192000000 10096340960 10570563584 1129994896 12.121 99 12 24 1 1169083552 36457040 2631616
04:25:44 75118 8192000000 10101451136 10568671232 1148927952 12.299 100 17 34 1 1224132576 70979904 4224720
04:28:06 84859 8192000000 10124645600 10573524992 1129528464 12.117 99 34 69 0 1218832688 81510352 7793872
04:30:51 58250 8192000000 10159464176 10634297344 1148111024 12.292 100 24 48 0 1235559328 82105856 5342448
04:33:29 58896 8192000000 10120723024 10604494848 1139085952 12.207 99 14 28 0 1205325664 63085472 3154240
04:36:05 51825 8192000000 10093205360 10509156352 1112461360 11.956 98 36 72 1 1158310032 38739648 7109024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-12-2021 04:38:06 to 31-12-2021 04:38:22.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 04:38:23 to 31-12-2021 04:38:25.

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          220677
MON$OLDEST_ACTIVE               224419
MON$OLDEST_SNAPSHOT             224419
MON$NEXT_TRANSACTION            224419
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                       2063584
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             778
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 ---
:145: 
:146: mscs-test-161.budzdorov.ru    Fri Dec 31 04:37:14 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Fri Dec 31 04:37:14 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Fri Dec 31 04:37:18 2021
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

31.12.21 04:38:26. 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-31 04:38:28 No severe PSQL-related problems occured
31.12.21 04:38:29. End of report.