Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2021 04:28
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16632708 kB
MemAvailable 31476888 kB
Buffers 20 kB
Cached 14996716 kB
SwapCached 0 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.0
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-03-31 04:26:59
DTS_END                         2021-03-31 04:26:58.9960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45214
TRN_ID                          228065
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: 8849.22 26381 1061906
customer order: creation 158.32 404 18998
customer order: refuse 14.50 366 1740
order to supplier: creation 42.18 1466 5062
order to supplier: removal 3.05 112494 366
invoice (draft): creation 43.33 3523 5200
invoice (draft): removal 49.50 843 5940
invoice accept: apply 39.72 18504 4766
invoice accept: cancel 44.82 822 5378
customer reserve: creation 3727.54 177 447305
customer reserve: removal 4446.04 61 533525
realization accept: apply 79.78 216 9574
realization accept: cancel 80.04 109 9605
payment from customer: creation 55.44 154 6653
payment from customer: removal 5.47 79 656
payment to supplier: creation 45.55 153 5466
payment to supplier: removal 3.41 71 409
service: total inventory turnovers 3.68 3453 442
service: total monetary turnovers 6.08 474 730
service: aggregate perf. data 0.73 19147 87
service: refresh index statistics 0.03 365094 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-03-2021 04:29:04 to 31-03-2021 04:29:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6409
TEST_TIME 2 4593
TEST_TIME 3 4537
TEST_TIME 4 4273
TEST_TIME 5 4675
TEST_TIME 6 4508
TEST_TIME 7 4639
TEST_TIME 8 5143
TEST_TIME 9 5171
TEST_TIME 10 5188
TEST_TIME 11 5539
TEST_TIME 12 5742
TEST_TIME 13 5900
TEST_TIME 14 6229
TEST_TIME 15 6324
TEST_TIME 16 6367
TEST_TIME 17 6826
TEST_TIME 18 7362
TEST_TIME 19 7756
TEST_TIME 20 7777
TEST_TIME 21 8255
TEST_TIME 22 8329
TEST_TIME 23 8599
TEST_TIME 24 8752
TEST_TIME 25 8576
TEST_TIME 26 8730
TEST_TIME 27 9305
TEST_TIME 28 9803
TEST_TIME 29 9855
TEST_TIME 30 9836
TEST_TIME 31 10236
TEST_TIME 32 10085
TEST_TIME 33 9829
TEST_TIME 34 9945
TEST_TIME 35 9855
TEST_TIME 36 9749
TEST_TIME 37 9617
TEST_TIME 38 9471
TEST_TIME 39 9659
TEST_TIME 40 9832
TEST_TIME 41 9851
TEST_TIME 42 9857
TEST_TIME 43 9908
TEST_TIME 44 9893
TEST_TIME 45 9922
TEST_TIME 46 9874
TEST_TIME 47 9729
TEST_TIME 48 9591
TEST_TIME 49 9408
TEST_TIME 50 9387
TEST_TIME 51 9352
TEST_TIME 52 9401
TEST_TIME 53 9452
TEST_TIME 54 9408
TEST_TIME 55 9364
TEST_TIME 56 9316
TEST_TIME 57 9238
TEST_TIME 58 9150
TEST_TIME 59 9147
TEST_TIME 60 9114
TEST_TIME 61 9167
TEST_TIME 62 9090
TEST_TIME 63 9074
TEST_TIME 64 9175
TEST_TIME 65 9287
TEST_TIME 66 9236
TEST_TIME 67 9215
TEST_TIME 68 9282
TEST_TIME 69 9204
TEST_TIME 70 9134
TEST_TIME 71 9249
TEST_TIME 72 9261
TEST_TIME 73 9187
TEST_TIME 74 9205
TEST_TIME 75 9099
TEST_TIME 76 9048
TEST_TIME 77 9017
TEST_TIME 78 8987
TEST_TIME 79 8958
TEST_TIME 80 8908
TEST_TIME 81 8893
TEST_TIME 82 8844
TEST_TIME 83 8820
TEST_TIME 84 8792
TEST_TIME 85 8796
TEST_TIME 86 8851
TEST_TIME 87 8858
TEST_TIME 88 8861
TEST_TIME 89 8979
TEST_TIME 90 8958
TEST_TIME 91 9007
TEST_TIME 92 8991
TEST_TIME 93 9050
TEST_TIME 94 8955
TEST_TIME 95 8945
TEST_TIME 96 8909
TEST_TIME 97 8912
TEST_TIME 98 8879
TEST_TIME 99 8816
TEST_TIME 100 8837
TEST_TIME 101 8867
TEST_TIME 102 8855
TEST_TIME 103 8842
TEST_TIME 104 8901
TEST_TIME 105 8944
TEST_TIME 106 8942
TEST_TIME 107 8953
TEST_TIME 108 8966
TEST_TIME 109 8957
TEST_TIME 110 8940
TEST_TIME 111 9089
TEST_TIME 112 9065
TEST_TIME 113 9079
TEST_TIME 114 9039
TEST_TIME 115 8974
TEST_TIME 116 8925
TEST_TIME 117 8927
TEST_TIME 118 8887
TEST_TIME 119 8852
TEST_TIME 120 8851
TEST_TIME 121 8778

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2021 04:29:08 to 31-03-2021 04:29:11.

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 1474360 1474360 0 0.00 0 30092 7 0 0
doc_list_biud 1474359 1474359 0 0.00 0 36373 28 0 0
sp_add_invoice_to_stock 4895 4766 129 2.63 31 220161 18505 0 129
sp_cancel_adding_invoice 5378 5378 0 0.00 5 93945 822 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 13719 367 0 0
sp_cancel_customer_reserve 533525 533525 0 0.00 1 36376 61 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 2771 80 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 3868 72 0 0
sp_cancel_supplier_invoice 5953 5940 13 0.21 1 29668 843 0 13
sp_cancel_supplier_order 366 366 0 0.00 11 1063620 112495 0 0
sp_cancel_write_off 9605 9605 0 0.00 1 14485 110 0 0
sp_client_order 18998 18998 0 0.00 4 20263 404 0 0
sp_customer_reserve 447305 447305 0 0.00 0 65801 177 0 0
sp_fill_shopping_cart 29238 29238 0 0.00 1 17585 43 0 0
sp_fill_shopping_cart_clo_res 447302 447302 0 0.00 0 23091 18 0 0
sp_get_clo_for_invoice 4762 4762 0 0.00 0 34584 408 0 0
sp_kill_cost_storno 16048 16048 0 0.00 0 8691 57 0 0
sp_kill_qstorno_ret_qs2qd 1082961 1082961 0 0.00 0 34646 33 0 0
sp_kill_qty_storno 570890 570890 0 0.00 0 36373 71 0 0
sp_lock_selected_doc 19801 19801 0 0.00 0 8057 6 0 0
sp_make_cost_storno 26455 26455 0 0.00 0 17011 72 0 0
sp_multiply_rows_for_pdistr 26455 26455 0 0.00 0 16847 40 0 0
sp_multiply_rows_for_qdistr 18998 18998 0 0.00 0 16967 211 0 0
sp_pay_from_customer 6653 6653 0 0.00 4 16158 154 0 0
sp_pay_to_supplier 5466 5466 0 0.00 5 16489 153 0 0
sp_payment_common 12118 12118 0 0.00 1 15032 33 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 13706 329 0 0
sp_qd_handle_on_invoice_upd_sts 10140 10140 0 0.00 0 24845 354 0 0
sp_qd_handle_on_reserve_upd_sts 9574 9574 0 0.00 0 15234 60 0 0
sp_reserve_write_off 9574 9574 0 0.00 6 24728 216 0 0
sp_supplier_invoice 5200 5200 0 0.00 5 106362 3523 0 0
sp_supplier_order 5062 5062 0 0.00 6 24594 1466 0 0
srv_aggregate_perf_data 87 87 0 0.00 2677 116827 19148 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1342 168512 46574 0 0
srv_find_qd_qs_mism 1016045 1016045 0 0.00 0 30090 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 38 114928 3453 0 0
srv_make_money_saldo 730 730 0 0.00 1 10029 475 0 0
srv_recalc_idx_stat 4 4 0 0.00 242353 565075 365095 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4766 4766 0 0.00 0 19759 206 0 0
t$perf-norm:sp_cancel_adding_invoice 354 354 0 0.00 0 8330 141 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4175 20 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 782 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 356 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 783 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 5513 31 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 23275 1620 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 1121 6 0 0
t$perf-norm:sp_client_order 18998 18998 0 0.00 0 16411 23 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 892 6 0 0
t$perf-norm:sp_pay_from_customer 6653 6653 0 0.00 0 7427 12 0 0
t$perf-norm:sp_pay_to_supplier 5466 5466 0 0.00 0 7386 13 0 0
t$perf-norm:sp_reserve_write_off 9574 9574 0 0.00 0 15330 13 0 0
t$perf-norm:sp_supplier_invoice 5200 5200 0 0.00 0 15312 22 0 0
t$perf-norm:sp_supplier_order 5062 5062 0 0.00 0 12743 18 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 2017 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2249 37 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 3146 22 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 1631 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 25 10 0 0
v_add_invoice_to_stock 9782 9524 258 2.63 0 7302 21 0 258
v_all_customers 34346 34346 0 0.00 0 25 1 0 0
v_all_suppliers 28520 28520 0 0.00 0 19 1 0 0
v_all_wares 37990 37990 0 0.00 0 176 1 0 0
v_cancel_adding_invoice 708 708 0 0.00 0 2654 14 0 0
v_cancel_client_order 3480 3480 0 0.00 0 5725 8 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 1504 7 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 358 3 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 757 5 0 0
v_cancel_supplier_invoice 754 728 26 3.44 0 4762 20 0 26
v_cancel_supplier_order 732 732 0 0.00 0 4136 15 0 0
v_cancel_write_off 1902 1902 0 0.00 0 1184 13 0 0
v_max_id_clo_ord 5062 5062 0 0.00 0 3593 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 5501 21 0 0
v_max_id_ord_sup 5178 5178 0 0.00 0 4051 4 0 0
v_max_non_paid_invoice 1446 1446 0 0.00 0 5091 8 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 5274 5 0 0
v_min_id_clo_ord 5062 5062 0 0.00 0 17539 14 0 0
v_min_id_clo_res 957 957 0 0.00 0 2993 13 0 0
v_min_id_ord_sup 5178 5178 0 0.00 0 4156 3 0 0
v_min_non_paid_invoice 5466 5466 0 0.00 0 11557 16 0 0
v_min_non_paid_realizn 6651 6651 0 0.00 0 14977 29 0 0
v_our_firm 9522 9522 0 0.00 0 18 1 0 0
v_reserve_write_off 19147 19147 0 0.00 0 6905 6 0 0
x_lock_depdocs_on_canc_invoice 10954 10954 0 0.00 0 16636 114 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 5747 373 0 0
x_make_qstorno_1000_1200 5062 5062 0 0.00 2 24585 1376 0 0
x_make_qstorno_1200_2000 5200 5200 0 0.00 2 94625 3370 0 0
x_make_qstorno_2100_3300 447305 447305 0 0.00 0 65799 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2021 04:29:12 to 31-03-2021 04:29:16.

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
01:57:02 6 8192000000 8800245824 8811896832 5260880 0.064 2 1 2 0 5360304 63648 35776
01:58:04 32523 8192000000 9744650784 10044862464 814715744 9.045 100 90 180 0 844711840 7747456 22248640
01:59:43 19238 8192000000 9789707008 10099142656 858411808 9.484 100 92 184 0 893362304 13149040 21801456
02:01:07 23131 8192000000 9823076160 10141872128 880133216 9.701 100 86 172 1 931923504 31764688 20025600
02:02:37 15211 8192000000 9868519168 10202923008 922142656 10.117 100 83 167 1 981433920 40813088 18478176
02:03:54 7250 8192000000 9938065664 10284924928 970394976 10.591 100 81 162 1 1044387760 56600800 17391984
02:05:06 4737 8192000000 9971562944 10329387008 1006025824 10.937 100 85 168 0 1084574656 61587440 16961392
02:06:13 2798 8192000000 9985594272 10345201664 1022930688 11.100 100 88 176 0 1098184336 57422048 17831600
02:07:19 1506 8192000000 10025727824 10391752704 1047867104 11.340 100 86 170 2 1142638912 77361632 17410176
02:08:21 2326 8192000000 10045099088 10413510656 1070162272 11.554 100 95 190 1 1155289216 65557920 19569024
02:09:25 1539 8192000000 10031143184 10409795584 1073380864 11.584 100 83 166 2 1145734528 55527232 16826432
02:10:27 9744 8192000000 9981569040 10382827520 1079115232 11.639 100 32 64 1 1122091248 36749632 6226384
02:11:37 9402 8192000000 9998559440 10404646912 1086168832 11.706 100 38 73 3 1115208352 20097520 8942000
02:12:57 5783 8192000000 10001314736 10401574912 1096467216 11.804 100 37 74 1 1121718032 17135904 8114912
02:14:03 23817 8192000000 10026283472 10420822016 1097637584 11.815 100 52 97 1 1123287664 14877888 10772192
02:15:27 8526 8192000000 10027950912 10430709760 1097578816 11.815 100 34 68 0 1139412752 34558656 7275280
02:16:36 8784 8192000000 10009656032 10415919104 1094908000 11.789 100 22 45 1 1124334992 24782816 4644176
02:17:45 4494 8192000000 9995495280 10389889024 1063503024 11.490 98 36 72 2 1089345808 18610032 7232752
02:18:52 37425 8192000000 9962665440 10330902528 994188016 10.822 93 69 138 5 1020492000 11509696 14794288
02:20:32 7574 8192000000 9929316736 10300108800 981925712 10.703 96 40 80 1 1004179952 13429808 8824432
02:21:40 6726 8192000000 9922006064 10300559360 976731216 10.652 96 33 66 2 1001183952 17594016 6858720
02:22:50 12259 8192000000 9942756368 10332004352 1026804176 11.138 99 49 97 1 1057538736 19269840 11464720
02:24:02 42778 8192000000 9948845328 10353532928 1035568448 11.222 99 15 30 0 1052987120 14179136 3239536
02:25:47 7031 8192000000 9986681904 10387611648 1056460160 11.423 100 48 97 3 1085345312 18078304 10806848
02:26:55 7596 8192000000 9978826624 10380218368 1065275504 11.507 100 38 74 1 1094267008 19094656 9896848
02:28:09 7438 8192000000 10017358864 10412531712 1083888096 11.685 100 40 79 1 1122069824 30262720 7919008
02:29:21 54490 8192000000 10045621152 10449784832 1097025696 11.809 100 46 93 1 1161453488 54584512 9843280
02:31:28 11713 8192000000 10075151344 10496131072 1106952256 11.904 100 47 94 0 1196590288 79775568 9862464
02:32:43 34807 8192000000 10121811296 10533867520 1114665488 11.977 100 51 103 2 1227215040 101138624 11410928
02:34:21 17993 8192000000 10128618608 10544742400 1124028624 12.065 100 39 78 0 1227179024 94846624 8303776
02:35:45 8156 8192000000 10169021360 10577649664 1135919424 12.177 100 54 107 0 1273513008 125567312 12026272
02:37:00 8267 8192000000 10204988288 10619326464 1152592144 12.334 100 47 92 0 1311675904 148663552 10420208
02:38:11 4711 8192000000 10235672224 10641858560 1159294080 12.397 100 68 135 2 1337386192 163442352 14649760
02:39:21 3050 8192000000 10270195872 10673475584 1160559872 12.409 100 75 146 0 1368218448 191742864 15915712
02:40:30 5551 8192000000 10304202352 10723770368 1158604976 12.390 99 70 140 0 1409365920 235414336 15346608
02:41:42 6307 8192000000 10308710176 10716213248 1170952640 12.506 100 84 165 1 1404398352 215014432 18431280
02:42:54 2053 8192000000 10314292480 10730614784 1133627808 12.156 98 86 171 2 1402400400 249681792 19090800
02:44:01 1337 8192000000 10342092528 10760089600 1157557488 12.380 100 84 165 1 1439505872 261826896 20121488
02:45:07 7892 8192000000 10348670336 10768732160 1153307120 12.341 100 52 104 1 1440995392 275986832 11701440
02:46:20 4498 8192000000 10295089024 10711076864 1156998000 12.375 100 48 96 2 1389971632 221474400 11499232
02:47:29 16495 8192000000 10301505696 10734256128 1158573568 12.390 100 36 72 0 1428060192 259981136 9505488
02:49:01 16471 8192000000 10294272592 10743062528 1133304000 12.152 99 23 44 0 1392909104 253757792 5847312
02:50:23 17414 8192000000 10238761728 10679738368 1110162464 11.934 98 23 46 0 1363423632 247490224 5770944
02:51:46 14000 8192000000 10281758848 10708398080 1114064256 11.971 99 41 82 0 1391664528 267818048 9782224
02:53:02 35537 8192000000 10236176224 10726301696 1100713696 11.844 98 20 40 1 1375483664 269480896 5289072
02:54:46 43157 8192000000 10135545952 10567815168 1098705568 11.825 99 16 32 1 1268581760 166197152 3679040
02:56:32 17046 8192000000 10127823616 10590040064 1094010848 11.781 99 16 30 1 1233840880 136274080 3555952
02:57:51 46237 8192000000 10061642672 10527776768 1092960496 11.771 99 20 39 0 1149402176 52163360 4278320
02:59:39 25632 8192000000 10092563200 10506215424 1117704992 12.005 100 68 137 2 1176344496 43776256 14863248
03:01:17 98645 8192000000 10065916576 10489012224 1099758224 11.835 98 21 42 1 1136312976 32754928 3799824
03:03:57 49268 8192000000 10162031952 10600435712 1137995104 12.197 100 19 38 0 1223721696 80321552 5405040
03:06:00 47506 8192000000 10092781216 10545401856 1117322064 12.002 98 12 22 2 1173397504 53881248 2194192
03:07:52 72716 8192000000 10090258048 10558713856 1138262608 12.199 99 8 16 0 1171554496 31918944 1372944
03:10:44 84446 8192000000 10093519696 10533163008 1131536912 12.136 98 5 10 0 1168968976 36549632 882432
03:13:23 168512 8192000000 10128874832 10556825600 1150353840 12.313 100 46 92 0 1221506144 61724624 9427680
03:17:15 18772 8192000000 10125916560 10561789952 1138850176 12.205 99 58 116 3 1213457792 61944032 12663584
03:18:36 71273 8192000000 10133277984 10591141888 1143560656 12.249 99 20 40 0 1227044000 78926336 4557008
03:20:48 25077 8192000000 10094292912 10554028032 1131099712 12.132 98 34 67 0 1183549696 44591136 7858848
03:22:16 40344 8192000000 10088191968 10539761664 1133977424 12.159 99 19 38 0 1171606496 33501232 4127840
03:24:09 41228 8192000000 10110615248 10583810048 1149701488 12.307 100 10 20 1 1211247360 59501120 2044752
03:26:20 98402 8192000000 10070016848 10576932864 1121468816 12.041 98 20 40 0 1182522672 56672944 4380912
03:28:59 61453 8192000000 10134779120 10593476608 1101214592 11.849 96 27 54 1 1185144704 78191488 5738624
03:31:18 50880 8192000000 10076404240 10580164608 1102875856 11.865 98 21 42 0 1175327216 68137152 4314208
03:33:40 54747 8192000000 10032999424 10521784320 1072359632 11.575 96 19 38 3 1154227040 77634080 4233328
03:35:35 21487 8192000000 10062777040 10498797568 1098988160 11.828 99 54 108 0 1146211408 35881648 11341600
03:37:00 48104 8192000000 10072028864 10594525184 1107474512 11.909 100 16 32 0 1227181136 116183536 3523088
03:38:52 109844 8192000000 10080888880 10559270912 1067832880 11.531 95 13 26 1 1100896288 30148208 2915200
03:41:42 88409 8192000000 10038875760 10495029248 1075214224 11.602 96 32 63 1 1144205456 61509696 7481536
03:44:25 72284 8192000000 10045826336 10520178688 1106109712 11.896 100 14 28 0 1159250192 50208208 2932272
03:46:37 34670 8192000000 10023829808 10487914496 1072299360 11.574 97 12 24 1 1110591072 35547952 2743760
03:48:25 29136 8192000000 10024795936 10452860928 1078422896 11.632 98 28 56 2 1109273024 25213552 5636576
03:49:54 59963 8192000000 10063962384 10496897024 1073409376 11.585 97 43 86 2 1168656944 86493632 8753936
03:52:15 92451 8192000000 10063295728 10516619264 1111603840 11.948 100 11 22 0 1158004864 44344144 2056880
03:54:53 66177 8192000000 10043662848 10531426304 1067476240 11.528 95 14 27 1 1150614256 79743552 3394464
03:56:59 28390 8192000000 10094251968 10535092224 1106101680 11.895 100 40 75 2 1179154752 65414352 7638720
03:59:12 47447 8192000000 10072044032 10554425344 1080104400 11.648 97 22 44 0 1181109088 96569392 4435296
04:01:00 110608 8192000000 10096479744 10584457216 1112903952 11.960 100 26 52 1 1209970272 91593904 5472416
04:03:53 42041 8192000000 10053606544 10570252288 1115007616 11.980 100 3 6 0 1157923920 42356816 559488
04:05:35 101263 8192000000 10096817936 10603954176 1117967456 12.008 99 19 38 2 1177849680 55710704 4171520
04:09:38 93001 8192000000 10124536720 10645639168 1146329712 12.275 100 17 34 0 1201727952 52374448 3023792
04:12:11 73897 8192000000 10091762672 10552696832 1141105200 12.226 99 16 32 1 1188301920 43301408 3895312
04:14:25 92168 8192000000 10130535744 10583957504 1108968304 11.923 96 19 38 1 1163488544 50452304 4067936
04:17:01 60240 8192000000 10109101744 10675843072 1124244768 12.067 98 8 16 0 1229177312 103238480 1694064
04:19:29 48025 8192000000 10080085024 10519605248 1111150784 11.943 98 16 31 1 1136188736 21125200 3912752
04:21:29 82300 8192000000 10111334784 10555871232 1129328192 12.115 99 18 35 1 1181077296 47829008 3920096
04:23:52 66325 8192000000 10125030176 10622345216 1132703120 12.147 99 15 30 1 1205690672 70110928 2876624
04:25:58 64773 8192000000 9848351088 10293731328 886933280 9.769 77 16 32 0 976309488 85565264 3810944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-03-2021 04:29:18 to 31-03-2021 04:29:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2021 04:29:34 to 31-03-2021 04:29:36.

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          225492
MON$OLDEST_ACTIVE               229894
MON$OLDEST_SNAPSHOT             229894
MON$NEXT_TRANSACTION            229894
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                       2115200
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2394 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:93: 
:94: mscs-test-161.budzdorov.ru    Wed Mar 31 04:27:22 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

31.03.21 04:29:37. 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-03-31 04:29:39 No severe PSQL-related problems occured
31.03.21 04:29:41. End of report.