Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 01: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 15994800 kB
MemAvailable 30958816 kB
Buffers 0 kB
Cached 15637500 kB
SwapCached 2580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-16 01:37:44
DTS_END                         2021-08-16 01:37:44.8100
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/37632
TRN_ID                          248130
ATT_ID                          718
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: 9631.05 20847 1155726
customer order: creation 173.14 342 20777
customer order: refuse 15.09 340 1811
order to supplier: creation 47.48 1182 5697
order to supplier: removal 3.56 100874 427
invoice (draft): creation 46.52 3152 5582
invoice (draft): removal 56.68 689 6801
invoice accept: apply 42.65 17732 5118
invoice accept: cancel 51.27 753 6152
customer reserve: creation 3882.96 178 465955
customer reserve: removal 5006.66 57 600799
realization accept: apply 85.52 166 10262
realization accept: cancel 86.81 104 10417
payment from customer: creation 61.03 110 7324
payment from customer: removal 5.43 96 652
payment to supplier: creation 49.98 122 5997
payment to supplier: removal 3.51 61 421
service: total inventory turnovers 5.20 1033 624
service: total monetary turnovers 6.74 30 809
service: aggregate perf. data 0.81 16727 97
service: refresh index statistics 0.03 273188 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 01:38:25 to 16-08-2021 01:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 723
TEST_TIME 2 2421
TEST_TIME 3 3201
TEST_TIME 4 3600
TEST_TIME 5 3915
TEST_TIME 6 4123
TEST_TIME 7 4619
TEST_TIME 8 5309
TEST_TIME 9 5463
TEST_TIME 10 5645
TEST_TIME 11 5679
TEST_TIME 12 5985
TEST_TIME 13 6323
TEST_TIME 14 6569
TEST_TIME 15 6481
TEST_TIME 16 6827
TEST_TIME 17 6892
TEST_TIME 18 7004
TEST_TIME 19 7502
TEST_TIME 20 8317
TEST_TIME 21 8721
TEST_TIME 22 8906
TEST_TIME 23 8848
TEST_TIME 24 9243
TEST_TIME 25 9490
TEST_TIME 26 9362
TEST_TIME 27 9968
TEST_TIME 28 10889
TEST_TIME 29 10764
TEST_TIME 30 10662
TEST_TIME 31 10488
TEST_TIME 32 10518
TEST_TIME 33 10312
TEST_TIME 34 10296
TEST_TIME 35 10417
TEST_TIME 36 10490
TEST_TIME 37 10322
TEST_TIME 38 10550
TEST_TIME 39 10388
TEST_TIME 40 10156
TEST_TIME 41 10191
TEST_TIME 42 10157
TEST_TIME 43 10017
TEST_TIME 44 9963
TEST_TIME 45 9845
TEST_TIME 46 9841
TEST_TIME 47 9839
TEST_TIME 48 9981
TEST_TIME 49 10048
TEST_TIME 50 10011
TEST_TIME 51 9968
TEST_TIME 52 9849
TEST_TIME 53 9727
TEST_TIME 54 9812
TEST_TIME 55 9818
TEST_TIME 56 9738
TEST_TIME 57 9721
TEST_TIME 58 9645
TEST_TIME 59 9615
TEST_TIME 60 9588
TEST_TIME 61 9607
TEST_TIME 62 9637
TEST_TIME 63 9681
TEST_TIME 64 9729
TEST_TIME 65 9758
TEST_TIME 66 9826
TEST_TIME 67 9958
TEST_TIME 68 9861
TEST_TIME 69 9887
TEST_TIME 70 9960
TEST_TIME 71 9889
TEST_TIME 72 9920
TEST_TIME 73 10025
TEST_TIME 74 9900
TEST_TIME 75 9836
TEST_TIME 76 9757
TEST_TIME 77 9740
TEST_TIME 78 9740
TEST_TIME 79 9705
TEST_TIME 80 9668
TEST_TIME 81 9667
TEST_TIME 82 9601
TEST_TIME 83 9589
TEST_TIME 84 9530
TEST_TIME 85 9597
TEST_TIME 86 9647
TEST_TIME 87 9647
TEST_TIME 88 9682
TEST_TIME 89 9648
TEST_TIME 90 9742
TEST_TIME 91 9702
TEST_TIME 92 9802
TEST_TIME 93 9789
TEST_TIME 94 9832
TEST_TIME 95 9831
TEST_TIME 96 9876
TEST_TIME 97 9791
TEST_TIME 98 9759
TEST_TIME 99 9695
TEST_TIME 100 9718
TEST_TIME 101 9651
TEST_TIME 102 9608
TEST_TIME 103 9561
TEST_TIME 104 9509
TEST_TIME 105 9523
TEST_TIME 106 9486
TEST_TIME 107 9437
TEST_TIME 108 9419
TEST_TIME 109 9506
TEST_TIME 110 9585
TEST_TIME 111 9599
TEST_TIME 112 9580
TEST_TIME 113 9557
TEST_TIME 114 9566
TEST_TIME 115 9577
TEST_TIME 116 9601
TEST_TIME 117 9611
TEST_TIME 118 9623
TEST_TIME 119 9636
TEST_TIME 120 9632

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-08-2021 01:38:28 to 16-08-2021 01:38:32.

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 1587137 1587137 0 0.00 0 18437 6 0 0
doc_list_biud 1587137 1587137 0 0.00 0 34354 27 0 0
sp_add_invoice_to_stock 5242 5118 124 2.36 39 289275 17732 0 124
sp_cancel_adding_invoice 6152 6152 0 0.00 5 74350 754 0 0
sp_cancel_client_order 1811 1811 0 0.00 4 34024 340 0 0
sp_cancel_customer_reserve 600799 600799 0 0.00 1 34358 57 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 8812 96 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 5438 61 0 0
sp_cancel_supplier_invoice 6808 6801 7 0.10 1 28225 690 0 7
sp_cancel_supplier_order 427 427 0 0.00 6 1094112 100875 0 0
sp_cancel_write_off 10417 10417 0 0.00 1 14202 105 0 0
sp_client_order 20777 20777 0 0.00 4 11785 343 0 0
sp_customer_reserve 465955 465955 0 0.00 0 91477 179 0 0
sp_fill_shopping_cart 32045 32045 0 0.00 1 10561 35 0 0
sp_fill_shopping_cart_clo_res 465953 465953 0 0.00 0 15086 13 0 0
sp_get_clo_for_invoice 5114 5114 0 0.00 0 19552 293 0 0
sp_kill_cost_storno 17642 17642 0 0.00 0 8806 54 0 0
sp_kill_qstorno_ret_qs2qd 1219243 1219243 0 0.00 0 34314 31 0 0
sp_kill_qty_storno 641782 641782 0 0.00 0 34354 66 0 0
sp_lock_selected_doc 21279 21279 0 0.00 0 7658 5 0 0
sp_make_cost_storno 28696 28696 0 0.00 0 10953 54 0 0
sp_multiply_rows_for_pdistr 28696 28696 0 0.00 0 7233 25 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 10416 159 0 0
sp_pay_from_customer 7324 7324 0 0.00 4 15058 110 0 0
sp_pay_to_supplier 5997 5997 0 0.00 5 10048 122 0 0
sp_payment_common 13321 13321 0 0.00 1 10557 27 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 0 34014 310 0 0
sp_qd_handle_on_invoice_upd_sts 11265 11265 0 0.00 0 26094 323 0 0
sp_qd_handle_on_reserve_upd_sts 10262 10262 0 0.00 0 27243 54 0 0
sp_reserve_write_off 10262 10262 0 0.00 6 27306 166 0 0
sp_supplier_invoice 5582 5582 0 0.00 8 96476 3153 0 0
sp_supplier_order 5697 5697 0 0.00 6 33054 1182 0 0
srv_aggregate_perf_data 97 97 0 0.00 3020 119177 16727 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1692 126498 46549 0 0
srv_find_qd_qs_mism 1107269 1107269 0 0.00 0 18437 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 40 33002 1033 0 0
srv_make_money_saldo 809 809 0 0.00 1 3308 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 181917 452862 273189 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5118 5118 0 0.00 0 6871 154 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2010 77 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 10110 22 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 4043 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 2744 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 802 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 452 452 0 0.00 0 2285 28 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 21147 1843 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 2006 7 0 0
t$perf-norm:sp_client_order 20777 20777 0 0.00 0 9136 18 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 1886 9 0 0
t$perf-norm:sp_pay_from_customer 7324 7324 0 0.00 0 4881 10 0 0
t$perf-norm:sp_pay_to_supplier 5997 5997 0 0.00 0 6332 10 0 0
t$perf-norm:sp_reserve_write_off 10262 10262 0 0.00 0 4020 9 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 4923 19 0 0
t$perf-norm:sp_supplier_order 5697 5697 0 0.00 0 9596 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 398 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 2058 29 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 677 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 880 222 0 0
v_add_invoice_to_stock 10474 10226 248 2.36 0 5194 19 0 248
v_all_customers 38484 38484 0 0.00 0 121 1 0 0
v_all_suppliers 31238 31238 0 0.00 0 20 1 0 0
v_all_wares 41554 41554 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 128 5 0 0
v_cancel_client_order 3622 3622 0 0.00 0 5059 10 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 1515 6 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 345 3 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 5116 11 0 0
v_cancel_supplier_invoice 918 904 14 1.52 0 395 12 0 14
v_cancel_supplier_order 854 854 0 0.00 0 697 5 0 0
v_cancel_write_off 2054 2054 0 0.00 0 3351 17 0 0
v_max_id_clo_ord 5697 5697 0 0.00 0 461 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 2739 10 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 10457 5 0 0
v_max_non_paid_invoice 1646 1646 0 0.00 0 770 3 0 0
v_max_non_paid_realizn 3725 3725 0 0.00 0 4832 8 0 0
v_min_id_clo_ord 5697 5697 0 0.00 0 988 5 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 1920 6 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 5742 4 0 0
v_min_non_paid_invoice 5997 5997 0 0.00 0 6656 14 0 0
v_min_non_paid_realizn 7324 7324 0 0.00 0 4895 15 0 0
v_our_firm 10268 10268 0 0.00 0 16 1 0 0
v_reserve_write_off 20524 20524 0 0.00 0 8324 6 0 0
x_lock_depdocs_on_canc_invoice 12501 12501 0 0.00 0 16498 106 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 17304 391 0 0
x_make_qstorno_1000_1200 5697 5697 0 0.00 2 33022 1104 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 3 96454 3036 0 0
x_make_qstorno_2100_3300 465955 465955 0 0.00 0 91475 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 01:38:33 to 16-08-2021 01:38:37.

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
23:07:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:49 23896 8192000000 10373746400 10694148096 1424780304 14.815 100 84 168 0 1458115120 6021136 27313680
23:10:24 8925 8192000000 10452800304 10783657984 1510175568 15.565 100 93 186 0 1547515264 8076544 29263152
23:11:39 16247 8192000000 10492834512 10835279872 1547095104 15.885 100 83 166 2 1582740784 9718464 25927216
23:13:01 12972 8192000000 10553679968 10907971584 1610527504 16.429 100 85 169 2 1647115696 11115584 25472608
23:14:17 9986 8192000000 10636940752 11005337600 1698712752 17.174 100 83 166 1 1734861712 11626624 24522336
23:15:30 5301 8192000000 10705613904 11082539008 1762916992 17.709 100 84 167 0 1800618000 14289936 23411072
23:16:38 3051 8192000000 10746218672 11126312960 1806858752 18.070 100 81 160 2 1842892176 13991712 22041712
23:17:42 1676 8192000000 10785689536 11169345536 1843546400 18.370 100 90 180 1 1882800304 13220576 26033328
23:18:45 1592 8192000000 10821128000 11207143424 1873160288 18.610 100 91 180 1 1910355776 12341024 24854464
23:19:47 6366 8192000000 10779247648 11204300800 1869669120 18.582 100 15 30 0 1879499920 5827472 4003328
23:20:55 11690 8192000000 10798117456 11230138368 1887814368 18.728 100 29 57 0 1902594192 7132944 7646880
23:22:09 10570 8192000000 10803265952 11236835328 1896903296 18.801 100 23 45 1 1909983568 6138352 6941920
23:23:22 67186 8192000000 10811589488 11249053696 1903980992 18.858 100 26 52 1 1917036768 5657936 7397840
23:25:30 6342 8192000000 10830440160 11262992384 1912938848 18.930 100 31 62 1 1926856480 5511152 8406480
23:26:38 6941 8192000000 10817876032 11242565632 1888904752 18.737 99 32 65 0 1904417552 6184032 9328768
23:27:45 49192 8192000000 10723789808 11144327168 1727159536 17.412 92 28 55 0 1740373680 5442096 7772048
23:29:35 8213 8192000000 10712935456 11129069568 1712898736 17.293 94 32 61 2 1727207136 5052064 9256336
23:30:43 7622 8192000000 10717215104 11139121152 1780732688 17.856 98 28 55 1 1798934944 10645920 7556336
23:31:51 4926 8192000000 10733578832 11149271040 1816683600 18.151 100 27 54 3 1829764400 5322896 7757904
23:32:57 51533 8192000000 10778531536 11201507328 1862309264 18.522 100 34 68 1 1877874880 6262496 9303120
23:34:55 4424 8192000000 10797207728 11225882624 1881184896 18.675 100 39 78 1 1900132544 7587824 11359824
23:36:00 5704 8192000000 10802796880 11234541568 1887184320 18.723 100 34 68 0 1907716256 10614656 9917280
23:37:06 9460 8192000000 10811377600 11245166592 1902499744 18.846 100 16 32 0 1912319616 5068368 4751504
23:38:19 68314 8192000000 10852859888 11279192064 1908529232 18.895 99 39 78 0 1926325232 7291232 10504768
23:40:40 24665 8192000000 10890620336 11319566336 1957715296 19.288 100 43 86 2 1979468080 9074112 12678672
23:42:09 27238 8192000000 10912996656 11349364736 1978373968 19.452 100 48 96 0 2009796352 17355824 14066560
23:43:40 26782 8192000000 10923438672 11362127872 1984107344 19.497 100 38 76 0 2013832320 18311376 11413600
23:45:13 12340 8192000000 10923642016 11358814208 1983342608 19.491 100 55 110 1 2023400496 23509008 16548880
23:46:32 5854 8192000000 10938750336 11364483072 1984608656 19.501 100 63 126 2 2026202864 22265024 19329184
23:47:40 9221 8192000000 10930043568 11361595392 1978298528 19.451 100 56 112 0 2019066432 23897920 16869984
23:48:57 8210 8192000000 10947800784 11377733632 1992051568 19.560 100 64 128 2 2038022592 26454528 19516496
23:50:17 4531 8192000000 10930992592 11360559104 1965739712 19.352 99 61 122 1 2011866048 27892000 18234336
23:51:28 1691 8192000000 10934323120 11339223040 1953858000 19.257 100 86 169 0 2007497728 27946048 25693680
23:52:36 12450 8192000000 10917002432 11340640256 1943929600 19.178 100 71 142 0 2003288528 37614944 21743984
23:53:53 4583 8192000000 10918854224 11340738560 1940090304 19.147 100 67 134 1 2001131648 39628512 21412832
23:55:03 2731 8192000000 10924144288 11334729728 1925081072 19.028 99 82 163 1 1996684560 44882896 26720592
23:56:10 2631 8192000000 10949703920 11358396416 1952427280 19.246 100 93 184 1 2036598000 54807072 29363648
23:57:19 4009 8192000000 10935695792 11369521152 1966970304 19.361 100 54 108 1 2025898560 40874752 18053504
23:58:40 13179 8192000000 10932027696 11374768128 1958903584 19.297 99 36 72 1 2012106736 40959360 12243792
00:00:03 10111 8192000000 10920835456 11369390080 1957204416 19.284 99 32 64 4 2004164576 36362832 10597328
00:01:16 9975 8192000000 10926935312 11374735360 1958233712 19.292 100 32 64 0 2016063024 46513664 11315648
00:02:36 38792 8192000000 10894972096 11337924608 1907949776 18.890 98 21 42 0 1948708896 33581744 7177376
00:04:17 31555 8192000000 10881556048 11343687680 1929117216 19.060 100 26 51 0 1971353584 33687072 8549296
00:05:52 64528 8192000000 10875143376 11323994112 1914393536 18.942 99 7 14 0 1933024432 16482832 2148064
00:08:00 72783 8192000000 10877207168 11332345856 1890719680 18.752 97 9 18 0 1907693936 14822928 2151328
00:10:13 44007 8192000000 10914853312 11352739840 1971201856 19.395 100 35 70 3 1989489984 8713856 9574272
00:12:01 71875 8192000000 10915148976 11384791040 1964972016 19.346 99 17 34 4 1991951024 21530544 5448464
00:14:14 69068 8192000000 10915798464 11389669376 1967165952 19.363 99 12 24 1 1995999472 25420784 3412736
00:16:24 106040 8192000000 10917226848 11365978112 1946604032 19.199 98 27 54 1 1962626336 8526672 7495632
00:19:29 28000 8192000000 10889163584 11353423872 1950838832 19.233 98 48 95 1 1972445728 8307152 13299744
00:21:02 21363 8192000000 10921648544 11378753536 1969800432 19.384 99 35 71 2 1990703216 10085616 10817168
00:22:25 41221 8192000000 10937329664 11397844992 1986477552 19.516 99 35 70 1 2011073968 13924624 10671792
00:24:19 45223 8192000000 10934533952 11399098368 1983390080 19.492 99 40 79 1 2017098080 22025856 11682144
00:26:13 72372 8192000000 10929252272 11394297856 1966105744 19.355 98 16 33 0 1978421440 8053888 4261808
00:28:54 92162 8192000000 10906651456 11377393664 1940162864 19.148 97 22 44 2 1958351648 12138640 6050144
00:31:37 66278 8192000000 10888496032 11377037312 1955998896 19.274 99 18 36 0 1972461776 11368544 5094336
00:33:49 65500 8192000000 10851334384 11326275584 1892175936 18.763 96 19 38 2 1910669968 13812400 4681632
00:36:20 63895 8192000000 10854561808 11329773568 1844267728 18.376 95 8 16 1 1854342736 7880640 2194368
00:38:24 43134 8192000000 10843882944 11305799680 1860175904 18.505 96 17 34 0 1872563344 7702384 4685056
00:40:11 54562 8192000000 10858926688 11323797504 1897945056 18.810 98 18 36 0 1918177856 15078800 5154000
00:42:06 42262 8192000000 10904623808 11365052416 1950307936 19.229 100 35 70 0 1982124320 22309168 9507216
00:43:48 78607 8192000000 10860225344 11332653056 1849145424 18.415 95 14 28 1 1869229792 16200096 3884272
00:46:09 43718 8192000000 10896973216 11366416384 1961879968 19.321 100 33 66 1 1983625056 12510864 9234224
00:47:53 33802 8192000000 10882073312 11337302016 1882956944 18.689 96 25 48 1 1897307888 7927376 6423568
00:49:29 84013 8192000000 10877970896 11367141376 1895895792 18.793 97 12 24 1 1920828992 21740112 3193088
00:51:54 84578 8192000000 10876414208 11341996032 1943118880 19.172 100 24 47 1 1959025104 9362256 6543968
00:54:19 45816 8192000000 10870819216 11348434944 1933121232 19.092 99 16 32 1 1950076400 12407616 4547552
00:56:14 26962 8192000000 10855816912 11345211392 1935013968 19.107 100 8 16 0 1948434400 11228256 2192176
00:57:51 56279 8192000000 10850210896 11344277504 1936883040 19.122 100 10 20 0 1950717008 10739344 3094624
00:59:48 43374 8192000000 10872348944 11346362368 1919444512 18.982 98 17 34 0 1939076224 15160784 4470928
01:01:34 71053 8192000000 10905684656 11381272576 1915201888 18.948 97 16 32 1 1937191136 17266928 4722320
01:03:51 49544 8192000000 10882193552 11355504640 1928543216 19.055 98 8 16 0 1940557328 9567616 2446496
01:06:00 32778 8192000000 10936319808 11383107584 1976778256 19.439 100 43 86 1 2001741872 12956336 12007280
01:07:36 64738 8192000000 10927445328 11380039680 1954853184 19.265 99 18 36 0 1973150704 12756928 5540592
01:09:43 102627 8192000000 10923059744 11403325440 1932480816 19.087 97 13 26 1 1950506176 14938672 3086688
01:12:27 112568 8192000000 10897291280 11388145664 1938382800 19.134 97 10 20 1 1956576496 15606224 2587472
01:15:35 50984 8192000000 10913062176 11397881856 1961615248 19.319 98 15 30 0 1976827056 11199920 4011888
01:17:36 48379 8192000000 10885367312 11381739520 1944172624 19.180 98 9 18 0 1957159344 10109440 2877280
01:19:25 50283 8192000000 10899743200 11383357440 1981620480 19.478 100 28 56 1 1997176848 7658944 7897424
01:21:17 16276 8192000000 10922455120 11396247552 1994545744 19.580 100 22 44 1 2012643408 11952464 6145200
01:22:34 69992 8192000000 10874922944 11337695232 1872282640 18.603 95 26 53 0 1902799136 22220784 8295712
01:25:07 89472 8192000000 10898838688 11385630720 1937824560 19.129 99 12 24 0 1970025440 28395408 3805472
01:27:51 126498 8192000000 10881377264 11353841664 1867999440 18.568 95 9 18 1 1889353744 18984128 2370176
01:31:01 33872 8192000000 10889986192 11347779584 1886512096 18.718 96 33 66 0 1908439232 12787632 9139504
01:32:37 21304 8192000000 10875825360 11322572800 1900576224 18.831 98 43 86 0 1923268640 10901104 11791312
01:34:04 92749 8192000000 10871798192 11343114240 1896463712 18.798 98 26 52 1 1920192768 16524208 7204848
01:36:38 65611 8192000000 10866776672 11333238784 1882510160 18.685 97 16 32 1 1896760496 10281280 3969056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-08-2021 01:38:39 to 16-08-2021 01:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2021 01:38:54 to 16-08-2021 01:38:56.

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          246809
MON$OLDEST_ACTIVE               248412
MON$OLDEST_SNAPSHOT             248412
MON$NEXT_TRANSACTION            248412
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                       2060704
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:317: 
:318: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:49 2021
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:320: 
:321: 
:322: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:51 2021
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:324: 
:325: 
:326: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:55 2021
:327:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:328: 
:329: 
:330: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:55 2021
:331:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:332: 
:333: 
:334: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:55 2021
:335:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:336: 
:337: 
:338: mscs-test-161.budzdorov.ru    Mon Aug 16 01:37:56 2021
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:340: 
--- end of diff output ---

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

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