Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.03.2021 13: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 16964892 kB
MemAvailable 31661652 kB
Buffers 0 kB
Cached 14738892 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-05 13:26:55
DTS_END                         2021-03-05 13:26:55.3000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42572
TRN_ID                          237111
ATT_ID                          642
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: 8872.72 19839 1064726
customer order: creation 172.98 396 20758
customer order: refuse 14.47 324 1736
order to supplier: creation 46.47 1366 5576
order to supplier: removal 3.46 104287 415
invoice (draft): creation 46.14 3613 5537
invoice (draft): removal 48.61 858 5833
invoice accept: apply 41.84 18898 5021
invoice accept: cancel 44.16 955 5299
customer reserve: creation 3841.72 188 461006
customer reserve: removal 4324.27 67 518912
realization accept: apply 84.88 159 10186
realization accept: cancel 74.48 117 8938
payment from customer: creation 59.93 100 7192
payment from customer: removal 5.88 76 705
payment to supplier: creation 48.63 120 5836
payment to supplier: removal 3.36 67 403
service: total inventory turnovers 3.77 3436 452
service: total monetary turnovers 6.64 464 797
service: aggregate perf. data 0.98 12512 118
service: refresh index statistics 0.05 248769 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-03-2021 13:28:54 to 05-03-2021 13:28:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4125
TEST_TIME 2 4777
TEST_TIME 3 6296
TEST_TIME 4 5797
TEST_TIME 5 5727
TEST_TIME 6 6013
TEST_TIME 7 5871
TEST_TIME 8 6004
TEST_TIME 9 6214
TEST_TIME 10 6261
TEST_TIME 11 6370
TEST_TIME 12 6537
TEST_TIME 13 6480
TEST_TIME 14 6724
TEST_TIME 15 6940
TEST_TIME 16 7203
TEST_TIME 17 7461
TEST_TIME 18 7579
TEST_TIME 19 8648
TEST_TIME 20 8565
TEST_TIME 21 8600
TEST_TIME 22 8492
TEST_TIME 23 9255
TEST_TIME 24 9108
TEST_TIME 25 9445
TEST_TIME 26 10209
TEST_TIME 27 10866
TEST_TIME 28 11046
TEST_TIME 29 10952
TEST_TIME 30 10639
TEST_TIME 31 10728
TEST_TIME 32 10422
TEST_TIME 33 10257
TEST_TIME 34 10133
TEST_TIME 35 10104
TEST_TIME 36 10079
TEST_TIME 37 10081
TEST_TIME 38 9954
TEST_TIME 39 9909
TEST_TIME 40 9891
TEST_TIME 41 9814
TEST_TIME 42 9993
TEST_TIME 43 9969
TEST_TIME 44 9854
TEST_TIME 45 10031
TEST_TIME 46 9966
TEST_TIME 47 9968
TEST_TIME 48 10025
TEST_TIME 49 10008
TEST_TIME 50 10030
TEST_TIME 51 9929
TEST_TIME 52 10067
TEST_TIME 53 10001
TEST_TIME 54 9823
TEST_TIME 55 9726
TEST_TIME 56 9665
TEST_TIME 57 9558
TEST_TIME 58 9446
TEST_TIME 59 9337
TEST_TIME 60 9327
TEST_TIME 61 9380
TEST_TIME 62 9347
TEST_TIME 63 9297
TEST_TIME 64 9294
TEST_TIME 65 9387
TEST_TIME 66 9350
TEST_TIME 67 9410
TEST_TIME 68 9486
TEST_TIME 69 9441
TEST_TIME 70 9415
TEST_TIME 71 9517
TEST_TIME 72 9524
TEST_TIME 73 9549
TEST_TIME 74 9471
TEST_TIME 75 9410
TEST_TIME 76 9334
TEST_TIME 77 9271
TEST_TIME 78 9232
TEST_TIME 79 9166
TEST_TIME 80 9134
TEST_TIME 81 9189
TEST_TIME 82 9198
TEST_TIME 83 9172
TEST_TIME 84 9134
TEST_TIME 85 9177
TEST_TIME 86 9124
TEST_TIME 87 9164
TEST_TIME 88 9205
TEST_TIME 89 9172
TEST_TIME 90 9172
TEST_TIME 91 9082
TEST_TIME 92 9046
TEST_TIME 93 9023
TEST_TIME 94 8982
TEST_TIME 95 8968
TEST_TIME 96 8955
TEST_TIME 97 8934
TEST_TIME 98 8929
TEST_TIME 99 8912
TEST_TIME 100 8974
TEST_TIME 101 8959
TEST_TIME 102 8935
TEST_TIME 103 9017
TEST_TIME 104 9051
TEST_TIME 105 9132
TEST_TIME 106 9121
TEST_TIME 107 9150
TEST_TIME 108 9133
TEST_TIME 109 9192
TEST_TIME 110 9256
TEST_TIME 111 9174
TEST_TIME 112 9105
TEST_TIME 113 9066
TEST_TIME 114 9013
TEST_TIME 115 9000
TEST_TIME 116 8996
TEST_TIME 117 8949
TEST_TIME 118 8921
TEST_TIME 119 8906
TEST_TIME 120 8877

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-03-2021 13:28:57 to 05-03-2021 13:29:01.

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 1491018 1491018 0 0.00 0 20479 7 0 0
doc_list_biud 1491015 1491015 0 0.00 0 74895 29 0 0
sp_add_invoice_to_stock 5121 5021 100 1.95 29 214987 18899 0 100
sp_cancel_adding_invoice 5299 5299 0 0.00 4 70914 955 0 0
sp_cancel_client_order 1736 1736 0 0.00 5 9797 325 0 0
sp_cancel_customer_reserve 518912 518912 0 0.00 1 19184 68 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 9113 76 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 1840 67 0 0
sp_cancel_supplier_invoice 5841 5833 8 0.13 2 74908 859 0 8
sp_cancel_supplier_order 415 415 0 0.00 10 1258254 104287 0 0
sp_cancel_write_off 8938 8938 0 0.00 1 8475 118 0 0
sp_client_order 20758 20758 0 0.00 4 18090 396 0 0
sp_customer_reserve 461006 461006 0 0.00 0 80744 188 0 0
sp_fill_shopping_cart 31868 31868 0 0.00 1 15580 45 0 0
sp_fill_shopping_cart_clo_res 461001 461001 0 0.00 0 25827 18 0 0
sp_get_clo_for_invoice 5013 5013 0 0.00 0 41365 404 0 0
sp_kill_cost_storno 15345 15345 0 0.00 0 8469 59 0 0
sp_kill_qstorno_ret_qs2qd 1053010 1053010 0 0.00 0 74895 36 0 0
sp_kill_qty_storno 556332 556332 0 0.00 0 74895 78 0 0
sp_lock_selected_doc 20962 20962 0 0.00 0 10500 4 0 0
sp_make_cost_storno 28227 28227 0 0.00 0 17428 51 0 0
sp_multiply_rows_for_pdistr 28227 28227 0 0.00 0 8011 28 0 0
sp_multiply_rows_for_qdistr 20758 20758 0 0.00 0 17796 231 0 0
sp_pay_from_customer 7192 7192 0 0.00 4 10322 101 0 0
sp_pay_to_supplier 5836 5836 0 0.00 5 20483 120 0 0
sp_payment_common 13028 13028 0 0.00 1 4875 14 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 9791 293 0 0
sp_qd_handle_on_invoice_upd_sts 10312 10312 0 0.00 0 40329 352 0 0
sp_qd_handle_on_reserve_upd_sts 10186 10186 0 0.00 0 14172 47 0 0
sp_reserve_write_off 10186 10186 0 0.00 7 14193 159 0 0
sp_supplier_invoice 5537 5537 0 0.00 6 103110 3613 0 0
sp_supplier_order 5576 5576 0 0.00 5 42191 1366 0 0
srv_aggregate_perf_data 118 118 0 0.00 2811 152699 12513 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2086 145087 48286 0 0
srv_find_qd_qs_mism 1016610 1016610 0 0.00 0 14140 7 0 0
srv_make_invnt_saldo 452 452 0 0.00 48 88535 3436 0 0
srv_make_money_saldo 797 797 0 0.00 1 10703 465 0 0
srv_recalc_idx_stat 6 6 0 0.00 137775 346992 248770 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5021 5021 0 0.00 0 48126 164 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 47328 212 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 5481 15 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 440 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 478 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2456 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 4372 30 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24018 1284 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 8158 11 0 0
t$perf-norm:sp_client_order 20758 20758 0 0.00 0 12745 18 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 1203 6 0 0
t$perf-norm:sp_pay_from_customer 7192 7192 0 0.00 0 6389 9 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 4091 7 0 0
t$perf-norm:sp_reserve_write_off 10186 10186 0 0.00 0 12310 9 0 0
t$perf-norm:sp_supplier_invoice 5537 5537 0 0.00 0 6702 19 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 4631 18 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 80 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 73 2 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 839 8 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1146 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 101 18 0 0
v_add_invoice_to_stock 10226 10026 200 1.95 0 3758 15 0 200
v_all_customers 37636 37636 0 0.00 0 20 1 0 0
v_all_suppliers 30538 30538 0 0.00 0 23 1 0 0
v_all_wares 41516 41516 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 10295 21 0 0
v_cancel_client_order 3472 3472 0 0.00 0 8893 7 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 5205 9 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1057 5 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 600 7 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 2927 20 0 16
v_cancel_supplier_order 830 830 0 0.00 0 3777 9 0 0
v_cancel_write_off 1986 1986 0 0.00 0 2020 13 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 497 1 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 1383 10 0 0
v_max_id_ord_sup 5535 5535 0 0.00 0 6532 5 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 85 2 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 2539 3 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 2724 5 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 7246 22 0 0
v_min_id_ord_sup 5535 5535 0 0.00 0 8602 6 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 4023 8 0 0
v_min_non_paid_realizn 7192 7192 0 0.00 0 4872 10 0 0
v_our_firm 10496 10496 0 0.00 0 12 1 0 0
v_reserve_write_off 20372 20372 0 0.00 0 8583 7 0 0
x_lock_depdocs_on_canc_invoice 10735 10735 0 0.00 0 16111 111 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8821 342 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 2 42168 1289 0 0
x_make_qstorno_1200_2000 5537 5537 0 0.00 3 103100 3434 0 0
x_make_qstorno_2100_3300 461006 461006 0 0.00 0 80734 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-03-2021 13:29:02 to 05-03-2021 13:29:06.

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
10:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:00 25968 8192000000 9725605728 10020495360 802730304 8.924 100 89 178 1 831714608 7999040 20985264
10:59:36 17860 8192000000 9761616112 10066771968 833054096 9.230 100 88 176 1 867098048 12808816 21235136
11:00:57 15250 8192000000 9803631904 10121277440 867942784 9.580 100 85 170 0 910146160 22668976 19534400
11:02:19 12973 8192000000 9870832480 10202263552 920379296 10.100 100 84 168 2 975029488 36456496 18193696
11:03:34 7184 8192000000 9923987328 10275143680 967814944 10.565 100 70 140 0 1046587456 64397520 14374992
11:04:43 2741 8192000000 9965078864 10317107200 1001227584 10.890 100 88 173 1 1083118544 64610560 17280400
11:05:48 2565 8192000000 10006358208 10368274432 1027807008 11.147 100 86 170 1 1115274080 70504256 16962816
11:06:52 1771 8192000000 10046210496 10411180032 1051369952 11.374 100 94 188 0 1155582432 85381744 18830736
11:07:55 30617 8192000000 9972703872 10367107072 1066613104 11.520 100 36 71 1 1110710688 37128592 6968992
11:09:28 9174 8192000000 9975886144 10370883584 1074540976 11.595 100 30 60 0 1092166944 10326144 7299824
11:10:38 5499 8192000000 9986124384 10387410944 1083083008 11.677 100 42 84 2 1109952448 17290160 9579280
11:11:45 34490 8192000000 10012574752 10401492992 1090269120 11.745 100 43 88 0 1111332864 12229376 8834368
11:13:19 6276 8192000000 10025751312 10422046720 1094829152 11.789 100 28 57 1 1130574288 30024448 5720688
11:14:29 6989 8192000000 10025838640 10436218880 1098146192 11.820 100 37 74 4 1136011616 29836240 8029184
11:15:38 5043 8192000000 10020722688 10420703232 1099658544 11.834 100 30 61 3 1134176368 28046496 6471328
11:16:44 36514 8192000000 9998872240 10413932544 1072637152 11.577 99 27 53 2 1099973280 22343632 4992496
11:18:20 5326 8192000000 9975127728 10362314752 1047805008 11.340 99 46 89 2 1085808896 27745904 10257984
11:19:29 5719 8192000000 9954706016 10332045312 1009184912 10.967 97 35 70 3 1048318960 30789344 8344704
11:20:36 6653 8192000000 9971752208 10352295936 1021905952 11.090 99 50 100 1 1043040032 9607232 11526848
11:21:46 30699 8192000000 9930212352 10325815296 972917856 10.615 93 24 48 0 1007594352 28862736 5813760
11:23:19 5241 8192000000 9970041216 10355638272 1041772288 11.282 99 45 89 4 1072775872 20799760 10203824
11:24:26 5890 8192000000 9998239088 10390122496 1063650368 11.491 100 47 94 1 1094457216 20694816 10112032
11:25:32 26913 8192000000 9978350240 10385387520 1073621344 11.587 100 36 72 0 1099704064 17236448 8846272
11:27:28 8431 8192000000 10029015488 10427215872 1099956768 11.837 100 60 119 2 1134600192 22811056 11832368
11:28:42 35253 8192000000 10089158288 10493390848 1114664736 11.977 100 70 138 0 1197413696 68190448 14558512
11:30:24 18363 8192000000 10093501312 10503114752 1129789152 12.119 100 51 102 2 1202966240 62711856 10465232
11:31:57 18506 8192000000 10117308656 10527199232 1141987728 12.234 100 50 100 1 1215984032 62366352 11629952
11:33:22 16728 8192000000 10122055536 10536923136 1151613504 12.325 100 52 105 1 1226519296 62898880 12006912
11:34:47 17768 8192000000 10194986176 10606841856 1156431824 12.370 100 54 106 2 1291921296 124413696 11075776
11:36:17 10653 8192000000 10213897040 10626576384 1166012848 12.460 100 48 96 1 1307357936 130466656 10878432
11:37:29 12354 8192000000 10264233776 10674954240 1175869792 12.552 100 59 118 0 1372165744 182626432 13669520
11:38:46 8216 8192000000 10270179712 10681196544 1172167232 12.517 100 63 126 1 1369820944 183251056 14402656
11:40:00 2083 8192000000 10332084256 10736381952 1179320736 12.584 100 79 155 1 1432566784 235468240 17777808
11:41:10 3008 8192000000 10354173536 10754596864 1168328544 12.481 99 77 151 2 1446893184 261483696 17080944
11:42:17 2965 8192000000 10343334768 10743676928 1151536176 12.324 99 87 174 0 1437294896 265698960 20059760
11:43:27 3430 8192000000 10353102048 10760593408 1118544416 12.013 98 76 151 3 1446113840 309957968 17611456
11:44:35 2419 8192000000 10389870752 10807496704 1140122464 12.217 100 85 168 2 1484678480 325352416 19203600
11:45:45 15068 8192000000 10323407296 10767245312 1150105248 12.310 100 33 66 2 1443459360 284854976 8499136
11:47:02 5262 8192000000 10353518112 10770128896 1147219472 12.283 100 44 88 0 1469700736 311718128 10763136
11:48:13 9272 8192000000 10400809648 10825613312 1150750128 12.317 100 53 103 1 1494351952 331290640 12311184
11:49:26 18178 8192000000 10356400208 10860019712 1140459968 12.220 99 37 74 0 1500656688 350669888 9526832
11:50:51 22053 8192000000 10364716320 10830888960 1153285248 12.340 100 30 60 0 1480250960 319114192 7851520
11:52:32 17989 8192000000 10192723216 10752520192 1120541424 12.032 100 13 26 0 1390612880 266372928 3698528
11:53:53 64241 8192000000 10055789392 10538897408 1096094960 11.801 97 9 17 0 1173269984 75440256 1734768
11:56:03 67243 8192000000 10135962800 10580008960 1094858880 11.789 97 39 78 0 1168262272 65215216 8188176
11:58:16 145087 8192000000 10064715520 10507878400 1080229856 11.650 96 35 67 1 1113882416 26697104 6955456
12:01:42 26944 8192000000 10069438080 10540670976 1102729792 11.864 98 30 59 2 1147151088 37575424 6845872
12:03:25 76864 8192000000 10085620016 10547228672 1104167776 11.877 98 12 24 0 1161113504 54501024 2444704
12:06:09 52713 8192000000 10080599168 10544533504 1113021808 11.961 98 18 37 1 1158137712 41117984 3997920
12:08:02 39953 8192000000 10094709936 10586128384 1106791920 11.902 98 19 38 0 1201786064 90186736 4807408
12:09:55 55023 8192000000 10107115264 10541527040 1103941184 11.875 97 30 60 1 1172791712 62674880 6175648
12:12:03 42169 8192000000 10082235888 10521853952 1130621488 12.127 100 24 47 0 1178921808 43671072 4629248
12:13:46 49505 8192000000 10100903664 10554064896 1126254864 12.086 99 25 50 1 1182283360 51156672 4871824
12:15:41 60146 8192000000 10077850880 10547920896 1128916144 12.111 99 23 45 1 1180797920 47092080 4789696
12:17:50 45794 8192000000 10167610896 10615414784 1120596944 12.033 98 38 76 0 1216624544 88534016 7493584
12:19:36 99294 8192000000 10057558576 10507919360 1120412032 12.031 99 23 46 1 1168842336 43792800 4637504
12:22:21 67152 8192000000 10033160576 10485379072 1080253936 11.650 96 13 26 1 1096283520 13463696 2565888
12:24:29 114124 8192000000 10124523600 10615017472 1136926640 12.187 100 22 42 1 1222866224 80990448 4949136
12:27:23 31932 8192000000 10048554832 10490028032 1114327648 11.973 99 12 23 0 1143196912 26380000 2489264
12:28:55 69862 8192000000 10114883616 10566651904 1130074256 12.122 99 24 48 2 1207307696 72045728 5187712
12:31:05 53246 8192000000 10115660240 10625507328 1113809568 11.968 98 16 32 0 1225578944 108404864 3364512
12:33:00 65886 8192000000 10089153424 10594504704 1081864464 11.665 95 14 28 0 1180020880 95766336 2390080
12:35:08 17916 8192000000 10074961056 10505314304 1120956752 12.036 99 39 78 1 1166559728 37962720 7640256
12:36:31 8810 8192000000 10125768672 10549440512 1133263120 12.152 100 55 110 0 1213028656 69056096 10709440
12:37:46 64156 8192000000 10075391792 10549522432 1061897888 11.475 94 21 42 0 1131935216 65656432 4380896
12:39:55 40704 8192000000 10056855440 10507370496 1086486976 11.709 97 38 76 0 1156150128 62051456 7611696
12:42:40 51296 8192000000 10050005936 10507350016 1113774768 11.968 99 8 16 0 1145466784 29706624 1985392
12:44:32 23807 8192000000 10045490448 10503241728 1114437456 11.974 99 8 16 0 1150547856 34193200 1917200
12:45:56 77431 8192000000 10092185952 10536112128 1070210512 11.554 95 15 30 0 1159010880 85427888 3372480
12:48:19 82717 8192000000 10072215056 10522931200 1105501968 11.890 98 16 32 1 1142444720 33690384 3252368
12:50:47 71774 8192000000 10046437072 10486206464 1099533184 11.833 97 19 38 2 1154120112 50452736 4134192
12:52:59 83867 8192000000 10098377872 10540584960 1126642224 12.090 99 23 46 1 1188039040 56300480 5096336
12:56:11 47325 8192000000 10128851936 10577358848 1134465424 12.163 99 35 70 0 1180638496 39418032 6755040
12:57:59 83778 8192000000 10075414128 10538864640 1145046864 12.263 100 20 40 1 1195251952 46124368 4080720
13:00:32 65351 8192000000 10072566640 10564640768 1147469136 12.286 100 3 6 0 1174678576 26669792 539648
13:02:37 135708 8192000000 10105124640 10555768832 1091234496 11.754 94 20 40 0 1152822992 57393280 4195216
13:05:54 110110 8192000000 10103506320 10556182528 1101467248 11.852 96 15 30 1 1152940912 48641248 2832416
13:08:45 70093 8192000000 10177354080 10662404096 1133878592 12.158 98 17 34 2 1235731840 97688448 4164800
13:11:25 101798 8192000000 10081785840 10548625408 1070576784 11.558 93 16 32 1 1119509856 45706224 3226848
13:14:11 52681 8192000000 10113892160 10540322816 1060997456 11.466 93 46 91 5 1104199216 33575872 9625888
13:16:25 72363 8192000000 10074199280 10621767680 1110967888 11.942 99 2 4 0 1166747584 55494160 285536
13:18:56 57820 8192000000 10067059520 10508722176 1081918816 11.666 96 15 29 0 1113165568 27716800 3529952
13:21:13 54141 8192000000 10061033328 10551693312 1100648368 11.844 98 12 24 0 1176726768 73086128 2992272
13:23:22 42870 8192000000 10043435664 10486689792 1109719392 11.930 99 25 50 1 1142568976 27528800 5320784
13:25:16 101892 8192000000 9741599600 10170908672 793714624 8.833 71 18 36 0 843671584 46122368 3834592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-03-2021 13:29:07 to 05-03-2021 13:29:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 05-03-2021 13:29:23 to 05-03-2021 13:29:25.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237036
MON$OLDEST_ACTIVE               237284
MON$OLDEST_SNAPSHOT             237284
MON$NEXT_TRANSACTION            237284
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                       2103552
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             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Fri Mar  5 13:27:21 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Fri Mar  5 13:27:21 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

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