Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.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 16091596 kB
MemAvailable 30704736 kB
Buffers 0 kB
Cached 15578348 kB
SwapCached 4700 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-03 16:37:52
DTS_END                         2021-10-03 16:37:52.4540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37740
TRN_ID                          238343
ATT_ID                          661
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: 9313.19 20402 1117583
customer order: creation 169.01 364 20281
customer order: refuse 13.88 343 1665
order to supplier: creation 44.13 1279 5296
order to supplier: removal 3.30 111374 396
invoice (draft): creation 44.93 3385 5392
invoice (draft): removal 53.22 789 6386
invoice accept: apply 40.76 18774 4891
invoice accept: cancel 48.37 773 5804
customer reserve: creation 3822.89 183 458747
customer reserve: removal 4781.18 60 573742
realization accept: apply 81.72 199 9807
realization accept: cancel 86.21 106 10345
payment from customer: creation 56.37 133 6764
payment from customer: removal 5.73 79 687
payment to supplier: creation 46.48 181 5578
payment to supplier: removal 3.09 79 371
service: total inventory turnovers 4.65 1136 558
service: total monetary turnovers 6.42 29 770
service: aggregate perf. data 0.83 19314 99
service: refresh index statistics 0.03 249456 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-10-2021 16:38:48 to 03-10-2021 16:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2918
TEST_TIME 2 2796
TEST_TIME 3 3927
TEST_TIME 4 4258
TEST_TIME 5 4540
TEST_TIME 6 4834
TEST_TIME 7 4843
TEST_TIME 8 5129
TEST_TIME 9 5228
TEST_TIME 10 5370
TEST_TIME 11 5182
TEST_TIME 12 5448
TEST_TIME 13 5430
TEST_TIME 14 5564
TEST_TIME 15 5944
TEST_TIME 16 6472
TEST_TIME 17 6836
TEST_TIME 18 7307
TEST_TIME 19 7536
TEST_TIME 20 7434
TEST_TIME 21 8804
TEST_TIME 22 9044
TEST_TIME 23 9082
TEST_TIME 24 9095
TEST_TIME 25 9177
TEST_TIME 26 9582
TEST_TIME 27 10445
TEST_TIME 28 11069
TEST_TIME 29 10805
TEST_TIME 30 10501
TEST_TIME 31 10401
TEST_TIME 32 10232
TEST_TIME 33 10173
TEST_TIME 34 10376
TEST_TIME 35 10299
TEST_TIME 36 10200
TEST_TIME 37 10097
TEST_TIME 38 10009
TEST_TIME 39 9974
TEST_TIME 40 9810
TEST_TIME 41 9658
TEST_TIME 42 9731
TEST_TIME 43 9709
TEST_TIME 44 9728
TEST_TIME 45 9631
TEST_TIME 46 9654
TEST_TIME 47 9599
TEST_TIME 48 9597
TEST_TIME 49 9575
TEST_TIME 50 9592
TEST_TIME 51 9596
TEST_TIME 52 9573
TEST_TIME 53 9596
TEST_TIME 54 9603
TEST_TIME 55 9836
TEST_TIME 56 9785
TEST_TIME 57 9749
TEST_TIME 58 9871
TEST_TIME 59 9804
TEST_TIME 60 9652
TEST_TIME 61 9640
TEST_TIME 62 9569
TEST_TIME 63 9580
TEST_TIME 64 9536
TEST_TIME 65 9462
TEST_TIME 66 9431
TEST_TIME 67 9441
TEST_TIME 68 9373
TEST_TIME 69 9353
TEST_TIME 70 9342
TEST_TIME 71 9373
TEST_TIME 72 9402
TEST_TIME 73 9372
TEST_TIME 74 9514
TEST_TIME 75 9558
TEST_TIME 76 9559
TEST_TIME 77 9501
TEST_TIME 78 9522
TEST_TIME 79 9606
TEST_TIME 80 9598
TEST_TIME 81 9511
TEST_TIME 82 9416
TEST_TIME 83 9425
TEST_TIME 84 9395
TEST_TIME 85 9329
TEST_TIME 86 9332
TEST_TIME 87 9278
TEST_TIME 88 9273
TEST_TIME 89 9273
TEST_TIME 90 9263
TEST_TIME 91 9317
TEST_TIME 92 9323
TEST_TIME 93 9312
TEST_TIME 94 9298
TEST_TIME 95 9331
TEST_TIME 96 9351
TEST_TIME 97 9401
TEST_TIME 98 9426
TEST_TIME 99 9410
TEST_TIME 100 9440
TEST_TIME 101 9422
TEST_TIME 102 9411
TEST_TIME 103 9456
TEST_TIME 104 9433
TEST_TIME 105 9365
TEST_TIME 106 9335
TEST_TIME 107 9324
TEST_TIME 108 9344
TEST_TIME 109 9349
TEST_TIME 110 9313
TEST_TIME 111 9308
TEST_TIME 112 9260
TEST_TIME 113 9276
TEST_TIME 114 9252
TEST_TIME 115 9301
TEST_TIME 116 9271
TEST_TIME 117 9337
TEST_TIME 118 9308
TEST_TIME 119 9317
TEST_TIME 120 9320

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2021 16:38:52 to 03-10-2021 16:38:55.

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 1541144 1541144 0 0.00 0 25167 7 0 0
doc_list_biud 1541140 1541140 0 0.00 0 30688 28 0 0
sp_add_invoice_to_stock 5041 4891 150 2.97 33 159372 18774 0 150
sp_cancel_adding_invoice 5804 5804 0 0.00 5 79980 774 0 0
sp_cancel_client_order 1665 1665 0 0.00 5 11151 344 0 0
sp_cancel_customer_reserve 573742 573742 0 0.00 1 30690 61 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 9687 80 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 3918 79 0 0
sp_cancel_supplier_invoice 6392 6386 6 0.09 1 15010 790 0 6
sp_cancel_supplier_order 396 396 0 0.00 6 1196554 111374 0 0
sp_cancel_write_off 10345 10345 0 0.00 1 10685 107 0 0
sp_client_order 20281 20281 0 0.00 4 23445 364 0 0
sp_customer_reserve 458747 458747 0 0.00 0 95022 184 0 0
sp_fill_shopping_cart 30965 30965 0 0.00 1 26105 43 0 0
sp_fill_shopping_cart_clo_res 458739 458739 0 0.00 0 11779 13 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 37266 350 0 0
sp_kill_cost_storno 17207 17207 0 0.00 0 10637 55 0 0
sp_kill_qstorno_ret_qs2qd 1164611 1164611 0 0.00 0 30685 33 0 0
sp_kill_qty_storno 613026 613026 0 0.00 0 30688 70 0 0
sp_lock_selected_doc 20233 20233 0 0.00 0 8390 6 0 0
sp_make_cost_storno 27032 27032 0 0.00 0 24633 75 0 0
sp_multiply_rows_for_pdistr 27030 27030 0 0.00 0 9805 32 0 0
sp_multiply_rows_for_qdistr 20281 20281 0 0.00 0 21983 174 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 38271 133 0 0
sp_pay_to_supplier 5578 5578 0 0.00 4 21859 181 0 0
sp_payment_common 12342 12342 0 0.00 1 38234 47 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 11144 314 0 0
sp_qd_handle_on_invoice_upd_sts 10686 10686 0 0.00 0 18701 315 0 0
sp_qd_handle_on_reserve_upd_sts 9806 9806 0 0.00 0 18522 57 0 0
sp_reserve_write_off 9807 9807 0 0.00 7 22142 199 0 0
sp_supplier_invoice 5392 5392 0 0.00 6 82127 3385 0 0
sp_supplier_order 5296 5296 0 0.00 6 26969 1280 0 0
srv_aggregate_perf_data 99 99 0 0.00 3035 271827 19315 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1595 131891 48191 0 0
srv_find_qd_qs_mism 1070387 1070387 0 0.00 0 17350 6 0 0
srv_make_invnt_saldo 558 558 0 0.00 32 97607 1136 0 0
srv_make_money_saldo 770 770 0 0.00 1 5702 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 114600 306148 249457 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4891 4891 0 0.00 0 16030 184 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 3073 82 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 13410 20 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 5303 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1366 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 3151 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 1437 20 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 29110 1895 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 1852 7 0 0
t$perf-norm:sp_client_order 20281 20281 0 0.00 0 14211 22 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 1959 5 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 9936 13 0 0
t$perf-norm:sp_pay_to_supplier 5578 5578 0 0.00 0 15335 13 0 0
t$perf-norm:sp_reserve_write_off 9807 9807 0 0.00 0 17310 11 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 10290 20 0 0
t$perf-norm:sp_supplier_order 5296 5296 0 0.00 0 9147 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 17 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 4659 106 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 1118 21 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 2217 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10060 9760 300 2.98 0 4167 18 0 300
v_all_customers 36652 36652 0 0.00 0 16 1 0 0
v_all_suppliers 29318 29318 0 0.00 0 15 1 0 0
v_all_wares 40558 40558 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 600 8 0 0
v_cancel_client_order 3330 3330 0 0.00 0 3189 5 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2629 7 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 1722 6 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 1961 9 0 0
v_cancel_supplier_invoice 732 720 12 1.63 0 284 16 0 12
v_cancel_supplier_order 792 792 0 0.00 0 2400 10 0 0
v_cancel_write_off 1916 1916 0 0.00 0 1746 14 0 0
v_max_id_clo_ord 5293 5293 0 0.00 0 2520 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 3583 24 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 4275 2 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 242 2 0 0
v_max_non_paid_realizn 3668 3668 0 0.00 0 12322 8 0 0
v_min_id_clo_ord 5293 5293 0 0.00 0 7482 13 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 5617 10 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 7802 19 0 0
v_min_non_paid_invoice 5578 5578 0 0.00 0 15118 33 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 11875 25 0 0
v_our_firm 10098 10098 0 0.00 0 22 1 0 0
v_reserve_write_off 19612 19612 0 0.00 0 3352 5 0 0
x_lock_depdocs_on_canc_invoice 11830 11830 0 0.00 0 24007 132 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 6437 350 0 0
x_make_qstorno_1000_1200 5296 5296 0 0.00 2 26933 1187 0 0
x_make_qstorno_1200_2000 5392 5392 0 0.00 2 79018 3234 0 0
x_make_qstorno_2100_3300 458747 458747 0 0.00 0 94107 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2021 16:38:56 to 03-10-2021 16:39:00.

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:56 8 8192000000 8801157104 8812462080 7256432 0.088 2 1 2 0 7375808 56816 62560
14:08:56 15929 8192000000 10369085584 10687741952 1425365584 14.820 100 87 174 0 1459701760 6415584 27920592
14:10:17 17196 8192000000 10432670176 10761568256 1488792608 15.378 100 90 180 0 1526024544 8591056 28640880
14:11:42 13010 8192000000 10468131472 10803617792 1519581056 15.647 100 87 174 0 1557395888 10045712 27769120
14:13:00 13871 8192000000 10551799104 10907389952 1604911824 16.381 100 82 164 1 1640080592 10283872 24884896
14:14:18 8405 8192000000 10642873056 11010904064 1696301936 17.154 100 83 166 0 1733105456 12625712 24177808
14:15:30 6651 8192000000 10710709632 11088048128 1771534160 17.780 100 85 171 1 1808465888 12664800 24266928
14:16:40 2715 8192000000 10753859488 11137032192 1817940272 18.161 100 88 176 1 1856264768 13421312 24903184
14:17:44 2073 8192000000 10803885648 11192922112 1862086992 18.520 100 92 183 1 1901719296 14433968 25198336
14:18:47 1547 8192000000 10839615264 11227500544 1885519280 18.710 100 93 189 0 1929541552 17114368 26907904
14:19:50 50399 8192000000 10830209968 11233910784 1891963760 18.762 100 54 107 1 1914099040 6545216 15590064
14:21:40 5448 8192000000 10814851584 11243958272 1901449632 18.838 100 24 49 0 1915831248 6910560 7471056
14:22:50 8866 8192000000 10818382448 11249758208 1908195792 18.892 100 21 41 0 1919797136 5789408 5811936
14:24:02 10099 8192000000 10826472672 11258884096 1911007344 18.915 100 13 27 0 1920325968 5263344 4055280
14:25:17 34137 8192000000 10862264992 11273650176 1923946448 19.018 100 58 113 2 1946549616 4797680 17805488
14:26:51 6237 8192000000 10827253856 11257298944 1880119696 18.666 98 28 53 1 1898154128 10355840 7678592
14:27:58 12581 8192000000 10779351936 11205767168 1780862400 17.857 94 18 36 0 1791403216 5374128 5166688
14:29:18 18762 8192000000 10737130784 11155849216 1726344848 17.405 93 34 68 1 1742447888 5952896 10150144
14:30:41 4362 8192000000 10710341888 11122614272 1777768928 17.831 99 25 50 1 1790409520 5190352 7450240
14:31:46 3891 8192000000 10728141424 11143872512 1812115344 18.113 100 32 65 2 1829079760 5880448 11083968
14:32:50 10347 8192000000 10726752176 11144556544 1752917392 17.626 96 18 36 0 1771936208 13974192 5044624
14:34:07 24808 8192000000 10765305872 11188416512 1846723264 18.395 100 35 70 2 1863352832 6776912 9852656
14:35:35 9073 8192000000 10776827696 11211198464 1866202912 18.554 100 25 49 0 1879240224 6044336 6992976
14:36:48 7923 8192000000 10801463920 11227344896 1879734272 18.663 100 21 39 3 1890605280 5215808 5655200
14:37:56 111679 8192000000 10869224032 11296849920 1925685856 19.032 100 40 81 2 1941884000 5550592 10647552
14:40:54 5201 8192000000 10912085216 11330002944 1966140768 19.355 100 61 118 0 1999350992 16148960 17061264
14:42:01 9917 8192000000 10910391936 11337064448 1969551088 19.382 100 49 98 0 2000935280 17388896 13995296
14:43:14 12632 8192000000 10925338448 11353210880 1979009952 19.457 100 49 99 2 2012774848 18723024 15041872
14:44:32 14381 8192000000 10928904400 11365593088 1989838592 19.543 100 43 84 1 2023658576 21496192 12323792
14:45:54 6924 8192000000 10959805616 11388153856 2001505264 19.635 100 52 103 3 2046733856 28621632 16606960
14:47:07 8064 8192000000 10965660752 11397832704 2014490800 19.737 100 58 116 1 2062672816 29639456 18542560
14:48:31 6269 8192000000 10981390112 11399659520 2012459744 19.721 100 71 142 1 2064269952 30607472 21202736
14:49:42 8600 8192000000 10966374448 11387658240 1997068176 19.600 100 60 120 0 2048303808 32598240 18637392
14:50:55 5397 8192000000 10944217456 11368767488 1967093664 19.362 99 72 143 0 2028662512 38904960 22663888
14:52:05 3627 8192000000 10969738032 11395469312 1983044512 19.489 99 73 146 2 2048703216 42087008 23571696
14:53:17 2198 8192000000 10985393984 11407077376 1995813856 19.590 99 80 160 1 2061447168 41314176 24319136
14:54:26 9561 8192000000 10971711088 11404746752 1980177760 19.466 99 61 121 1 2045915664 46298960 19438944
14:55:42 2888 8192000000 10974194224 11389370368 1976958896 19.441 100 84 166 1 2053203536 50784688 25459952
14:56:50 13769 8192000000 10959995104 11400609792 1978716240 19.455 100 44 86 1 2050296672 56705824 14874608
14:58:10 1594 8192000000 10993663744 11401875456 1969248176 19.379 99 82 161 2 2065059328 70599664 25211488
14:59:13 12746 8192000000 10944045456 11399278592 1975994256 19.433 100 36 72 1 2036760736 48706288 12060192
15:00:40 31135 8192000000 10931132464 11392774144 1964325744 19.340 100 21 42 3 2017933408 46663216 6944448
15:02:15 25978 8192000000 10918406592 11373838336 1921569024 18.999 98 25 48 0 1976978384 47036000 8373360
15:04:09 35090 8192000000 10879945408 11342995456 1928454016 19.055 100 16 33 0 1971052896 37156768 5442112
15:05:55 116601 8192000000 10878959152 11323129856 1903606128 18.855 98 41 82 1 1929570432 13598656 12365648
15:08:57 57479 8192000000 10886699280 11330551808 1940256928 19.149 99 50 94 5 1963705344 10534944 12913472
15:11:06 25203 8192000000 10861518096 11313254400 1898597920 18.815 97 33 64 3 1926085248 17831744 9655584
15:12:32 50668 8192000000 10874221824 11336212480 1955214848 19.268 100 15 30 0 1967369440 7964320 4190272
15:14:26 99184 8192000000 10886988976 11344277504 1930729936 19.073 98 10 20 0 1945217040 11398464 3088640
15:17:14 109950 8192000000 10896223808 11358937088 1959316528 19.301 100 19 38 1 1975934176 11215920 5401728
15:20:45 78861 8192000000 10882162400 11371499520 1967645760 19.367 100 14 28 0 1989422512 18341712 3435040
15:23:09 59501 8192000000 10880611312 11335200768 1914581744 18.943 97 14 28 2 1926959808 8809280 3568784
15:25:13 42066 8192000000 10907028048 11373342720 1968516704 19.374 99 27 54 1 1984651488 8743824 7390960
15:26:55 51434 8192000000 10890968592 11366404096 1946600224 19.199 98 19 38 0 1959686208 8157392 4928592
15:28:47 58630 8192000000 10920373136 11388776448 1996304256 19.594 100 32 64 1 2014889296 9761424 8823616
15:30:49 41490 8192000000 10912893120 11384692736 1969309552 19.380 99 9 18 0 1986253712 14325040 2619120
15:32:46 84649 8192000000 10885275856 11347795968 1890591424 18.751 95 13 26 1 1905982880 12105136 3286320
15:35:15 58088 8192000000 10897598064 11383328768 1968035040 19.370 100 30 60 0 1993277776 16599776 8642960
15:38:52 27511 8192000000 10900523360 11346038784 1908138112 18.892 96 45 90 0 1929926576 8700208 13088256
15:40:45 44035 8192000000 10869210304 11339206656 1923008144 19.011 98 14 26 1 1933405328 6505264 3891920
15:42:29 51376 8192000000 10855109616 11322335232 1896886720 18.801 97 11 22 1 1907000608 7327504 2786384
15:46:22 23118 8192000000 10864966656 11334430720 1916527792 18.959 98 25 50 1 1931317600 7555184 7234624
15:47:46 53199 8192000000 10837160496 11308638208 1877965360 18.649 97 16 33 1 1894774848 11823232 4986256
15:49:41 66533 8192000000 10848177120 11313303552 1840851200 18.348 95 21 42 0 1863642096 16623568 6167328
15:51:48 65183 8192000000 10859410032 11335565312 1890117440 18.747 98 11 22 0 1916495952 23333984 3044528
15:53:58 47444 8192000000 10871597392 11322961920 1880849024 18.672 97 28 56 0 1898325248 10094560 7381664
15:55:45 29751 8192000000 10874164976 11323641856 1890475104 18.750 97 40 80 1 1921865696 20184752 11205840
15:57:18 131891 8192000000 10865471936 11322404864 1884539424 18.702 97 18 36 1 1905473296 15664352 5269520
16:00:35 8440 8192000000 10854953376 11317612544 1916511184 18.959 99 23 46 0 1936746416 13996768 6238464
16:01:50 70642 8192000000 10861603776 11338842112 1927334112 19.046 99 10 20 0 1946591232 16347888 2909232
16:04:05 55995 8192000000 10873706256 11348553728 1937875648 19.130 99 17 34 0 1955280752 12519568 4885536
16:06:01 66422 8192000000 10884378208 11366670336 1962111376 19.323 100 15 30 1 1978523968 11993552 4419040
16:08:08 62234 8192000000 10904484352 11386535936 1955514928 19.270 99 13 26 1 1977045040 18256960 3273152
16:10:10 71014 8192000000 10893792432 11361263616 1931047232 19.075 98 11 22 0 1942305344 8474240 2783872
16:12:38 38885 8192000000 10902322592 11372531712 1941321824 19.157 98 27 54 0 1962131440 13333040 7476576
16:14:18 118575 8192000000 10904374080 11365625856 1876587008 18.638 95 11 22 1 1895891872 16383872 2920992
16:17:19 84855 8192000000 10899517536 11371094016 1936633472 19.120 98 28 57 0 1959292640 14909472 7749696
16:19:45 99065 8192000000 10899672016 11374145536 1954160816 19.260 99 11 22 0 1969439840 12290944 2988080
16:22:24 85893 8192000000 10925400672 11397910528 1984393904 19.499 100 28 54 0 2001985392 10197056 7394432
16:24:57 47836 8192000000 10902048032 11386605568 1961093424 19.315 99 15 30 0 1990909424 25361712 4454288
16:26:46 41846 8192000000 10902271072 11386462208 1962018400 19.322 99 10 20 0 1978420208 13570544 2831264
16:28:31 69719 8192000000 10883334304 11359318016 1934451136 19.102 97 9 18 1 1948143376 11046816 2645424
16:31:21 73109 8192000000 10875122432 11371683840 1887350144 18.724 95 12 24 0 1904266320 13518848 3397328
16:33:35 84405 8192000000 10876063280 11361222656 1911533312 18.919 97 16 32 1 1926554240 10144816 4876112
16:36:00 59106 8192000000 10861049712 11331674112 1854194704 18.456 94 23 45 1 1873455264 13426928 5833632

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-10-2021 16:39:02 to 03-10-2021 16:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          234095
MON$OLDEST_ACTIVE               238508
MON$OLDEST_SNAPSHOT             238508
MON$NEXT_TRANSACTION            238508
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                       2021376
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:130: 
:131: mscs-test-161.budzdorov.ru    Sun Oct  3 16:37:55 2021
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:133: 
:134: 
:135: mscs-test-161.budzdorov.ru    Sun Oct  3 16:37:55 2021
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Sun Oct  3 16:37:57 2021
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
--- end of diff output ---

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

03.10.21 16:39:21. 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-03 16:39:23 No severe PSQL-related problems occured
03.10.21 16:39:24. End of report.