Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.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 16126852 kB
MemAvailable 30912672 kB
Buffers 0 kB
Cached 15578516 kB
SwapCached 336 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-09-03 22:37:48
DTS_END                         2021-09-03 22:37:48.8600
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/53388
TRN_ID                          244707
ATT_ID                          709
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: 9483.75 19876 1138050
customer order: creation 179.18 336 21501
customer order: refuse 16.09 346 1931
order to supplier: creation 46.50 1291 5580
order to supplier: removal 3.34 98084 401
invoice (draft): creation 47.44 3390 5693
invoice (draft): removal 52.84 840 6341
invoice accept: apply 43.38 16674 5206
invoice accept: cancel 48.37 744 5804
customer reserve: creation 4006.13 165 480736
customer reserve: removal 4739.93 53 568791
realization accept: apply 86.64 159 10397
realization accept: cancel 80.72 103 9687
payment from customer: creation 60.94 106 7313
payment from customer: removal 5.89 61 707
payment to supplier: creation 50.51 119 6061
payment to supplier: removal 3.51 74 421
service: total inventory turnovers 4.72 1230 567
service: total monetary turnovers 6.78 24 814
service: aggregate perf. data 0.79 27415 95
service: refresh index statistics 0.03 246302 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 22:40:03 to 03-09-2021 22:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5072
TEST_TIME 2 5289
TEST_TIME 3 6041
TEST_TIME 4 6478
TEST_TIME 5 5745
TEST_TIME 6 5462
TEST_TIME 7 5431
TEST_TIME 8 6048
TEST_TIME 9 5868
TEST_TIME 10 6258
TEST_TIME 11 6391
TEST_TIME 12 6707
TEST_TIME 13 6908
TEST_TIME 14 7321
TEST_TIME 15 7610
TEST_TIME 16 7624
TEST_TIME 17 7731
TEST_TIME 18 7872
TEST_TIME 19 8102
TEST_TIME 20 8253
TEST_TIME 21 8173
TEST_TIME 22 8913
TEST_TIME 23 8934
TEST_TIME 24 8824
TEST_TIME 25 9094
TEST_TIME 26 9368
TEST_TIME 27 10156
TEST_TIME 28 11199
TEST_TIME 29 11296
TEST_TIME 30 11292
TEST_TIME 31 11114
TEST_TIME 32 10972
TEST_TIME 33 10930
TEST_TIME 34 10859
TEST_TIME 35 10709
TEST_TIME 36 10624
TEST_TIME 37 10397
TEST_TIME 38 10529
TEST_TIME 39 10334
TEST_TIME 40 10468
TEST_TIME 41 10374
TEST_TIME 42 10235
TEST_TIME 43 10296
TEST_TIME 44 10136
TEST_TIME 45 10068
TEST_TIME 46 10065
TEST_TIME 47 10163
TEST_TIME 48 10096
TEST_TIME 49 10027
TEST_TIME 50 9879
TEST_TIME 51 9717
TEST_TIME 52 9747
TEST_TIME 53 9654
TEST_TIME 54 9633
TEST_TIME 55 9645
TEST_TIME 56 9528
TEST_TIME 57 9627
TEST_TIME 58 9577
TEST_TIME 59 9571
TEST_TIME 60 9595
TEST_TIME 61 9586
TEST_TIME 62 9747
TEST_TIME 63 9702
TEST_TIME 64 9779
TEST_TIME 65 9885
TEST_TIME 66 9856
TEST_TIME 67 9874
TEST_TIME 68 9896
TEST_TIME 69 9929
TEST_TIME 70 9940
TEST_TIME 71 9845
TEST_TIME 72 9814
TEST_TIME 73 9732
TEST_TIME 74 9716
TEST_TIME 75 9652
TEST_TIME 76 9611
TEST_TIME 77 9611
TEST_TIME 78 9613
TEST_TIME 79 9545
TEST_TIME 80 9467
TEST_TIME 81 9527
TEST_TIME 82 9552
TEST_TIME 83 9561
TEST_TIME 84 9587
TEST_TIME 85 9622
TEST_TIME 86 9676
TEST_TIME 87 9674
TEST_TIME 88 9645
TEST_TIME 89 9700
TEST_TIME 90 9729
TEST_TIME 91 9705
TEST_TIME 92 9818
TEST_TIME 93 9784
TEST_TIME 94 9767
TEST_TIME 95 9693
TEST_TIME 96 9633
TEST_TIME 97 9630
TEST_TIME 98 9585
TEST_TIME 99 9567
TEST_TIME 100 9549
TEST_TIME 101 9534
TEST_TIME 102 9564
TEST_TIME 103 9530
TEST_TIME 104 9573
TEST_TIME 105 9629
TEST_TIME 106 9595
TEST_TIME 107 9559
TEST_TIME 108 9628
TEST_TIME 109 9632
TEST_TIME 110 9624
TEST_TIME 111 9685
TEST_TIME 112 9671
TEST_TIME 113 9592
TEST_TIME 114 9575
TEST_TIME 115 9555
TEST_TIME 116 9553
TEST_TIME 117 9529
TEST_TIME 118 9501
TEST_TIME 119 9486
TEST_TIME 120 9496

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-09-2021 22:40:07 to 03-09-2021 22:40:10.

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 1583255 1583255 0 0.00 0 17390 6 0 0
doc_list_biud 1583246 1583246 0 0.00 0 32819 25 0 0
sp_add_invoice_to_stock 5362 5206 156 2.90 29 159944 16675 0 156
sp_cancel_adding_invoice 5804 5804 0 0.00 6 49848 744 0 0
sp_cancel_client_order 1931 1931 0 0.00 4 12683 346 0 0
sp_cancel_customer_reserve 568791 568791 0 0.00 1 27544 54 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 2436 62 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2855 74 0 0
sp_cancel_supplier_invoice 6350 6341 9 0.14 2 32821 841 0 9
sp_cancel_supplier_order 401 401 0 0.00 11 1134100 98084 0 0
sp_cancel_write_off 9687 9687 0 0.00 1 14172 103 0 0
sp_client_order 21501 21501 0 0.00 4 30305 337 0 0
sp_customer_reserve 480736 480736 0 0.00 0 112247 165 0 0
sp_fill_shopping_cart 32768 32768 0 0.00 1 13061 42 0 0
sp_fill_shopping_cart_clo_res 480724 480724 0 0.00 0 22710 14 0 0
sp_get_clo_for_invoice 5192 5192 0 0.00 0 23389 289 0 0
sp_kill_cost_storno 16619 16619 0 0.00 0 17379 50 0 0
sp_kill_qstorno_ret_qs2qd 1154011 1154011 0 0.00 0 32819 30 0 0
sp_kill_qty_storno 608543 608543 0 0.00 0 32819 65 0 0
sp_lock_selected_doc 21602 21602 0 0.00 0 7686 5 0 0
sp_make_cost_storno 28963 28963 0 0.00 0 9693 47 0 0
sp_multiply_rows_for_pdistr 28961 28961 0 0.00 0 6700 23 0 0
sp_multiply_rows_for_qdistr 21500 21500 0 0.00 0 24104 157 0 0
sp_pay_from_customer 7313 7313 0 0.00 4 35852 106 0 0
sp_pay_to_supplier 6061 6061 0 0.00 5 13116 120 0 0
sp_payment_common 13372 13372 0 0.00 2 35842 27 0 0
sp_qd_handle_on_cancel_clo 1931 1931 0 0.00 0 12678 313 0 0
sp_qd_handle_on_invoice_upd_sts 10995 10995 0 0.00 0 12950 316 0 0
sp_qd_handle_on_reserve_upd_sts 10397 10397 0 0.00 0 11526 51 0 0
sp_reserve_write_off 10397 10397 0 0.00 6 12079 159 0 0
sp_supplier_invoice 5693 5693 0 0.00 6 100984 3390 0 0
sp_supplier_order 5580 5580 0 0.00 5 53441 1292 0 0
srv_aggregate_perf_data 95 95 0 0.00 3180 338416 27415 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1225 157710 49530 0 0
srv_find_qd_qs_mism 1088651 1088651 0 0.00 0 12325 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 27 64304 1231 0 0
srv_make_money_saldo 814 814 0 0.00 1 926 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 62254 346111 246303 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5207 5207 0 0.00 0 10819 164 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 3948 105 0 0
t$perf-norm:sp_cancel_client_order 1931 1931 0 0.00 0 8042 15 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1528 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 2855 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3309 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 4360 37 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 18369 1467 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 1015 7 0 0
t$perf-norm:sp_client_order 21501 21501 0 0.00 0 15562 16 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 4227 22 0 0
t$perf-norm:sp_pay_from_customer 7313 7313 0 0.00 0 7566 10 0 0
t$perf-norm:sp_pay_to_supplier 6061 6061 0 0.00 0 14480 16 0 0
t$perf-norm:sp_reserve_write_off 10397 10397 0 0.00 0 9441 13 0 0
t$perf-norm:sp_supplier_invoice 5693 5693 0 0.00 0 11747 20 0 0
t$perf-norm:sp_supplier_order 5580 5580 0 0.00 0 6677 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 100 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 221 10 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 3067 32 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 2364 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10692 10380 312 2.91 0 7200 19 0 312
v_all_customers 38696 38696 0 0.00 0 24 1 0 0
v_all_suppliers 30990 30990 0 0.00 0 23 1 0 0
v_all_wares 43000 43000 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 5927 16 0 0
v_cancel_client_order 3862 3862 0 0.00 0 6330 8 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 999 6 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 2485 8 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2569 6 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 1570 15 0 18
v_cancel_supplier_order 802 802 0 0.00 0 941 6 0 0
v_cancel_write_off 2014 2014 0 0.00 0 6755 25 0 0
v_max_id_clo_ord 5579 5579 0 0.00 0 1676 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 1731 12 0 0
v_max_id_ord_sup 5689 5689 0 0.00 0 5804 5 0 0
v_max_non_paid_invoice 1833 1833 0 0.00 0 827 2 0 0
v_max_non_paid_realizn 4047 4047 0 0.00 0 3136 4 0 0
v_min_id_clo_ord 5579 5579 0 0.00 0 12785 9 0 0
v_min_id_clo_res 988 988 0 0.00 0 10505 21 0 0
v_min_id_ord_sup 5689 5689 0 0.00 0 7592 7 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 13068 16 0 0
v_min_non_paid_realizn 7312 7312 0 0.00 0 35838 21 0 0
v_our_firm 10834 10834 0 0.00 0 23 1 0 0
v_reserve_write_off 20792 20792 0 0.00 0 6455 7 0 0
x_lock_depdocs_on_canc_invoice 11730 11730 0 0.00 0 11985 106 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 26156 413 0 0
x_make_qstorno_1000_1200 5580 5580 0 0.00 2 53434 1203 0 0
x_make_qstorno_1200_2000 5693 5693 0 0.00 3 100954 3243 0 0
x_make_qstorno_2100_3300 480735 480735 0 0.00 0 108854 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-09-2021 22:40:11 to 03-09-2021 22:40:15.

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:52 13 8192000000 8810849008 8824852480 12239152 0.149 4 1 2 0 12361216 59504 62560
20:08:53 43534 8192000000 10404396576 10726707200 1455661600 15.088 100 91 183 0 1492508656 6695296 30151760
20:10:42 25038 8192000000 10452855168 10784235520 1506175408 15.530 100 92 185 0 1544601792 8494960 29931424
20:12:15 19638 8192000000 10509503568 10859450368 1563163360 16.023 100 84 169 0 1598857248 9934336 25759552
20:13:36 17233 8192000000 10600551312 10959024128 1652555024 16.786 100 82 164 0 1688827376 11764768 24507584
20:14:57 7474 8192000000 10654548000 11024822272 1711425232 17.281 100 78 156 1 1747317824 13454896 22437696
20:16:07 5090 8192000000 10701374320 11087089664 1772698288 17.789 100 68 136 1 1805180544 13167904 19314352
20:17:14 2409 8192000000 10768377680 11150426112 1827150352 18.236 100 84 168 0 1862567360 11759472 23657536
20:18:19 2627 8192000000 10795491280 11190378496 1865333776 18.547 100 80 160 4 1901946576 13839232 22773568
20:19:22 12765 8192000000 10816414880 11216908288 1883069376 18.690 100 68 135 1 1913339344 11514944 18755024
20:20:36 3879 8192000000 10806923696 11226861568 1892546576 18.766 100 43 87 1 1913486208 9156160 11783472
20:21:42 56533 8192000000 10812170272 11237437440 1895904256 18.793 100 33 65 0 1911421424 5251808 10265360
20:23:40 5208 8192000000 10830257904 11251507200 1904227584 18.860 100 38 76 3 1923643216 9178112 10237520
20:24:46 9638 8192000000 10820605072 11253334016 1907547184 18.887 100 19 38 1 1918723584 5726464 5449936
20:25:59 14110 8192000000 10828797280 11247243264 1877621984 18.646 98 28 56 0 1891072544 5153568 8296992
20:27:20 7313 8192000000 10797559008 11230203904 1874795104 18.623 99 18 35 1 1889353008 10149200 4408704
20:28:27 8795 8192000000 10789628624 11222163456 1835866736 18.307 97 20 40 2 1847391584 5917104 5607744
20:29:38 8746 8192000000 10719054448 11138809856 1738612288 17.507 95 11 22 0 1746746816 5172896 2961632
20:30:47 49016 8192000000 10729821104 11120766976 1754783440 17.641 97 62 125 2 1779456608 4818256 19854912
20:32:36 7350 8192000000 10724410256 11130601472 1772968288 17.792 98 38 75 1 1790312080 6585024 10758768
20:33:44 8136 8192000000 10741674832 11162009600 1823974032 18.210 100 17 34 1 1833848752 5374640 4500080
20:34:55 11805 8192000000 10764125152 11190669312 1850072240 18.423 100 21 42 2 1861520640 4996352 6452048
20:36:10 37709 8192000000 10767901872 11202621440 1862438368 18.523 100 26 52 1 1875691136 5289536 7963232
20:37:50 4275 8192000000 10835288224 11252101120 1902732016 18.848 100 36 74 2 1922484752 9961088 9791648
20:39:02 11366 8192000000 10869277376 11292614656 1932392240 19.086 100 47 93 0 1955979440 10345632 13241568
20:40:15 10541 8192000000 10877966192 11305152512 1954628608 19.263 100 59 117 0 1981662800 10197424 16836768
20:41:29 91421 8192000000 10878898896 11326357504 1944867104 19.186 99 31 62 1 1965374896 11096416 9411376
20:44:09 11717 8192000000 10918957264 11351121920 1982632304 19.486 100 48 95 2 2011575152 15157408 13785440
20:45:35 18926 8192000000 10923598240 11366817792 1987388224 19.523 100 33 66 1 2012176096 15094432 9693440
20:46:57 16428 8192000000 10958689904 11391893504 2007759152 19.684 100 63 125 0 2048909392 23804656 17345584
20:48:16 3113 8192000000 10976435424 11401191424 1999668800 19.620 99 72 144 1 2050007296 28728816 21609680
20:49:22 6119 8192000000 10973926512 11404439552 2011539232 19.714 100 60 120 2 2060496064 30571664 18385168
20:50:31 4197 8192000000 10982937344 11396857856 2002497312 19.642 100 79 157 1 2060272128 33595648 24179168
20:51:40 9526 8192000000 10933680944 11364077568 1971093376 19.394 100 64 128 0 2022821920 32764496 18964048
20:52:53 8225 8192000000 10926236576 11345936384 1957639424 19.287 100 66 136 6 2011122384 34531632 18951328
20:54:09 1223 8192000000 10967966848 11391614976 1987754576 19.526 100 76 152 2 2047476192 36558592 23163024
20:55:16 4009 8192000000 10949616160 11371671552 1970846112 19.392 100 64 128 3 2030685520 40785792 19053616
20:56:23 3132 8192000000 10953566640 11361202176 1963285872 19.332 100 69 137 1 2027455472 42468240 21701360
20:57:34 11702 8192000000 10914958320 11349069824 1944172832 19.180 100 50 99 2 2005669120 45804144 15692144
20:58:51 2091 8192000000 10943176272 11359531008 1953103296 19.251 100 73 147 0 2026696528 50854944 22738288
20:59:56 4031 8192000000 10901908720 11330965504 1928542992 19.055 100 43 87 0 1981159856 39646864 12970000
21:01:09 9926 8192000000 10927781040 11353079808 1928800464 19.057 99 53 106 3 1992021840 46046368 17175008
21:02:30 34356 8192000000 10935064848 11390005248 1937517504 19.127 99 32 64 2 2010975648 62383296 11074848
21:04:06 20550 8192000000 10929449328 11391668224 1945851504 19.193 100 20 40 1 2022809248 69260224 7697520
21:05:28 33641 8192000000 10873123296 11352408064 1917073760 18.963 99 13 26 1 1964365568 42151504 5140304
21:07:08 30616 8192000000 10878745344 11336077312 1948458480 19.214 100 14 28 0 1963334336 10950768 3925088
21:08:40 80759 8192000000 10887069728 11330543616 1909357184 18.901 97 14 27 1 1923232352 10697200 3177968
21:11:02 81614 8192000000 10923053760 11370172416 1966209712 19.355 100 26 52 0 1988240272 14433488 7597072
21:13:30 97242 8192000000 10910541776 11367989248 1941657584 19.160 98 19 38 1 1968265456 20465072 6142800
21:16:38 115857 8192000000 10910393408 11382624256 1954319424 19.261 99 18 36 1 1976326496 17460800 4546272
21:19:35 107992 8192000000 10927966448 11399380992 1999898464 19.622 100 15 30 0 2022404928 18284848 4221616
21:22:38 48176 8192000000 10926552400 11381669888 1960030816 19.306 98 23 47 3 1980796624 13713520 7052288
21:24:27 127457 8192000000 10860364160 11320614912 1877985680 18.649 94 19 36 0 1895064224 11957424 5121120
21:27:40 65635 8192000000 10892175008 11360100352 1938565568 19.135 98 13 26 1 1952113632 9954192 3593872
21:29:55 48124 8192000000 10876489168 11334766592 1916050672 18.955 97 12 24 0 1925283808 6558192 2674944
21:31:54 44556 8192000000 10895287296 11372253184 1967381232 19.365 100 13 26 2 1983555920 12430784 3743904
21:33:39 94696 8192000000 10881517632 11365380096 1902369152 18.845 96 11 22 0 1922011728 16250848 3391728
21:36:15 17708 8192000000 10903978016 11363254272 1949395120 19.222 99 51 102 0 1972690896 8537264 14758512
21:37:35 113658 8192000000 10871574128 11339337728 1794752704 17.971 91 10 20 1 1812218272 14464864 3000704
21:40:32 15617 8192000000 10879941040 11329880064 1904207104 18.860 97 40 80 1 1931603776 16706288 10690384
21:42:10 107227 8192000000 10864772368 11325603840 1828679312 18.249 94 11 22 1 1845346848 13684368 2983168
21:45:14 22993 8192000000 10887308096 11335581696 1915702512 18.952 98 46 92 0 1942727264 13818896 13205856
21:46:38 91482 8192000000 10884693776 11344867328 1881840848 18.680 96 23 46 1 1899878352 11531040 6506464
21:49:16 103452 8192000000 10880170768 11353231360 1942772656 19.169 99 19 38 1 1957298704 9397008 5129040
21:52:00 40072 8192000000 10880063616 11360075776 1963653328 19.335 100 16 32 2 1979346624 11216384 4476912
21:53:41 72462 8192000000 10889989040 11367956480 1932742176 19.089 98 15 30 1 1951443888 14432368 4269344
21:55:54 41940 8192000000 10876546272 11353923584 1953574048 19.255 100 4 8 0 1962454288 7954496 925744
21:57:37 67013 8192000000 10903080240 11386212352 1973508928 19.413 100 11 22 0 1992491872 15582416 3400528
21:59:46 36447 8192000000 10904966032 11373510656 1955263632 19.268 99 25 50 2 1973099184 10215904 7619648
22:01:23 63669 8192000000 10903138528 11381968896 1972374864 19.404 100 16 32 2 1989940400 12857904 4707632
22:03:34 69637 8192000000 10918363984 11393773568 1959094560 19.299 99 20 40 1 1977856448 13535456 5226432
22:05:44 45233 8192000000 10946506304 11412029440 2001170976 19.632 100 32 64 1 2029832736 20114416 8547344
22:07:30 44015 8192000000 10926760768 11391680512 1941628048 19.160 97 26 51 4 1961530816 13187184 6715584
22:09:14 157709 8192000000 10909088864 11384020992 1955060784 19.267 98 16 32 0 1983925696 23683360 5181552
22:13:19 78272 8192000000 10887214080 11375026176 1939752512 19.145 98 9 18 1 1951664944 9529248 2383184
22:16:29 58965 8192000000 10921239248 11396452352 1979450848 19.460 99 20 40 1 1992151296 7225024 5475424
22:18:28 58812 8192000000 10898082160 11380084736 1922357168 19.006 96 15 30 0 1938994208 11747056 4889984
22:20:28 76000 8192000000 10905229184 11383742464 1938744816 19.137 98 16 32 1 1957429952 13756576 4928560
22:22:48 59023 8192000000 10888340016 11354320896 1923031056 19.011 98 9 18 0 1935654016 10096032 2526928
22:24:47 56608 8192000000 10883098512 11366948864 1933776864 19.097 98 21 42 0 1952308160 12619504 5911792
22:26:45 76712 8192000000 10867047168 11323580416 1819847344 18.176 93 17 34 0 1833292144 9308176 4136624
22:29:04 129404 8192000000 10865889456 11342516224 1920911920 18.994 99 6 12 0 1945574432 22992704 1669808
22:32:17 38476 8192000000 10851729360 11310784512 1889929936 18.745 98 41 82 0 1912985344 11444016 11611392
22:33:57 29044 8192000000 10860724912 11315539968 1909869344 18.906 99 26 52 1 1924610192 6918208 7822640
22:35:29 55582 8192000000 10850413568 11329445888 1922290608 19.005 99 15 28 1 1934749872 8579088 3880176
22:37:25 29839 8192000000 9905945328 10277511168 1036755712 11.233 54 2 4 0 1043011584 6131248 124624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-09-2021 22:40:33 to 03-09-2021 22:40:35.

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          243752
MON$OLDEST_ACTIVE               245234
MON$OLDEST_SNAPSHOT             245234
MON$NEXT_TRANSACTION            245234
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                       2036000
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
--- end of diff output ---

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

03.09.21 22:40:35. 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-09-03 22:40:38 No severe PSQL-related problems occured
03.09.21 22:40:39. End of report.