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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16346404 kB
MemAvailable 30990432 kB
Buffers 0 kB
Cached 15369008 kB
SwapCached 3644 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-22 22:37:44
DTS_END                         2021-08-22 22:37:44.0660
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(57:17)
IP                              ::1/60840
TRN_ID                          231848
ATT_ID                          668
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: 9014.55 18081 1081746
customer order: creation 168.93 337 20271
customer order: refuse 14.85 381 1782
order to supplier: creation 44.34 1350 5321
order to supplier: removal 3.43 104031 412
invoice (draft): creation 44.52 3455 5342
invoice (draft): removal 50.82 830 6098
invoice accept: apply 41.04 19273 4925
invoice accept: cancel 45.83 795 5500
customer reserve: creation 3788.33 191 454599
customer reserve: removal 4522.34 62 542681
realization accept: apply 82.06 188 9847
realization accept: cancel 82.00 106 9840
payment from customer: creation 58.79 101 7055
payment from customer: removal 5.60 66 672
payment to supplier: creation 46.83 134 5620
payment to supplier: removal 2.93 114 351
service: total inventory turnovers 4.86 1183 583
service: total monetary turnovers 6.28 35 754
service: aggregate perf. data 0.75 25527 90
service: refresh index statistics 0.03 203452 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-08-2021 22:39:55 to 22-08-2021 22:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3328
TEST_TIME 2 5039
TEST_TIME 3 5830
TEST_TIME 4 6028
TEST_TIME 5 5252
TEST_TIME 6 5490
TEST_TIME 7 5378
TEST_TIME 8 5308
TEST_TIME 9 5444
TEST_TIME 10 5455
TEST_TIME 11 5846
TEST_TIME 12 5900
TEST_TIME 13 5980
TEST_TIME 14 6256
TEST_TIME 15 6530
TEST_TIME 16 6575
TEST_TIME 17 7015
TEST_TIME 18 6993
TEST_TIME 19 7373
TEST_TIME 20 7925
TEST_TIME 21 8235
TEST_TIME 22 8417
TEST_TIME 23 8791
TEST_TIME 24 8836
TEST_TIME 25 9167
TEST_TIME 26 9664
TEST_TIME 27 9969
TEST_TIME 28 9955
TEST_TIME 29 10777
TEST_TIME 30 10802
TEST_TIME 31 10805
TEST_TIME 32 10611
TEST_TIME 33 10389
TEST_TIME 34 10247
TEST_TIME 35 10075
TEST_TIME 36 9905
TEST_TIME 37 9763
TEST_TIME 38 9653
TEST_TIME 39 9591
TEST_TIME 40 9510
TEST_TIME 41 9522
TEST_TIME 42 9471
TEST_TIME 43 9424
TEST_TIME 44 9326
TEST_TIME 45 9252
TEST_TIME 46 9121
TEST_TIME 47 9032
TEST_TIME 48 9106
TEST_TIME 49 9000
TEST_TIME 50 8918
TEST_TIME 51 8822
TEST_TIME 52 8847
TEST_TIME 53 8827
TEST_TIME 54 8773
TEST_TIME 55 8826
TEST_TIME 56 8876
TEST_TIME 57 8890
TEST_TIME 58 8908
TEST_TIME 59 8979
TEST_TIME 60 8948
TEST_TIME 61 9006
TEST_TIME 62 9028
TEST_TIME 63 9092
TEST_TIME 64 9173
TEST_TIME 65 9101
TEST_TIME 66 9117
TEST_TIME 67 9206
TEST_TIME 68 9120
TEST_TIME 69 9029
TEST_TIME 70 9057
TEST_TIME 71 8982
TEST_TIME 72 8932
TEST_TIME 73 8957
TEST_TIME 74 8981
TEST_TIME 75 8924
TEST_TIME 76 8884
TEST_TIME 77 8840
TEST_TIME 78 8760
TEST_TIME 79 8827
TEST_TIME 80 8861
TEST_TIME 81 8830
TEST_TIME 82 8838
TEST_TIME 83 8816
TEST_TIME 84 8836
TEST_TIME 85 8890
TEST_TIME 86 8881
TEST_TIME 87 8847
TEST_TIME 88 8922
TEST_TIME 89 9011
TEST_TIME 90 9028
TEST_TIME 91 8998
TEST_TIME 92 9085
TEST_TIME 93 9054
TEST_TIME 94 9103
TEST_TIME 95 9061
TEST_TIME 96 9017
TEST_TIME 97 8972
TEST_TIME 98 8971
TEST_TIME 99 8940
TEST_TIME 100 8926
TEST_TIME 101 8870
TEST_TIME 102 8898
TEST_TIME 103 8888
TEST_TIME 104 8923
TEST_TIME 105 8986
TEST_TIME 106 8992
TEST_TIME 107 8952
TEST_TIME 108 8962
TEST_TIME 109 9099
TEST_TIME 110 9086
TEST_TIME 111 9054
TEST_TIME 112 9098
TEST_TIME 113 9163
TEST_TIME 114 9183
TEST_TIME 115 9153
TEST_TIME 116 9147
TEST_TIME 117 9081
TEST_TIME 118 9084
TEST_TIME 119 9066
TEST_TIME 120 9026

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-08-2021 22:39:58 to 22-08-2021 22:40:02.

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 1501917 1501917 0 0.00 0 18222 6 0 0
doc_list_biud 1501916 1501916 0 0.00 0 58501 28 0 0
sp_add_invoice_to_stock 5000 4925 75 1.50 30 195992 19273 0 75
sp_cancel_adding_invoice 5500 5500 0 0.00 5 56637 796 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 23090 382 0 0
sp_cancel_customer_reserve 542681 542681 0 0.00 1 58503 62 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 4109 67 0 0
sp_cancel_pay_to_supplier 351 351 0 0.00 2 6430 115 0 0
sp_cancel_supplier_invoice 6106 6098 8 0.13 2 23867 831 0 8
sp_cancel_supplier_order 412 412 0 0.00 11 1051341 104031 0 0
sp_cancel_write_off 9840 9840 0 0.00 1 8024 106 0 0
sp_client_order 20271 20271 0 0.00 3 30257 337 0 0
sp_customer_reserve 454599 454599 0 0.00 0 32703 191 0 0
sp_fill_shopping_cart 30922 30922 0 0.00 1 16745 42 0 0
sp_fill_shopping_cart_clo_res 454586 454586 0 0.00 0 12950 14 0 0
sp_get_clo_for_invoice 4906 4906 0 0.00 0 38262 323 0 0
sp_kill_cost_storno 16363 16363 0 0.00 0 7427 55 0 0
sp_kill_qstorno_ret_qs2qd 1101712 1101712 0 0.00 0 58499 34 0 0
sp_kill_qty_storno 581066 581066 0 0.00 0 58501 73 0 0
sp_lock_selected_doc 20392 20392 0 0.00 0 7818 5 0 0
sp_make_cost_storno 27428 27428 0 0.00 0 16112 53 0 0
sp_multiply_rows_for_pdistr 27428 27428 0 0.00 0 5983 30 0 0
sp_multiply_rows_for_qdistr 20271 20271 0 0.00 0 30240 138 0 0
sp_pay_from_customer 7055 7055 0 0.00 4 9286 102 0 0
sp_pay_to_supplier 5620 5620 0 0.00 4 12530 134 0 0
sp_payment_common 12675 12675 0 0.00 1 9531 25 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 23081 349 0 0
sp_qd_handle_on_invoice_upd_sts 10406 10406 0 0.00 0 17978 336 0 0
sp_qd_handle_on_reserve_upd_sts 9847 9847 0 0.00 0 17835 71 0 0
sp_reserve_write_off 9847 9847 0 0.00 6 17959 189 0 0
sp_supplier_invoice 5342 5342 0 0.00 6 81922 3456 0 0
sp_supplier_order 5321 5321 0 0.00 5 44407 1350 0 0
srv_aggregate_perf_data 90 90 0 0.00 2755 322804 25527 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1448 123268 44595 0 0
srv_find_qd_qs_mism 1034652 1034652 0 0.00 0 15402 6 0 0
srv_make_invnt_saldo 583 583 0 0.00 39 97121 1183 0 0
srv_make_money_saldo 754 754 0 0.00 1 3785 35 0 0
srv_recalc_idx_stat 3 3 0 0.00 131519 257100 203452 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 10368 178 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 1 7622 122 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 4847 18 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 2883 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 4302 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 351 351 0 0.00 0 216 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 3621 23 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 26933 1627 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 3005 8 0 0
t$perf-norm:sp_client_order 20271 20271 0 0.00 0 5837 18 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 958 5 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 3118 8 0 0
t$perf-norm:sp_pay_to_supplier 5620 5620 0 0.00 0 5171 10 0 0
t$perf-norm:sp_reserve_write_off 9847 9847 0 0.00 0 5046 11 0 0
t$perf-norm:sp_supplier_invoice 5342 5342 0 0.00 0 4962 17 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 5725 19 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 167 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 810 19 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 1325 16 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 24 9 0 0
v_add_invoice_to_stock 9962 9812 150 1.50 0 11868 19 0 150
v_all_customers 36782 36782 0 0.00 0 16 1 0 0
v_all_suppliers 29376 29376 0 0.00 0 106 1 0 0
v_all_wares 40540 40540 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 158 6 0 0
v_cancel_client_order 3564 3564 0 0.00 0 2526 4 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 4103 7 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 932 4 0 0
v_cancel_payment_to_supplier 702 702 0 0.00 0 3792 17 0 0
v_cancel_supplier_invoice 842 826 16 1.90 0 2104 15 0 16
v_cancel_supplier_order 824 824 0 0.00 0 7815 18 0 0
v_cancel_write_off 1962 1962 0 0.00 0 744 13 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 5329 2 0 0
v_max_id_clo_res 966 966 0 0.00 0 1588 8 0 0
v_max_id_ord_sup 5331 5331 0 0.00 0 2433 3 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 3675 5 0 0
v_max_non_paid_realizn 3879 3879 0 0.00 0 5451 5 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 3827 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 2449 8 0 0
v_min_id_ord_sup 5331 5331 0 0.00 0 4093 7 0 0
v_min_non_paid_invoice 5620 5620 0 0.00 0 9529 15 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 5820 14 0 0
v_our_firm 10110 10110 0 0.00 0 13 1 0 0
v_reserve_write_off 19694 19694 0 0.00 0 2956 4 0 0
x_lock_depdocs_on_canc_invoice 11185 11185 0 0.00 0 21143 138 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 11163 419 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 1 44397 1259 0 0
x_make_qstorno_1200_2000 5342 5342 0 0.00 3 81917 3306 0 0
x_make_qstorno_2100_3300 454599 454599 0 0.00 0 32702 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-08-2021 22:40:03 to 22-08-2021 22:40:07.

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
20:07:47 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:48 22950 8192000000 10367062176 10685317120 1415442880 14.732 100 86 172 0 1450448464 6239488 28766096
20:10:17 17825 8192000000 10419605120 10747494400 1471962176 15.231 100 84 168 0 1507630496 7953392 27714928
20:11:37 17973 8192000000 10463550832 10801328128 1517671920 15.630 100 83 166 0 1553093120 8826848 26594352
20:13:00 12556 8192000000 10551303968 10906144768 1605996160 16.391 100 80 158 0 1639708576 10233264 23479152
20:14:17 8113 8192000000 10619451840 10986110976 1682693936 17.040 100 78 156 2 1717926640 12517104 22715600
20:15:28 8148 8192000000 10684539280 11062509568 1748254288 17.587 100 74 146 3 1781489568 12623488 20611792
20:16:38 4597 8192000000 10734801216 11114582016 1800807584 18.021 100 83 164 1 1837906080 13624656 23473840
20:17:45 2760 8192000000 10771824640 11162890240 1838878432 18.332 100 87 173 2 1877596752 14890784 23827536
20:18:49 1817 8192000000 10810124832 11193098240 1863598752 18.532 100 95 190 2 1903948112 13343696 27005664
20:19:52 14119 8192000000 10804368800 11203325952 1870352752 18.587 100 61 125 0 1896725440 8637104 17735584
20:21:07 4644 8192000000 10789654960 11216453632 1883624448 18.694 100 27 54 1 1898438432 7307856 7506128
20:22:18 16368 8192000000 10814614320 11235659776 1893321392 18.773 100 29 57 0 1908674240 5866928 9485920
20:23:37 8714 8192000000 10850559664 11252985856 1908159808 18.892 100 61 122 2 1932253200 6267712 17825680
20:24:50 5692 8192000000 10834613456 11261313024 1913904288 18.938 100 22 44 0 1926363664 5854464 6604912
20:25:57 6900 8192000000 10841922368 11274403840 1923304352 19.013 100 29 58 0 1938045504 6841792 7899360
20:27:04 6523 8192000000 10814958432 11247800320 1863150624 18.529 97 17 34 2 1874356032 5102016 6103392
20:28:13 8181 8192000000 10792596064 11234123776 1892411776 18.765 100 35 70 0 1909381728 6701088 10268864
20:29:22 5614 8192000000 10752378672 11178147840 1817010432 18.153 98 23 46 3 1833692496 9802384 6879680
20:30:29 7834 8192000000 10730879296 11142483968 1771475920 17.779 97 37 74 1 1788434608 5448592 11510096
20:31:41 10136 8192000000 10703213968 11117035520 1761948448 17.701 98 25 51 0 1774374592 4993072 7433072
20:32:51 12286 8192000000 10756925968 11137744896 1781625664 17.863 98 82 168 2 1812461376 6032464 24803248
20:34:03 6110 8192000000 10756648928 11167535104 1818382752 18.164 99 28 56 0 1833056992 6567312 8106928
20:35:11 14451 8192000000 10760623568 11188658176 1850846352 18.429 100 26 53 0 1864325152 5794384 7684416
20:36:28 35018 8192000000 10780700256 11204591616 1860697408 18.509 100 18 36 1 1871083152 5664464 4721280
20:38:06 34958 8192000000 10836762736 11254960128 1904091152 18.859 100 47 92 1 1924377856 6804592 13482112
20:39:44 4613 8192000000 10866046240 11284688896 1928554032 19.055 100 59 118 1 1959776432 13594064 17628336
20:40:54 12248 8192000000 10877724864 11302666240 1943821600 19.177 100 50 99 1 1971729952 13049712 14858640
20:42:08 31139 8192000000 10888552880 11317063680 1951989072 19.242 100 37 73 2 1975283760 12576240 10718448
20:43:44 14473 8192000000 10907393536 11340296192 1970769920 19.392 100 44 88 5 2000869504 17301792 12797792
20:45:09 7888 8192000000 10926067152 11356041216 1980121360 19.466 100 46 92 0 2016022784 22511184 13390240
20:46:23 17932 8192000000 10938006352 11369480192 1992137376 19.561 100 61 122 1 2035555408 25498544 17919488
20:47:45 4688 8192000000 10955030320 11375423488 1988841952 19.535 100 70 140 0 2039121664 29228720 21050992
20:48:53 4482 8192000000 10956561760 11381170176 1993880992 19.574 100 63 127 1 2041493344 29191072 18421280
20:50:03 7229 8192000000 10990711840 11406753792 2011729312 19.715 100 82 163 1 2072734208 36371184 24633712
20:51:16 9050 8192000000 10977751184 11409035264 1998385392 19.610 99 62 124 4 2053045184 35188624 19471168
20:52:41 3681 8192000000 10972806560 11395031040 1979345904 19.460 98 75 150 0 2043656096 41298112 23012080
20:53:48 1726 8192000000 11019049808 11435921408 2024148384 19.813 100 83 163 1 2095568448 46833968 24586096
20:54:55 3713 8192000000 11019949568 11452006400 2034975744 19.898 100 71 141 0 2106205872 48732624 22497504
20:56:07 1445 8192000000 10995756400 11414368256 1992389088 19.563 99 83 166 2 2073116736 54128656 26598992
20:57:14 1910 8192000000 11017634272 11435008000 2012801488 19.724 100 88 177 1 2099529264 59146896 27580880
20:58:21 6843 8192000000 10947109376 11377258496 1940056784 19.147 98 60 115 1 2007973440 48825392 19091264
20:59:33 7529 8192000000 10965488032 11393732608 1969536624 19.382 100 51 104 0 2041139472 54941536 16661312
21:00:47 12256 8192000000 10930160352 11375579136 1943595984 19.175 99 27 54 2 2002811504 50145056 9070464
21:02:18 19512 8192000000 10917456384 11366043648 1936134896 19.116 99 27 54 3 1998038624 51783408 10120320
21:03:45 16073 8192000000 10897286624 11352256512 1926820112 19.041 99 21 40 0 1973471968 39408800 7243056
21:05:40 51510 8192000000 10873681344 11350429696 1880806624 18.672 97 19 38 2 1933939536 46401952 6730960
21:07:38 26326 8192000000 10880232640 11323166720 1924781680 19.025 99 23 43 2 1943746256 12932608 6031968
21:09:05 63297 8192000000 10879740048 11336273920 1921304720 18.997 98 14 28 0 1931569360 6791008 3473632
21:11:09 39061 8192000000 10855960544 11323912192 1907670896 18.888 98 11 22 1 1918217968 7648704 2898368
21:12:48 57978 8192000000 10875577728 11325378560 1926292928 19.037 99 15 30 1 1938321312 7784672 4243712
21:14:50 53644 8192000000 10905112688 11360985088 1963754160 19.336 100 29 57 2 1983999328 12137056 8108112
21:16:46 24504 8192000000 10908827168 11367907328 1969824048 19.384 100 35 71 0 1996134304 15431584 10878672
21:18:13 38952 8192000000 10892246384 11362308096 1929683520 19.064 98 19 38 1 1951120384 15849104 5587760
21:19:57 49182 8192000000 10878200080 11338231808 1949671248 19.224 100 17 35 0 1965186224 10634352 4880624
21:21:51 50635 8192000000 10891806288 11359137792 1962341040 19.325 100 10 20 0 1978407952 12896752 3170160
21:23:42 107641 8192000000 10889879664 11369512960 1955785920 19.273 99 34 68 2 1978704048 13107392 9810736
21:26:31 50337 8192000000 10906002480 11368345600 1961837232 19.321 99 22 45 2 1983229664 15587568 5804864
21:28:22 60432 8192000000 10885837984 11368267776 1953915248 19.258 99 15 30 0 1968662096 10211952 4534896
21:30:24 34956 8192000000 10906111072 11369201664 1973066672 19.410 100 8 16 0 1981552336 6795760 1689904
21:32:08 70226 8192000000 10911236176 11388878848 1948168640 19.212 98 14 28 0 1961765632 9273712 4323280
21:34:23 115521 8192000000 10928379040 11405185024 1960708672 19.312 98 17 35 1 1980720272 15073376 4938224
21:37:19 45766 8192000000 10941082512 11412971520 2009861920 19.700 100 26 52 2 2025292816 8544352 6886544
21:39:07 117433 8192000000 10902604976 11378327552 1840333216 18.344 92 11 22 1 1858335680 14991072 3011392
21:42:04 74105 8192000000 10884475104 11349385216 1846460608 18.393 93 17 34 0 1861415344 10345808 4608928
21:44:19 123268 8192000000 10891609360 11361132544 1962469488 19.326 100 20 40 1 1981653664 13328128 5856048
21:47:50 81830 8192000000 10885442304 11347329024 1867373424 18.563 95 18 34 2 1883413984 10740544 5300016
21:50:12 60091 8192000000 10880614480 11365908480 1945790080 19.193 99 9 18 0 1960780976 11980864 3010032
21:52:19 16437 8192000000 10868235792 11329486848 1932213744 19.085 99 26 52 2 1944976336 5528448 7234144
21:53:37 50609 8192000000 10864058912 11331522560 1924535536 19.023 99 18 36 1 1945152304 15336736 5280032
21:55:30 51016 8192000000 10845981056 11314487296 1911990928 18.923 99 17 34 1 1933794416 16328032 5475456
21:58:07 44331 8192000000 10840806176 11309060096 1873219808 18.610 97 12 25 1 1882419488 6460432 2739248
22:00:19 23911 8192000000 10884760656 11333910528 1911326384 18.917 98 25 50 1 1926276064 7687456 7262224
22:01:45 78804 8192000000 10874233472 11354537984 1932832896 19.090 99 17 34 0 1948348064 11535968 3979200
22:04:04 24797 8192000000 10902639360 11354644480 1944447856 19.182 99 32 64 2 1968351280 14725504 9177920
22:05:37 64229 8192000000 10874323504 11346186240 1914257728 18.941 98 15 30 0 1936955504 18071344 4626432
22:07:50 60539 8192000000 10903510384 11354714112 1898895376 18.817 97 22 44 1 1915330192 10611296 5823520
22:09:51 56218 8192000000 10923526880 11366211584 1945109520 19.188 98 33 66 2 1964405616 10104496 9191600
22:11:50 98281 8192000000 10871070288 11332108288 1909006096 18.899 97 33 66 0 1930644656 12657120 8981440
22:14:49 31339 8192000000 10892458448 11348267008 1941243568 19.157 99 31 62 1 1959867984 9708128 8916288
22:16:21 87090 8192000000 10879003968 11360624640 1936084272 19.115 99 11 21 0 1953602096 14608400 2909424
22:18:50 51231 8192000000 10882671520 11370213376 1950580928 19.231 99 12 24 0 1973064080 18630000 3853152
22:21:11 95942 8192000000 10878379584 11368542208 1921194064 18.996 98 18 36 1 1939186864 12764240 5228560
22:23:50 36119 8192000000 10907226528 11372326912 1960776288 19.312 99 27 54 1 1978566592 10188448 7601856
22:25:28 73186 8192000000 10896098016 11387822080 1952952992 19.250 99 17 34 1 1973211824 15538352 4720480
22:27:41 49614 8192000000 10897683952 11367743488 1951752208 19.240 99 27 54 0 1970061232 9951168 8357856
22:29:36 92177 8192000000 10901111568 11386040320 1943994624 19.179 99 18 36 0 1972063728 23204352 4864752
22:32:17 30149 8192000000 10902776848 11358998528 1921408160 18.998 98 35 70 0 1941979184 11302256 9268768
22:33:48 111146 8192000000 10845628848 11329454080 1905684496 18.872 98 35 70 2 1928470992 12151024 10635472
22:36:39 51348 8192000000 10851520288 11341148160 1934322912 19.101 100 11 21 1 1944627184 7507616 2796656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-08-2021 22:40:09 to 22-08-2021 22:40:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-08-2021 22:40:25 to 22-08-2021 22:40:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231847
MON$OLDEST_ACTIVE               232692
MON$OLDEST_SNAPSHOT             232692
MON$NEXT_TRANSACTION            232692
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2023296
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:292: 
:293: mscs-test-161.budzdorov.ru    Sun Aug 22 22:37:54 2021
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:295: 
:296: 
:297: mscs-test-161.budzdorov.ru    Sun Aug 22 22:37:55 2021
:298:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:299: 
--- end of diff output ---

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

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