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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15954940 kB
MemAvailable 30715228 kB
Buffers 0 kB
Cached 15763968 kB
SwapCached 3860 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-10-10 16:37:55
DTS_END                         2021-10-10 16:37:55.2600
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(57:17)
IP                              ::1/33258
TRN_ID                          235262
ATT_ID                          647
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: 9382.23 23562 1125868
customer order: creation 166.34 360 19961
customer order: refuse 14.27 373 1712
order to supplier: creation 45.66 1295 5479
order to supplier: removal 3.51 107317 421
invoice (draft): creation 44.96 3214 5395
invoice (draft): removal 53.54 848 6425
invoice accept: apply 41.63 17578 4996
invoice accept: cancel 47.93 738 5751
customer reserve: creation 3913.41 171 469609
customer reserve: removal 4756.29 62 570755
realization accept: apply 81.96 181 9835
realization accept: cancel 86.10 105 10332
payment from customer: creation 58.78 107 7054
payment from customer: removal 5.58 75 669
payment to supplier: creation 47.53 132 5704
payment to supplier: removal 3.38 58 405
service: total inventory turnovers 4.22 1113 506
service: total monetary turnovers 6.35 22 762
service: aggregate perf. data 0.78 23203 93
service: refresh index statistics 0.03 314282 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2021 16:39:20 to 10-10-2021 16:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1597
TEST_TIME 2 3237
TEST_TIME 3 4845
TEST_TIME 4 4808
TEST_TIME 5 5062
TEST_TIME 6 4894
TEST_TIME 7 4969
TEST_TIME 8 4819
TEST_TIME 9 5338
TEST_TIME 10 5477
TEST_TIME 11 5325
TEST_TIME 12 5610
TEST_TIME 13 5512
TEST_TIME 14 5951
TEST_TIME 15 6769
TEST_TIME 16 7102
TEST_TIME 17 7274
TEST_TIME 18 7197
TEST_TIME 19 7594
TEST_TIME 20 7853
TEST_TIME 21 8202
TEST_TIME 22 8181
TEST_TIME 23 8749
TEST_TIME 24 9425
TEST_TIME 25 9323
TEST_TIME 26 9805
TEST_TIME 27 10162
TEST_TIME 28 10444
TEST_TIME 29 10392
TEST_TIME 30 10798
TEST_TIME 31 11182
TEST_TIME 32 10918
TEST_TIME 33 10683
TEST_TIME 34 10665
TEST_TIME 35 10657
TEST_TIME 36 10451
TEST_TIME 37 10374
TEST_TIME 38 10238
TEST_TIME 39 10156
TEST_TIME 40 10168
TEST_TIME 41 10209
TEST_TIME 42 10055
TEST_TIME 43 9919
TEST_TIME 44 9986
TEST_TIME 45 9920
TEST_TIME 46 9832
TEST_TIME 47 9757
TEST_TIME 48 9753
TEST_TIME 49 9634
TEST_TIME 50 9598
TEST_TIME 51 9448
TEST_TIME 52 9470
TEST_TIME 53 9553
TEST_TIME 54 9629
TEST_TIME 55 9641
TEST_TIME 56 9652
TEST_TIME 57 9828
TEST_TIME 58 9822
TEST_TIME 59 9785
TEST_TIME 60 9755
TEST_TIME 61 9900
TEST_TIME 62 9876
TEST_TIME 63 9881
TEST_TIME 64 9916
TEST_TIME 65 9881
TEST_TIME 66 9754
TEST_TIME 67 9689
TEST_TIME 68 9754
TEST_TIME 69 9732
TEST_TIME 70 9643
TEST_TIME 71 9536
TEST_TIME 72 9550
TEST_TIME 73 9504
TEST_TIME 74 9430
TEST_TIME 75 9479
TEST_TIME 76 9448
TEST_TIME 77 9418
TEST_TIME 78 9390
TEST_TIME 79 9543
TEST_TIME 80 9511
TEST_TIME 81 9539
TEST_TIME 82 9511
TEST_TIME 83 9544
TEST_TIME 84 9570
TEST_TIME 85 9572
TEST_TIME 86 9593
TEST_TIME 87 9752
TEST_TIME 88 9735
TEST_TIME 89 9655
TEST_TIME 90 9619
TEST_TIME 91 9556
TEST_TIME 92 9549
TEST_TIME 93 9504
TEST_TIME 94 9534
TEST_TIME 95 9541
TEST_TIME 96 9537
TEST_TIME 97 9512
TEST_TIME 98 9499
TEST_TIME 99 9535
TEST_TIME 100 9519
TEST_TIME 101 9554
TEST_TIME 102 9592
TEST_TIME 103 9553
TEST_TIME 104 9586
TEST_TIME 105 9600
TEST_TIME 106 9596
TEST_TIME 107 9591
TEST_TIME 108 9654
TEST_TIME 109 9643
TEST_TIME 110 9624
TEST_TIME 111 9555
TEST_TIME 112 9542
TEST_TIME 113 9502
TEST_TIME 114 9504
TEST_TIME 115 9469
TEST_TIME 116 9434
TEST_TIME 117 9403
TEST_TIME 118 9427
TEST_TIME 119 9409
TEST_TIME 120 9382

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2021 16:39:24 to 10-10-2021 16:39:27.

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 1560521 1560521 0 0.00 0 24451 6 0 0
doc_list_biud 1560521 1560521 0 0.00 0 58323 29 0 0
sp_add_invoice_to_stock 5133 4996 137 2.66 28 219225 17578 0 137
sp_cancel_adding_invoice 5751 5751 0 0.00 5 78775 738 0 0
sp_cancel_client_order 1712 1712 0 0.00 4 12001 373 0 0
sp_cancel_customer_reserve 570755 570755 0 0.00 1 58324 63 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 4437 76 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2674 58 0 0
sp_cancel_supplier_invoice 6439 6425 14 0.21 1 39370 849 0 14
sp_cancel_supplier_order 421 421 0 0.00 7 1121433 107317 0 0
sp_cancel_write_off 10332 10332 0 0.00 1 8011 106 0 0
sp_client_order 19961 19961 0 0.00 3 20804 360 0 0
sp_customer_reserve 469609 469609 0 0.00 0 121259 171 0 0
sp_fill_shopping_cart 30828 30828 0 0.00 1 15605 34 0 0
sp_fill_shopping_cart_clo_res 469609 469609 0 0.00 0 16064 16 0 0
sp_get_clo_for_invoice 4996 4996 0 0.00 0 24479 309 0 0
sp_kill_cost_storno 17157 17157 0 0.00 0 24447 56 0 0
sp_kill_qstorno_ret_qs2qd 1158688 1158688 0 0.00 0 58321 34 0 0
sp_kill_qty_storno 610227 610227 0 0.00 0 58323 73 0 0
sp_lock_selected_doc 20497 20497 0 0.00 0 6480 5 0 0
sp_make_cost_storno 27589 27589 0 0.00 0 18755 55 0 0
sp_multiply_rows_for_pdistr 27589 27589 0 0.00 0 6109 27 0 0
sp_multiply_rows_for_qdistr 19961 19961 0 0.00 0 12670 172 0 0
sp_pay_from_customer 7054 7054 0 0.00 4 18536 108 0 0
sp_pay_to_supplier 5704 5704 0 0.00 5 18763 133 0 0
sp_payment_common 12758 12758 0 0.00 1 18526 29 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 11979 354 0 0
sp_qd_handle_on_invoice_upd_sts 10747 10747 0 0.00 0 12801 304 0 0
sp_qd_handle_on_reserve_upd_sts 9835 9835 0 0.00 0 20653 61 0 0
sp_reserve_write_off 9835 9835 0 0.00 6 20738 181 0 0
sp_supplier_invoice 5395 5395 0 0.00 7 74519 3214 0 0
sp_supplier_order 5479 5479 0 0.00 5 29751 1296 0 0
srv_aggregate_perf_data 93 93 0 0.00 3029 359622 23203 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1836 161758 45160 0 0
srv_find_qd_qs_mism 1078461 1078461 0 0.00 0 10693 5 0 0
srv_make_invnt_saldo 506 506 0 0.00 49 80295 1113 0 0
srv_make_money_saldo 762 762 0 0.00 0 1133 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 157667 489125 314282 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 8752 163 0 0
t$perf-norm:sp_cancel_adding_invoice 341 341 0 0.00 0 5855 128 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 18483 24 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 875 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 4711 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 3496 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1852 16 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 23709 1752 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 4150 9 0 0
t$perf-norm:sp_client_order 19961 19961 0 0.00 0 7130 19 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 693 5 0 0
t$perf-norm:sp_pay_from_customer 7054 7054 0 0.00 0 4562 10 0 0
t$perf-norm:sp_pay_to_supplier 5704 5704 0 0.00 0 5588 11 0 0
t$perf-norm:sp_reserve_write_off 9835 9835 0 0.00 0 8605 11 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 3214 15 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 3953 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 272 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 159 7 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 863 14 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 4123 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10266 9992 274 2.66 0 6612 21 0 274
v_all_customers 36650 36650 0 0.00 0 24 0 0 0
v_all_suppliers 29888 29888 0 0.00 0 18 1 0 0
v_all_wares 39922 39922 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 682 682 0 0.00 0 1169 7 0 0
v_cancel_client_order 3424 3424 0 0.00 0 1555 3 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 4427 11 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2430 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 706 6 0 0
v_cancel_supplier_invoice 876 848 28 3.19 0 2419 17 0 28
v_cancel_supplier_order 842 842 0 0.00 0 3567 8 0 0
v_cancel_write_off 2002 2002 0 0.00 0 3652 16 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 250 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 4872 22 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 4137 3 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 5528 10 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 282 2 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 1570 4 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 5537 11 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 6011 10 0 0
v_min_non_paid_invoice 5704 5704 0 0.00 0 9353 18 0 0
v_min_non_paid_realizn 7054 7054 0 0.00 0 18518 23 0 0
v_our_firm 9944 9944 0 0.00 0 27 1 0 0
v_reserve_write_off 19670 19670 0 0.00 0 7284 7 0 0
x_lock_depdocs_on_canc_invoice 11752 11752 0 0.00 0 15046 111 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 13395 383 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 2 29743 1224 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 2 74499 3095 0 0
x_make_qstorno_2100_3300 469609 469609 0 0.00 0 121256 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2021 16:39:28 to 10-10-2021 16:39:32.

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
14:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:00 34573 8192000000 9907484784 10221187072 980387408 10.688 100 92 184 0 1011384880 6175824 24821648
14:10:39 26488 8192000000 9957828304 10281607168 1031618208 11.184 100 86 172 0 1061582992 7818816 22145968
14:12:10 14607 8192000000 9980888784 10317086720 1067227728 11.526 100 77 154 0 1095364912 9313728 18823456
14:13:31 17061 8192000000 10046858592 10399334400 1134973648 12.168 100 79 158 0 1163982528 10338736 18670144
14:14:51 9073 8192000000 10096438976 10462228480 1187144336 12.657 100 72 142 0 1215047824 11719136 16184352
14:16:04 5843 8192000000 10138522736 10512793600 1229183792 13.047 100 78 157 0 1260595696 14511760 16900144
14:17:13 3933 8192000000 10167604560 10547458048 1255156496 13.286 100 88 177 1 1290012752 14510928 20345328
14:18:19 2647 8192000000 10200346368 10581647360 1279594240 13.509 100 91 182 0 1313597312 12896128 21106944
14:19:24 2071 8192000000 10215226768 10598707200 1295046896 13.650 100 93 183 0 1327718448 12629600 20041952
14:20:31 1048 8192000000 10213851152 10602459136 1303486064 13.727 100 88 175 2 1337645520 14029264 20130192
14:21:34 5507 8192000000 10204030272 10616135680 1314158480 13.824 100 40 80 0 1331877200 9262576 8456144
14:22:41 6189 8192000000 10208990528 10619666432 1317754608 13.856 100 34 69 0 1332165424 7120416 7290400
14:23:47 15857 8192000000 10205399376 10628780032 1323790256 13.911 100 19 38 0 1334912912 6723376 4399280
14:25:03 61483 8192000000 10237931376 10645716992 1331285808 13.979 100 51 103 1 1349433984 4956896 13191280
14:27:05 3926 8192000000 10229734656 10646450176 1332657376 13.991 100 43 86 2 1350176672 7911616 9607680
14:28:10 53268 8192000000 10210348592 10627977216 1292968832 13.631 97 31 61 0 1306181872 5657392 7555648
14:30:06 6497 8192000000 10176605584 10587443200 1283966048 13.549 100 26 51 0 1296685648 6757248 5962352
14:31:13 9670 8192000000 10162245920 10568630272 1241152768 13.157 97 17 34 1 1254498160 9460304 3885088
14:32:23 16812 8192000000 10145933200 10546286592 1235683744 13.106 98 22 44 0 1246744320 4780848 6279728
14:33:45 6687 8192000000 10130901472 10532454400 1213155728 12.898 97 20 40 1 1223451488 5818592 4477168
14:34:55 7847 8192000000 10162727712 10571149312 1271350016 13.434 100 23 45 2 1281972432 4833488 5788928
14:36:07 8787 8192000000 10171357392 10582937600 1282884080 13.539 100 18 36 0 1292315904 4955904 4475920
14:37:19 17444 8192000000 10203136304 10617548800 1311344976 13.798 100 23 45 1 1322737120 5502896 5889248
14:38:39 5417 8192000000 10227546352 10635649024 1323669872 13.910 100 44 88 1 1340440032 6762480 10007680
14:39:58 7505 8192000000 10247177056 10662580224 1344365968 14.097 100 48 95 0 1365075856 9517504 11192384
14:41:09 10272 8192000000 10269258112 10685739008 1357560640 14.215 100 44 88 2 1377033152 9259504 10213008
14:42:34 18840 8192000000 10274071680 10697617408 1369907808 14.326 100 28 56 3 1387227008 10902992 6416208
14:43:55 7047 8192000000 10306834672 10727497728 1392577104 14.529 100 44 88 0 1420671280 17338352 10755824
14:45:08 15145 8192000000 10325717360 10750636032 1403241760 14.624 100 37 74 5 1432563200 19785088 9536352
14:46:39 8262 8192000000 10351910976 10772738048 1420986304 14.781 100 56 110 0 1461276048 26496752 13792992
14:48:00 15005 8192000000 10376422048 10795618304 1432201968 14.881 100 67 134 0 1480349200 31692992 16454240
14:49:29 2827 8192000000 10386139296 10807963648 1437879584 14.931 100 65 129 0 1496192624 42001440 16311600
14:50:39 4038 8192000000 10386199056 10812080128 1430346528 14.864 99 57 114 1 1488739552 42856336 15536688
14:51:48 5180 8192000000 10399474144 10819862528 1435576816 14.911 100 66 131 2 1508391296 55460432 17354048
14:53:03 1832 8192000000 10361304320 10775457792 1398522464 14.582 100 78 156 0 1463012384 45024576 19465344
14:54:11 3255 8192000000 10358563616 10783707136 1403256528 14.624 100 58 115 1 1463645664 45905408 14483728
14:55:23 12024 8192000000 10336778480 10759344128 1371963056 14.345 99 42 84 2 1429548464 46127264 11458144
14:56:43 10292 8192000000 10366941728 10794422272 1410831392 14.691 100 48 96 1 1474054944 50344560 12878992
14:57:56 5832 8192000000 10341513472 10766598144 1378796496 14.406 99 49 98 0 1443211152 51396800 13017856
14:59:06 14828 8192000000 10336912032 10765586432 1376534384 14.386 99 32 64 2 1435515616 49983856 8997376
15:00:44 8341 8192000000 10316610400 10738024448 1363470032 14.269 100 47 94 0 1424266608 46784176 14012400
15:01:56 30941 8192000000 10316329136 10757652480 1383004992 14.443 100 21 42 0 1421192992 32193216 5994784
15:03:32 50408 8192000000 10313943872 10766467072 1360704400 14.244 98 20 40 1 1418357904 51898640 5754864
15:06:14 38304 8192000000 10289120240 10724077568 1334668688 14.009 97 28 58 1 1370255280 27871216 7715376
15:08:00 42277 8192000000 10264962384 10717028352 1342679408 14.082 99 8 16 0 1370813312 25540560 2593344
15:09:53 96601 8192000000 10287746976 10722168832 1343452160 14.089 98 33 66 0 1365690304 14816080 7422064
15:12:31 161757 8192000000 10282785984 10727727104 1311708880 13.802 95 25 49 0 1327543152 10089888 5744384
15:16:17 42172 8192000000 10274932400 10712944640 1314783840 13.829 96 45 88 3 1338676832 13461808 10431184
15:18:02 40741 8192000000 10276378096 10732007424 1367694592 14.306 100 11 22 1 1383038640 12580752 2763296
15:19:49 58834 8192000000 10280894560 10734731264 1383670464 14.449 100 11 22 0 1396790448 9975008 3144976
15:22:27 51387 8192000000 10267179120 10722013184 1367052576 14.301 99 13 26 0 1376688000 6590368 3045056
15:24:41 63641 8192000000 10272859440 10730311680 1372712976 14.351 99 19 38 0 1387795376 10846240 4236160
15:26:47 35032 8192000000 10289241824 10750259200 1391813904 14.522 100 36 69 2 1411882528 12005040 8063584
15:28:26 79850 8192000000 10313276672 10782347264 1400645088 14.601 100 16 32 0 1420979680 16377840 3956752
15:30:46 60765 8192000000 10305231104 10770501632 1386284912 14.473 99 11 22 1 1405145408 16062576 2797920
15:33:12 12438 8192000000 10323761904 10779230208 1407430352 14.661 100 42 84 2 1431469776 14641744 9397680
15:34:31 56492 8192000000 10310692624 10778300416 1392771728 14.531 99 23 46 1 1419389856 21235904 5382224
15:36:34 30811 8192000000 10310067440 10766249984 1388399248 14.492 99 34 68 2 1407539760 11443392 7697120
15:38:20 78082 8192000000 10287952832 10739929088 1302501904 13.718 93 20 40 1 1321476544 14647072 4327568
15:40:38 90409 8192000000 10292566560 10729955328 1324254528 13.915 95 20 38 0 1340627504 11697840 4675136
15:43:09 110882 8192000000 10284056208 10732220416 1351741408 14.163 97 25 49 1 1368818992 11256848 5820736
15:46:01 57750 8192000000 10273358016 10736726016 1356496960 14.206 98 20 40 1 1370963360 9500624 4965776
15:48:00 56615 8192000000 10274687568 10737135616 1346608672 14.117 97 18 36 1 1362869568 12351088 3909808
15:49:57 42914 8192000000 10265196480 10720870400 1345991744 14.111 98 12 24 0 1357352384 8993072 2367568
15:51:45 52350 8192000000 10252847632 10715197440 1336007904 14.021 98 10 20 1 1350552784 12335888 2208992
15:54:15 33333 8192000000 10250650240 10694647808 1305713120 13.747 96 23 46 0 1320699904 9952896 5033888
15:55:53 68292 8192000000 10254979264 10728452096 1356361952 14.205 100 22 44 0 1381332112 19678736 5291424
15:58:03 69528 8192000000 10252466208 10704289792 1329946368 13.967 97 20 40 0 1345243376 10752880 4544128
16:00:14 50671 8192000000 10287723888 10741776384 1369851120 14.326 100 33 66 1 1389504080 12634240 7018720
16:02:06 21383 8192000000 10285317056 10729033728 1371131728 14.337 99 33 66 3 1389933008 11210992 7590288
16:03:29 54289 8192000000 10264977456 10734206976 1337836432 14.038 98 8 16 2 1359137296 19288096 2012768
16:05:26 30801 8192000000 10289856896 10723287040 1363070336 14.265 100 39 78 2 1381188928 8849312 9269280
16:06:58 76588 8192000000 10266477712 10721566720 1354601440 14.189 99 32 64 0 1369669888 7676544 7391904
16:09:20 83867 8192000000 10289976112 10760286208 1386228848 14.472 100 13 26 0 1407510448 18308640 2972960
16:11:49 55045 8192000000 10293956496 10751602688 1389226912 14.499 100 8 16 1 1409324864 18455136 1642816
16:13:48 56901 8192000000 10294184736 10751442944 1368771168 14.316 98 26 51 0 1381104080 7179776 5153136
16:15:46 48230 8192000000 10297740544 10758725632 1377503872 14.394 99 19 38 1 1394925600 12092496 5329232
16:17:38 52746 8192000000 10313264544 10773458944 1405087456 14.640 100 36 72 0 1423180048 10043424 8049168
16:19:34 58300 8192000000 10314488064 10771124224 1402069616 14.613 100 12 24 1 1414834688 10474560 2290512
16:21:34 64492 8192000000 10295066640 10745573376 1329700800 13.964 95 15 30 0 1342410464 9580272 3129392
16:23:41 66087 8192000000 10301323568 10741067776 1351226672 14.159 97 24 47 1 1365751024 9569664 4954688
16:25:49 144505 8192000000 10290440240 10744299520 1313837760 13.821 94 19 38 1 1329625168 11060400 4727008
16:30:26 41034 8192000000 10270527104 10738556928 1363255536 14.267 99 13 26 1 1381491056 15245776 2989744
16:32:15 60270 8192000000 10279144304 10742370304 1357914544 14.219 98 9 18 0 1376794384 17063344 1816496
16:34:16 39733 8192000000 10246731424 10710134784 1353778096 14.181 100 4 8 0 1365266880 10756192 732592
16:36:03 71444 8192000000 10261071440 10710843392 1312442416 13.808 95 8 16 0 1325271328 10822368 2006544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2021 16:39:34 to 10-10-2021 16:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2021 16:39:50 to 10-10-2021 16:39:52.

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          233197
MON$OLDEST_ACTIVE               235601
MON$OLDEST_SNAPSHOT             235601
MON$NEXT_TRANSACTION            235601
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                       2032032
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Oct 10 16:37:57 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sun Oct 10 16:37:57 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Sun Oct 10 16:37:59 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Sun Oct 10 16:38:00 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Sun Oct 10 16:38:00 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Sun Oct 10 16:38:00 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
--- end of diff output ---

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

10.10.21 16:39:53. 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-10-10 16:39:55 No severe PSQL-related problems occured
10.10.21 16:39:56. End of report.