Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2021 10: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15826084 kB
MemAvailable 30709296 kB
Buffers 0 kB
Cached 15850328 kB
SwapCached 4696 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-10-04 10:37:08
DTS_END                         2021-10-04 10:37:08.3730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55552
TRN_ID                          230226
ATT_ID                          686
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: 8512.17 26558 1021460
customer order: creation 159.39 448 19127
customer order: refuse 14.18 414 1702
order to supplier: creation 42.84 1564 5141
order to supplier: removal 3.16 125021 379
invoice (draft): creation 44.09 4082 5291
invoice (draft): removal 49.22 974 5906
invoice accept: apply 38.04 20858 4565
invoice accept: cancel 44.36 999 5323
customer reserve: creation 3554.48 200 426538
customer reserve: removal 4281.09 73 513731
realization accept: apply 78.70 238 9444
realization accept: cancel 81.90 126 9828
payment from customer: creation 55.63 137 6676
payment from customer: removal 5.60 158 672
payment to supplier: creation 46.02 196 5522
payment to supplier: removal 3.36 160 403
service: total inventory turnovers 3.58 4337 430
service: total monetary turnovers 5.48 623 658
service: aggregate perf. data 1.00 13891 120
service: refresh index statistics 0.03 356670 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-10-2021 10:38:27 to 04-10-2021 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2179
TEST_TIME 2 2128
TEST_TIME 3 2669
TEST_TIME 4 3764
TEST_TIME 5 4634
TEST_TIME 6 4881
TEST_TIME 7 4911
TEST_TIME 8 5020
TEST_TIME 9 5097
TEST_TIME 10 5640
TEST_TIME 11 6363
TEST_TIME 12 6314
TEST_TIME 13 6580
TEST_TIME 14 6620
TEST_TIME 15 6781
TEST_TIME 16 7016
TEST_TIME 17 7546
TEST_TIME 18 7883
TEST_TIME 19 7881
TEST_TIME 20 8225
TEST_TIME 21 8390
TEST_TIME 22 8539
TEST_TIME 23 8943
TEST_TIME 24 9025
TEST_TIME 25 8949
TEST_TIME 26 9143
TEST_TIME 27 9550
TEST_TIME 28 9759
TEST_TIME 29 10448
TEST_TIME 30 10457
TEST_TIME 31 10205
TEST_TIME 32 9911
TEST_TIME 33 10086
TEST_TIME 34 10035
TEST_TIME 35 9984
TEST_TIME 36 9834
TEST_TIME 37 9662
TEST_TIME 38 9618
TEST_TIME 39 9479
TEST_TIME 40 9392
TEST_TIME 41 9285
TEST_TIME 42 9171
TEST_TIME 43 9070
TEST_TIME 44 9184
TEST_TIME 45 9161
TEST_TIME 46 9057
TEST_TIME 47 9037
TEST_TIME 48 8925
TEST_TIME 49 8908
TEST_TIME 50 8912
TEST_TIME 51 8836
TEST_TIME 52 8675
TEST_TIME 53 8615
TEST_TIME 54 8667
TEST_TIME 55 8613
TEST_TIME 56 8633
TEST_TIME 57 8524
TEST_TIME 58 8449
TEST_TIME 59 8382
TEST_TIME 60 8391
TEST_TIME 61 8496
TEST_TIME 62 8509
TEST_TIME 63 8501
TEST_TIME 64 8521
TEST_TIME 65 8593
TEST_TIME 66 8560
TEST_TIME 67 8649
TEST_TIME 68 8794
TEST_TIME 69 8795
TEST_TIME 70 8740
TEST_TIME 71 8902
TEST_TIME 72 8925
TEST_TIME 73 8847
TEST_TIME 74 8756
TEST_TIME 75 8686
TEST_TIME 76 8686
TEST_TIME 77 8641
TEST_TIME 78 8598
TEST_TIME 79 8576
TEST_TIME 80 8524
TEST_TIME 81 8562
TEST_TIME 82 8623
TEST_TIME 83 8606
TEST_TIME 84 8575
TEST_TIME 85 8591
TEST_TIME 86 8644
TEST_TIME 87 8615
TEST_TIME 88 8683
TEST_TIME 89 8701
TEST_TIME 90 8730
TEST_TIME 91 8751
TEST_TIME 92 8735
TEST_TIME 93 8658
TEST_TIME 94 8636
TEST_TIME 95 8624
TEST_TIME 96 8567
TEST_TIME 97 8538
TEST_TIME 98 8529
TEST_TIME 99 8517
TEST_TIME 100 8484
TEST_TIME 101 8493
TEST_TIME 102 8476
TEST_TIME 103 8492
TEST_TIME 104 8524
TEST_TIME 105 8560
TEST_TIME 106 8551
TEST_TIME 107 8569
TEST_TIME 108 8579
TEST_TIME 109 8690
TEST_TIME 110 8678
TEST_TIME 111 8634
TEST_TIME 112 8603
TEST_TIME 113 8630
TEST_TIME 114 8630
TEST_TIME 115 8582
TEST_TIME 116 8547
TEST_TIME 117 8508
TEST_TIME 118 8533
TEST_TIME 119 8564
TEST_TIME 120 8514

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2021 10:38:31 to 04-10-2021 10:38:34.

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 1416114 1416114 0 0.00 0 42728 8 0 0
doc_list_biud 1416112 1416112 0 0.00 0 84242 34 0 0
sp_add_invoice_to_stock 4685 4565 120 2.56 26 210713 20859 0 120
sp_cancel_adding_invoice 5323 5323 0 0.00 4 91381 999 0 0
sp_cancel_client_order 1702 1702 0 0.00 5 19632 414 0 0
sp_cancel_customer_reserve 513731 513731 0 0.00 1 27592 73 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 16265 159 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 14131 160 0 0
sp_cancel_supplier_invoice 5911 5906 5 0.08 2 84254 974 0 5
sp_cancel_supplier_order 379 379 0 0.00 6 1164792 125021 0 0
sp_cancel_write_off 9828 9828 0 0.00 1 13498 127 0 0
sp_client_order 19127 19127 0 0.00 4 29865 448 0 0
sp_customer_reserve 426538 426538 0 0.00 0 41215 201 0 0
sp_fill_shopping_cart 29544 29544 0 0.00 1 21871 56 0 0
sp_fill_shopping_cart_clo_res 426534 426534 0 0.00 0 18700 19 0 0
sp_get_clo_for_invoice 4560 4560 0 0.00 0 39675 497 0 0
sp_kill_cost_storno 16226 16226 0 0.00 0 15299 74 0 0
sp_kill_qstorno_ret_qs2qd 1043575 1043575 0 0.00 0 84241 39 0 0
sp_kill_qty_storno 550871 550871 0 0.00 0 84242 85 0 0
sp_lock_selected_doc 19536 19536 0 0.00 0 7668 5 0 0
sp_make_cost_storno 26202 26202 0 0.00 0 32898 76 0 0
sp_multiply_rows_for_pdistr 26201 26201 0 0.00 0 16176 45 0 0
sp_multiply_rows_for_qdistr 19127 19127 0 0.00 0 27336 219 0 0
sp_pay_from_customer 6676 6676 0 0.00 4 22274 138 0 0
sp_pay_to_supplier 5522 5522 0 0.00 5 42733 196 0 0
sp_payment_common 12198 12198 0 0.00 1 9778 30 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 8481 363 0 0
sp_qd_handle_on_invoice_upd_sts 9882 9882 0 0.00 0 33497 428 0 0
sp_qd_handle_on_reserve_upd_sts 9443 9443 0 0.00 0 14807 69 0 0
sp_reserve_write_off 9444 9444 0 0.00 7 41683 239 0 0
sp_supplier_invoice 5291 5291 0 0.00 7 91743 4083 0 0
sp_supplier_order 5141 5141 0 0.00 5 78868 1565 0 0
srv_aggregate_perf_data 120 120 0 0.00 2789 280197 13892 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1289 167655 48461 0 0
srv_find_qd_qs_mism 976864 976864 0 0.00 0 22335 6 0 0
srv_make_invnt_saldo 430 430 0 0.00 25 125225 4338 0 0
srv_make_money_saldo 658 658 0 0.00 1 11248 623 0 0
srv_recalc_idx_stat 4 4 0 0.00 140760 539206 356670 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4565 4565 0 0.00 0 16612 195 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 15666 157 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 1928 14 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1499 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 909 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1078 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 4245 25 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 18084 1735 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 961 5 0 0
t$perf-norm:sp_client_order 19127 19127 0 0.00 0 14782 23 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 979 6 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 6026 10 0 0
t$perf-norm:sp_pay_to_supplier 5522 5522 0 0.00 0 4649 11 0 0
t$perf-norm:sp_reserve_write_off 9444 9444 0 0.00 0 6920 12 0 0
t$perf-norm:sp_supplier_invoice 5291 5291 0 0.00 0 6792 19 0 0
t$perf-norm:sp_supplier_order 5141 5141 0 0.00 0 5759 20 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 365 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 671 13 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 927 12 0 0
t$perf-norm:srv_make_money_saldo 658 658 0 0.00 0 335 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9358 9118 240 2.56 0 3364 17 0 240
v_all_customers 34834 34834 0 0.00 0 21 1 0 0
v_all_suppliers 28874 28874 0 0.00 0 32 1 0 0
v_all_wares 38254 38254 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 8461 29 0 0
v_cancel_client_order 3404 3404 0 0.00 0 19248 15 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 15755 30 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2117 6 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1716 10 0 0
v_cancel_supplier_invoice 794 784 10 1.25 0 10005 39 0 10
v_cancel_supplier_order 758 758 0 0.00 0 18720 27 0 0
v_cancel_write_off 1890 1890 0 0.00 0 4495 19 0 0
v_max_id_clo_ord 5134 5134 0 0.00 0 2709 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 1091 8 0 0
v_max_id_ord_sup 5282 5282 0 0.00 0 5513 7 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 576 2 0 0
v_max_non_paid_realizn 3657 3657 0 0.00 0 795 3 0 0
v_min_id_clo_ord 5134 5134 0 0.00 0 11429 15 0 0
v_min_id_clo_res 939 939 0 0.00 0 4911 8 0 0
v_min_id_ord_sup 5282 5282 0 0.00 0 2895 5 0 0
v_min_non_paid_invoice 5522 5522 0 0.00 0 9772 30 0 0
v_min_non_paid_realizn 6676 6676 0 0.00 0 8545 18 0 0
v_our_firm 9458 9458 0 0.00 0 35 1 0 0
v_reserve_write_off 18886 18886 0 0.00 0 18509 8 0 0
x_lock_depdocs_on_canc_invoice 10837 10837 0 0.00 0 15733 134 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 33157 506 0 0
x_make_qstorno_1000_1200 5141 5141 0 0.00 2 78847 1458 0 0
x_make_qstorno_1200_2000 5291 5291 0 0.00 3 91732 3879 0 0
x_make_qstorno_2100_3300 426538 426538 0 0.00 0 41199 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2021 10:38:35 to 04-10-2021 10:38:39.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 39063 8192000000 9717840880 10012151808 786105504 8.755 100 91 182 2 816614096 8271856 22236736
08:09:56 23475 8192000000 9758418928 10066157568 832231840 9.222 100 81 162 0 863401456 12006256 19163360
08:11:26 17824 8192000000 9804206688 10126131200 875613552 9.656 100 75 150 0 916722928 23592112 17517264
08:12:47 13226 8192000000 9867396528 10202152960 924657520 10.142 100 78 157 1 975148656 33575824 16915312
08:14:10 9990 8192000000 9947087664 10287263744 966495616 10.552 100 90 180 0 1054639536 69555744 18588176
08:15:24 6515 8192000000 9953493552 10305294336 990566784 10.787 100 80 158 0 1069225968 62218880 16440304
08:16:32 2815 8192000000 9995823376 10354462720 1017963856 11.052 100 84 167 1 1110515088 75668560 16882672
08:17:37 2427 8192000000 10009525760 10377207808 1047454880 11.336 100 89 176 3 1126538864 61770896 17313088
08:18:41 1355 8192000000 10046734624 10415484928 1061921920 11.475 100 96 193 0 1163863552 81522032 20419600
08:19:44 1179 8192000000 10026017696 10404032512 1071831200 11.570 100 75 151 0 1139004800 51926576 15247024
08:20:48 7924 8192000000 10011563856 10404253696 1083010176 11.676 100 50 99 0 1128622544 34915920 10696448
08:21:57 4990 8192000000 10007663648 10406379520 1088914032 11.732 100 36 72 1 1121815392 24447472 8453888
08:23:04 46582 8192000000 10024938832 10420428800 1092564064 11.767 100 52 102 2 1121163392 17659744 10939584
08:24:51 5024 8192000000 9991938176 10399051776 1086996400 11.714 99 38 76 0 1108318624 13022736 8299488
08:25:59 5410 8192000000 10025712736 10432704512 1106137840 11.896 100 51 103 2 1132983680 15259552 11586288
08:27:05 5436 8192000000 10008131664 10402336768 1085796304 11.703 99 35 68 1 1114771936 20462672 8512960
08:28:14 45011 8192000000 9980702800 10361872384 995458336 10.834 91 45 89 1 1017121936 11937904 9725696
08:30:00 5387 8192000000 9974390000 10368151552 1033247216 11.200 97 40 80 1 1056137920 13637040 9253664
08:31:08 10070 8192000000 9943600560 10332987392 996333024 10.843 96 32 64 0 1021448112 17801312 7313776
08:32:24 9340 8192000000 9942712160 10336858112 1016627840 11.039 98 28 57 0 1040417152 17454608 6334704
08:33:34 19068 8192000000 9946602480 10347573248 1026940432 11.139 99 60 120 0 1060968720 21544320 12483968
08:34:55 5227 8192000000 9978691824 10367488000 1050087616 11.362 100 54 107 2 1085960656 24695104 11177936
08:36:02 5644 8192000000 9987545632 10375507968 1066277792 11.517 100 46 88 1 1101952240 26346784 9327664
08:37:11 167654 8192000000 10054855120 10464583680 1097366144 11.813 100 34 68 1 1136675568 31845024 7464400
08:41:05 15138 8192000000 10104794128 10522390528 1120788000 12.034 100 52 104 3 1209069344 77204176 11077168
08:42:27 8042 8192000000 10131424496 10546188288 1129107856 12.113 100 60 115 1 1245826128 104530160 12188112
08:43:39 8264 8192000000 10161683968 10568470528 1144114640 12.254 100 72 144 2 1273950464 114079904 15755920
08:44:52 16673 8192000000 10192774560 10609217536 1152882592 12.337 100 45 90 0 1296198352 133171120 10144640
08:46:14 17942 8192000000 10236512512 10649006080 1158653344 12.391 100 60 117 0 1336630480 165606928 12370208
08:47:34 5185 8192000000 10246601984 10681008128 1162716400 12.429 100 51 98 2 1365667232 190561328 12389504
08:48:43 10447 8192000000 10224586288 10634035200 1148197504 12.293 99 76 150 2 1329288544 163294416 17796624
08:50:00 9081 8192000000 10294389776 10703646720 1158080144 12.385 100 66 130 3 1405378704 232904544 14394016
08:51:14 2522 8192000000 10310776960 10716909568 1159993472 12.403 100 80 159 2 1410740144 233243824 17502848
08:52:22 11163 8192000000 10302978528 10706845696 1134774656 12.166 98 65 128 0 1392836352 243876096 14185600
08:53:38 4810 8192000000 10343999488 10766553088 1165031728 12.450 100 72 144 1 1444800432 263203408 16565296
08:54:48 1790 8192000000 10333233712 10734227456 1149604336 12.306 100 86 171 2 1431340608 262426560 19309712
08:55:55 2726 8192000000 10316742128 10716950528 1141721024 12.232 100 84 168 1 1413313664 252789184 18803456
08:57:03 1287 8192000000 10325625392 10740207616 1122375056 12.049 99 77 151 1 1416292400 275708720 18208624
08:58:09 6057 8192000000 10312368704 10728427520 1142898192 12.243 100 44 88 1 1420258192 266595728 10764272
08:59:23 7626 8192000000 10382480496 10800914432 1140927584 12.224 100 46 92 0 1474520464 322036768 11556112
09:00:41 11058 8192000000 10306243760 10725519360 1128329648 12.106 99 39 78 0 1404070640 265965840 9775152
09:01:56 16318 8192000000 10326870240 10765680640 1144178368 12.255 100 29 57 1 1433299664 281915040 7206256
09:03:27 11952 8192000000 10352248128 10781704192 1127753056 12.100 100 34 68 0 1446056032 309984832 8318144
09:04:43 41835 8192000000 10296515344 10736271360 1127702800 12.100 100 21 42 0 1423419856 290197728 5519328
09:06:41 25702 8192000000 10087004656 10598653952 1120968016 12.036 100 14 28 1 1264682512 140640656 3073840
09:08:09 100468 8192000000 10127408320 10548027392 1095800800 11.798 97 50 97 1 1208564896 101947952 10816144
09:10:54 35603 8192000000 10099739552 10536599552 1126469712 12.088 100 8 16 2 1211776016 83735152 1571152
09:12:36 58749 8192000000 10063717616 10509766656 1121891360 12.045 99 16 32 1 1148459872 22872528 3695984
09:14:35 26994 8192000000 10102357776 10543947776 1129089136 12.113 99 24 47 0 1209498000 75367920 5040944
09:16:11 86462 8192000000 10148477344 10613428224 1139093968 12.207 99 46 92 0 1227046224 77084352 10867904
09:18:41 23550 8192000000 10143670128 10583846912 1148826640 12.298 100 50 100 0 1248517744 88141424 11549680
09:20:05 86973 8192000000 10157951088 10639900672 1140442832 12.220 100 26 53 1 1269114784 122858128 5813824
09:22:41 61462 8192000000 10083196368 10515312640 1130071808 12.122 99 24 48 0 1161154896 26090928 4992160
09:24:47 18097 8192000000 10101250368 10549817344 1144639024 12.259 100 29 58 0 1207227984 56129952 6459008
09:26:07 59250 8192000000 10088524064 10567299072 1142617952 12.240 100 12 24 0 1225185408 79878688 2688768
09:28:07 116878 8192000000 10092212240 10551775232 1120871536 12.035 98 13 26 0 1176895088 53305392 2718160
09:31:51 19557 8192000000 10049108896 10492919808 1126434528 12.088 100 19 38 1 1166507104 35974128 4098448
09:33:28 56859 8192000000 10057912736 10537472000 1114030624 11.971 99 9 18 1 1158555824 42648144 1877056
09:35:25 65134 8192000000 10096506592 10563543040 1127625952 12.099 99 16 32 0 1200345744 69283888 3435904
09:37:31 82216 8192000000 10057167552 10565726208 1105943680 11.894 97 6 12 1 1170783168 63803760 1035728
09:39:59 79454 8192000000 10078436896 10538348544 1092823408 11.769 96 11 22 0 1128887584 34226176 1838000
09:42:19 88021 8192000000 10101162832 10535849984 1079980560 11.647 94 17 34 0 1132546608 49406288 3159760
09:45:07 98934 8192000000 10087484096 10551320576 1100969728 11.847 97 12 24 2 1157414720 53765376 2679616
09:47:46 35392 8192000000 10161848144 10592280576 1129811648 12.120 99 54 108 3 1245324864 104428720 11084496
09:49:24 55242 8192000000 10063923936 10491998208 1117119136 12.000 98 34 68 1 1139498160 14892128 7486896
09:51:22 88691 8192000000 10079089152 10521853952 1129177904 12.114 99 39 78 1 1176736304 39595680 7962720
09:53:56 71578 8192000000 10078006752 10537275392 1121886976 12.045 98 6 12 0 1172501584 49393040 1221568
09:56:09 57976 8192000000 10079291840 10523500544 1107152064 11.905 97 15 30 0 1152802080 42153328 3496688
09:58:48 49287 8192000000 10059693648 10547904512 1106587872 11.900 97 16 32 0 1171118176 61302048 3228256
10:00:50 48626 8192000000 10085349776 10527068160 1119907072 12.026 98 27 53 1 1161969088 36084944 5977072
10:02:39 63575 8192000000 10102103920 10563665920 1127853872 12.101 99 18 34 0 1214210448 82538016 3818560
10:04:44 55905 8192000000 10071954160 10535182336 1106648832 11.901 97 21 42 0 1158191968 47577328 3965808
10:06:45 93849 8192000000 10101956720 10534465536 1133054512 12.150 98 31 62 0 1185040832 46057712 5928608
10:09:24 116295 8192000000 10079991888 10523992064 1113042464 11.961 97 41 82 0 1160243936 39124224 8077248
10:12:20 48390 8192000000 10047091056 10511650816 1113843152 11.969 97 15 30 0 1154329104 37445824 3040128
10:14:25 47523 8192000000 10071989776 10556989440 1148783424 12.298 100 7 14 0 1187748960 37310464 1655072
10:16:13 48207 8192000000 10085982288 10552918016 1154972368 12.356 100 22 44 1 1202353360 42699744 4681248
10:18:02 26501 8192000000 10125495856 10576613376 1154875584 12.355 99 14 28 0 1227559920 69835136 2849200
10:19:29 72087 8192000000 10110888080 10587136000 1119333248 12.021 97 24 49 0 1185021456 60194832 5493376
10:21:44 98160 8192000000 10158685984 10644230144 1149250080 12.302 99 18 36 1 1247470560 94604912 3615568
10:24:32 50698 8192000000 10162045776 10620973056 1138879088 12.205 98 21 42 0 1248883904 105668448 4336368
10:26:30 85194 8192000000 10090696752 10538147840 1079422080 11.642 94 12 24 0 1143173264 61367456 2383728
10:28:56 90603 8192000000 10116492640 10568208384 1142616496 12.240 100 34 68 0 1229806576 80019616 7170464
10:33:32 60745 8192000000 10095557968 10549772288 1120434272 12.031 98 14 28 0 1171584096 47752672 3397152
10:35:45 72052 8192000000 10071445888 10549567488 1101112624 11.848 97 14 28 0 1155251872 51168000 2971248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 10:38:41 to 04-10-2021 10:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2021 10:38:57 to 04-10-2021 10:38:59.

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          229910
MON$OLDEST_ACTIVE               230464
MON$OLDEST_SNAPSHOT             230464
MON$NEXT_TRANSACTION            230464
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                       2053856
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:47: 
:48: mscs-test-161.budzdorov.ru    Mon Oct  4 10:37:19 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Mon Oct  4 10:37:19 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
--- end of diff output ---

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

04.10.21 10:39:00. 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-10-04 10:39:02 No severe PSQL-related problems occured
04.10.21 10:39:04. End of report.