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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16862068 kB
MemAvailable 31492548 kB
Buffers 0 kB
Cached 14839816 kB
SwapCached 2824 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-06 13:26:53
DTS_END                         2020-11-06 13:26:53.6420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39086
TRN_ID                          231031
ATT_ID                          645
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: 8833.24 26156 1059989
customer order: creation 167.91 440 20149
customer order: refuse 15.49 383 1859
order to supplier: creation 44.32 1474 5318
order to supplier: removal 3.11 144931 373
invoice (draft): creation 45.05 3968 5406
invoice (draft): removal 49.53 1121 5943
invoice accept: apply 40.51 19301 4861
invoice accept: cancel 45.10 1078 5412
customer reserve: creation 3709.63 189 445156
customer reserve: removal 4415.91 80 529909
realization accept: apply 81.96 184 9835
realization accept: cancel 89.73 152 10767
payment from customer: creation 56.71 120 6805
payment from customer: removal 5.62 96 674
payment to supplier: creation 48.61 138 5833
payment to supplier: removal 3.39 100 407
service: total inventory turnovers 3.79 4246 455
service: total monetary turnovers 6.03 748 723
service: aggregate perf. data 0.83 17102 99
service: refresh index statistics 0.04 327275 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-11-2020 13:29:15 to 06-11-2020 13:29:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2920
TEST_TIME 2 3182
TEST_TIME 3 4105
TEST_TIME 4 4837
TEST_TIME 5 5263
TEST_TIME 6 5031
TEST_TIME 7 5024
TEST_TIME 8 5302
TEST_TIME 9 5393
TEST_TIME 10 5447
TEST_TIME 11 5638
TEST_TIME 12 5849
TEST_TIME 13 6058
TEST_TIME 14 6187
TEST_TIME 15 6316
TEST_TIME 16 6874
TEST_TIME 17 6878
TEST_TIME 18 7073
TEST_TIME 19 7021
TEST_TIME 20 7058
TEST_TIME 21 7476
TEST_TIME 22 7554
TEST_TIME 23 7525
TEST_TIME 24 7473
TEST_TIME 25 7844
TEST_TIME 26 8004
TEST_TIME 27 7936
TEST_TIME 28 8156
TEST_TIME 29 8522
TEST_TIME 30 9169
TEST_TIME 31 10391
TEST_TIME 32 10677
TEST_TIME 33 10579
TEST_TIME 34 10741
TEST_TIME 35 10586
TEST_TIME 36 10493
TEST_TIME 37 10310
TEST_TIME 38 10053
TEST_TIME 39 9825
TEST_TIME 40 9859
TEST_TIME 41 9879
TEST_TIME 42 9846
TEST_TIME 43 9803
TEST_TIME 44 9779
TEST_TIME 45 9665
TEST_TIME 46 9610
TEST_TIME 47 9516
TEST_TIME 48 9506
TEST_TIME 49 9461
TEST_TIME 50 9442
TEST_TIME 51 9452
TEST_TIME 52 9508
TEST_TIME 53 9494
TEST_TIME 54 9444
TEST_TIME 55 9477
TEST_TIME 56 9500
TEST_TIME 57 9571
TEST_TIME 58 9488
TEST_TIME 59 9537
TEST_TIME 60 9605
TEST_TIME 61 9661
TEST_TIME 62 9674
TEST_TIME 63 9620
TEST_TIME 64 9490
TEST_TIME 65 9398
TEST_TIME 66 9339
TEST_TIME 67 9277
TEST_TIME 68 9222
TEST_TIME 69 9181
TEST_TIME 70 9138
TEST_TIME 71 9119
TEST_TIME 72 9097
TEST_TIME 73 9139
TEST_TIME 74 9140
TEST_TIME 75 9130
TEST_TIME 76 9177
TEST_TIME 77 9153
TEST_TIME 78 9175
TEST_TIME 79 9170
TEST_TIME 80 9226
TEST_TIME 81 9356
TEST_TIME 82 9307
TEST_TIME 83 9206
TEST_TIME 84 9163
TEST_TIME 85 9080
TEST_TIME 86 9088
TEST_TIME 87 9095
TEST_TIME 88 9061
TEST_TIME 89 9011
TEST_TIME 90 9005
TEST_TIME 91 8964
TEST_TIME 92 9013
TEST_TIME 93 9027
TEST_TIME 94 9066
TEST_TIME 95 9027
TEST_TIME 96 9003
TEST_TIME 97 9126
TEST_TIME 98 9148
TEST_TIME 99 9137
TEST_TIME 100 9128
TEST_TIME 101 9091
TEST_TIME 102 9021
TEST_TIME 103 8993
TEST_TIME 104 8964
TEST_TIME 105 8930
TEST_TIME 106 8905
TEST_TIME 107 8892
TEST_TIME 108 8890
TEST_TIME 109 8916
TEST_TIME 110 8906
TEST_TIME 111 8882
TEST_TIME 112 8877
TEST_TIME 113 8958
TEST_TIME 114 8952
TEST_TIME 115 8959
TEST_TIME 116 8989
TEST_TIME 117 8967
TEST_TIME 118 8907
TEST_TIME 119 8850
TEST_TIME 120 8840

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-11-2020 13:29:18 to 06-11-2020 13:29:22.

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 1471269 1471269 0 0.00 0 29635 7 0 0
doc_list_biud 1471269 1471269 0 0.00 0 58534 37 0 0
sp_add_invoice_to_stock 4970 4861 109 2.19 47 208327 19301 0 109
sp_cancel_adding_invoice 5412 5412 0 0.00 5 78157 1079 0 0
sp_cancel_client_order 1859 1859 0 0.00 5 12331 383 0 0
sp_cancel_customer_reserve 529909 529909 0 0.00 1 33176 81 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 11709 97 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2892 101 0 0
sp_cancel_supplier_invoice 5958 5943 15 0.25 2 19070 1121 0 15
sp_cancel_supplier_order 373 373 0 0.00 11 1210126 144932 0 0
sp_cancel_write_off 10767 10767 0 0.00 1 21469 152 0 0
sp_client_order 20149 20149 0 0.00 4 27563 440 0 0
sp_customer_reserve 445156 445156 0 0.00 0 109031 189 0 0
sp_fill_shopping_cart 30871 30871 0 0.00 1 20753 51 0 0
sp_fill_shopping_cart_clo_res 445147 445147 0 0.00 0 25028 20 0 0
sp_get_clo_for_invoice 4850 4850 0 0.00 0 44212 423 0 0
sp_kill_cost_storno 17260 17260 0 0.00 0 11936 72 0 0
sp_kill_qstorno_ret_qs2qd 1076901 1076901 0 0.00 0 33122 44 0 0
sp_kill_qty_storno 568945 568945 0 0.00 0 58534 94 0 0
sp_lock_selected_doc 20454 20454 0 0.00 0 7902 6 0 0
sp_make_cost_storno 27320 27320 0 0.00 0 13516 58 0 0
sp_multiply_rows_for_pdistr 27320 27320 0 0.00 0 8122 34 0 0
sp_multiply_rows_for_qdistr 20149 20149 0 0.00 0 27556 241 0 0
sp_pay_from_customer 6805 6805 0 0.00 4 13228 120 0 0
sp_pay_to_supplier 5833 5833 0 0.00 4 21600 138 0 0
sp_payment_common 12638 12638 0 0.00 1 8323 18 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 12319 349 0 0
sp_qd_handle_on_invoice_upd_sts 10259 10259 0 0.00 0 58532 472 0 0
sp_qd_handle_on_reserve_upd_sts 9835 9835 0 0.00 0 22036 57 0 0
sp_reserve_write_off 9835 9835 0 0.00 6 22065 185 0 0
sp_supplier_invoice 5406 5406 0 0.00 5 105507 3969 0 0
sp_supplier_order 5318 5318 0 0.00 5 109352 1474 0 0
srv_aggregate_perf_data 99 99 0 0.00 2699 287740 17102 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1989 211908 43655 0 0
srv_find_qd_qs_mism 1013250 1013250 0 0.00 0 29260 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 57 101843 4246 0 0
srv_make_money_saldo 723 723 0 0.00 1 20984 748 0 0
srv_recalc_idx_stat 5 5 0 0.00 157976 473926 327275 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4861 4861 0 0.00 0 32870 181 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 4270 137 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 2315 13 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 9004 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 672 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1563 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 734 15 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 19250 1747 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 625 4 0 0
t$perf-norm:sp_client_order 20149 20149 0 0.00 0 8504 22 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1297 7 0 0
t$perf-norm:sp_pay_from_customer 6805 6805 0 0.00 0 29393 11 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 4699 9 0 0
t$perf-norm:sp_reserve_write_off 9835 9835 0 0.00 0 6617 10 0 0
t$perf-norm:sp_supplier_invoice 5406 5406 0 0.00 0 7168 21 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 9215 20 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 432 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 904 23 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 2041 12 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 2525 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 55 21 0 0
v_add_invoice_to_stock 9912 9694 218 2.19 0 9725 19 0 218
v_all_customers 36700 36700 0 0.00 0 17 1 0 0
v_all_suppliers 29940 29940 0 0.00 0 23 1 0 0
v_all_wares 40298 40298 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2901 10 0 0
v_cancel_client_order 3718 3718 0 0.00 0 4082 8 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3783 8 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 632 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 418 6 0 0
v_cancel_supplier_invoice 810 780 30 3.70 0 2323 15 0 30
v_cancel_supplier_order 746 746 0 0.00 0 706 6 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3700 19 0 0
v_max_id_clo_ord 5318 5318 0 0.00 0 246 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2112 16 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 6496 7 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 726 3 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 6185 6 0 0
v_min_id_clo_ord 5318 5318 0 0.00 0 4145 7 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1659 6 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 3463 5 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 6184 7 0 0
v_min_non_paid_realizn 6805 6805 0 0.00 0 3596 10 0 0
v_our_firm 9980 9980 0 0.00 0 20 1 0 0
v_reserve_write_off 19670 19670 0 0.00 0 3330 6 0 0
x_lock_depdocs_on_canc_invoice 10965 10965 0 0.00 0 12921 125 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 6456 396 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 2 109343 1388 0 0
x_make_qstorno_1200_2000 5406 5406 0 0.00 2 105500 3765 0 0
x_make_qstorno_2100_3300 445156 445156 0 0.00 0 108987 166 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-11-2020 13:29:22 to 06-11-2020 13:29:27.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:57 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:58 20899 8192000000 9718289888 10010832896 801472144 8.911 100 90 180 0 830918512 7813568 21632800
10:59:26 31981 8192000000 9762113968 10067415040 832924768 9.229 100 87 174 1 866197024 12435568 20836688
11:01:05 22034 8192000000 9802110288 10120912896 863685504 9.537 100 81 162 0 908958128 26665056 18607568
11:02:36 19291 8192000000 9867110944 10202439680 915865728 10.055 100 74 148 0 974512400 42593264 16053408
11:04:06 8148 8192000000 9936036896 10277519360 961747040 10.506 100 79 158 1 1044694464 66749296 16198128
11:05:16 6442 8192000000 9953117744 10308886528 990655600 10.788 100 78 155 2 1078355808 71408080 16292128
11:06:25 3736 8192000000 9963733296 10327072768 1017730000 11.050 100 69 138 0 1072607600 40414256 14463344
11:07:31 1777 8192000000 10033439152 10399928320 1047647488 11.338 100 91 180 0 1143833536 78031696 18154352
11:08:36 7709 8192000000 10014423872 10409930752 1057986144 11.437 100 66 132 1 1144117536 72863840 13267552
11:09:55 4129 8192000000 9997680416 10403213312 1065801776 11.512 100 36 72 1 1119846032 46778144 7266112
11:11:00 5498 8192000000 9993706080 10381950976 1077675360 11.625 100 47 92 2 1116959488 29655328 9628800
11:12:07 58805 8192000000 9997274896 10386993152 1087799920 11.722 100 35 71 3 1115614416 20297136 7517360
11:14:07 5976 8192000000 10015379296 10409926656 1089211648 11.735 100 40 78 2 1120778064 23428752 8137664
11:15:16 7887 8192000000 9984090080 10394492928 1084765856 11.693 99 34 67 2 1106222816 13576944 7880016
11:16:24 8853 8192000000 10006679408 10413105152 1085751280 11.702 100 23 45 3 1105267536 14277376 5238880
11:17:35 20423 8192000000 10016798384 10394460160 1071401376 11.565 98 84 168 1 1109726944 18662160 19663408
11:18:56 7139 8192000000 9957813376 10350899200 1029125264 11.160 96 35 70 2 1048722896 11700368 7897264
11:20:04 6773 8192000000 9946094512 10339332096 1015886944 11.032 99 28 56 3 1043897984 21613424 6397616
11:21:12 38082 8192000000 9937766656 10307969024 968860848 10.576 94 47 94 2 990341744 11388624 10092272
11:22:53 7226 8192000000 9934795072 10325958656 1010552080 10.981 99 40 79 3 1039422384 19386368 9483936
11:24:08 10405 8192000000 9947922096 10333818880 1034134688 11.208 100 33 66 4 1051967376 10491008 7341680
11:25:21 5432 8192000000 9968932464 10364772352 1049521248 11.356 100 23 46 4 1083515696 28973072 5021376
11:26:27 95991 8192000000 10012407472 10402230272 1070676800 11.559 100 39 78 0 1103569968 24686944 8206224
11:29:09 13603 8192000000 10067920752 10466283520 1096876928 11.808 100 48 94 1 1178548592 72327472 9344192
11:30:29 7972 8192000000 10113084016 10506153984 1114376432 11.974 100 53 107 0 1213348256 88037888 10933936
11:31:41 19019 8192000000 10089476496 10500415488 1123072048 12.056 100 34 68 2 1207910176 77569136 7268992
11:33:03 17467 8192000000 10136184976 10543276032 1135503520 12.173 100 39 78 0 1252886544 108984048 8398976
11:34:24 21469 8192000000 10164538320 10592460800 1135728080 12.175 99 46 92 1 1258179072 112219680 10231312
11:35:53 13560 8192000000 10210014368 10622423040 1163048800 12.432 100 50 97 2 1318608544 144913808 10645936
11:37:10 5253 8192000000 10249127968 10651828224 1167939520 12.478 100 63 125 3 1348844240 167078496 13826224
11:38:20 6620 8192000000 10275318256 10682683392 1166140960 12.461 100 66 130 1 1373224208 192850080 14233168
11:39:31 2896 8192000000 10304481424 10707750912 1175668288 12.550 100 81 162 2 1409731008 216063216 17999504
11:40:39 5164 8192000000 10347824592 10748604416 1168052528 12.479 99 80 157 1 1438185456 252937824 17195104
11:41:50 3442 8192000000 10381817264 10784641024 1160366272 12.407 99 76 152 1 1483484608 304982432 18135904
11:42:57 2803 8192000000 10350551952 10753040384 1162887632 12.430 100 76 152 0 1449805248 269685920 17231696
11:44:03 2329 8192000000 10357951568 10758193152 1171674384 12.512 100 80 161 2 1452345968 261902032 18769552
11:45:13 2877 8192000000 10386444608 10788982784 1158442256 12.389 100 81 160 1 1482562736 305676208 18444272
11:46:21 2685 8192000000 10434649200 10828619776 1135243424 12.171 99 81 161 0 1527101168 373540864 18316880
11:47:31 2166 8192000000 10422075088 10814504960 1147280512 12.284 99 86 168 1 1511450400 344877456 19292432
11:48:40 1989 8192000000 10457928416 10856230912 1159322640 12.397 100 82 159 2 1557760208 379303296 19134272
11:49:47 3689 8192000000 10472762128 10894737408 1162708080 12.429 100 51 102 1 1569273616 393329360 13236176
11:51:01 3938 8192000000 10496944832 10943229952 1158939056 12.393 100 68 137 3 1590340640 414129984 17271600
11:52:10 18998 8192000000 10466534384 10905649152 1148254640 12.293 99 36 72 0 1568650480 410795152 9600688
11:53:32 13691 8192000000 10542458976 10966282240 1142557424 12.240 100 42 84 0 1631668224 478346768 10764032
11:55:05 18523 8192000000 10506784688 10954686464 1120523728 12.032 100 34 68 1 1640406752 509587232 10295792
11:56:27 7696 8192000000 10422687840 10894929920 1136138688 12.179 100 35 70 1 1531908272 386757936 9011648
11:57:56 64250 8192000000 10068054992 10579685376 1101361616 11.851 98 5 10 0 1177558592 75077088 1119888
12:00:00 56662 8192000000 10072104864 10576338944 1127310240 12.096 100 9 18 0 1177849888 48906080 1633568
12:02:02 43729 8192000000 10098212368 10539462656 1123995280 12.065 99 31 61 1 1169234256 38925344 6313632
12:04:05 211907 8192000000 10097992416 10531127296 1086379648 11.708 95 33 65 1 1181822832 88722624 6720560
12:08:40 36114 8192000000 10123288928 10541899776 1135534336 12.174 100 49 97 2 1212167728 65968592 10664800
12:10:17 43865 8192000000 10082307568 10508935168 1110000512 11.932 97 24 48 0 1167248704 52075488 5172704
12:12:03 64197 8192000000 10133559488 10570346496 1131397968 12.135 99 23 45 1 1238595040 102112800 5084272
12:14:09 47573 8192000000 10166366000 10611916800 1144580576 12.259 100 12 24 1 1266320096 118853568 2885952
12:15:57 65252 8192000000 10122176976 10589638656 1139336080 12.209 100 14 28 0 1224604848 82179008 3089760
12:18:45 62871 8192000000 10076372736 10535182336 1110254048 11.935 97 7 14 0 1160115872 48325792 1536032
12:20:53 57236 8192000000 10089345552 10567581696 1128835488 12.110 99 15 30 0 1177327760 45616656 2875616
12:22:50 56247 8192000000 10114636144 10561716224 1132939760 12.149 99 25 50 1 1199138640 60866032 5332848
12:24:54 35391 8192000000 10129966832 10567467008 1114389904 11.974 97 34 66 6 1206908624 84894464 7624256
12:27:00 39248 8192000000 10107251344 10534297600 1142564064 12.240 100 33 64 0 1201569920 51363856 7642000
12:28:40 57479 8192000000 10082485104 10533306368 1106613840 11.900 97 31 61 0 1145328224 33261424 5452960
12:30:39 96996 8192000000 10060748320 10489360384 1107593440 11.910 97 15 28 0 1140046544 29771776 2681328
12:33:23 46987 8192000000 10074570016 10519707648 1127731008 12.100 99 18 34 3 1165702944 34289808 3682128
12:35:10 34526 8192000000 10049064160 10497212416 1076704224 11.616 97 15 28 1 1127189104 47303328 3181552
12:36:45 72982 8192000000 10076431024 10501353472 1061273168 11.469 95 17 34 1 1127890576 62686064 3931344
12:39:01 65397 8192000000 10074998800 10535718912 1085400672 11.699 97 22 44 0 1179278864 88748624 5129568
12:41:17 49038 8192000000 10096141040 10520772608 1075025824 11.600 96 19 38 1 1162172272 82702016 4444432
12:43:10 55797 8192000000 10118608064 10555572224 1106491936 11.899 98 19 38 0 1208510928 97133376 4885616
12:45:05 46927 8192000000 10168261120 10608476160 1132339568 12.143 100 39 77 1 1248275632 107103760 8832304
12:46:53 64972 8192000000 10078386160 10538237952 1115837904 11.988 99 14 28 0 1190640192 71755552 3046736
12:49:03 86778 8192000000 10084717504 10525536256 1112346336 11.955 98 27 54 3 1161670512 43763920 5560256
12:51:39 58838 8192000000 10100621696 10577952768 1117492848 12.003 100 8 16 0 1219101152 99553856 2054448
12:53:39 33064 8192000000 10043581968 10560004096 1084549664 11.691 98 4 8 0 1130069744 44896064 624016
12:55:12 24213 8192000000 10047727296 10484191232 1107707952 11.911 99 18 36 0 1129840464 18258656 3873856
12:56:44 49380 8192000000 10083427216 10514972672 1112576160 11.957 99 17 34 2 1178096960 61858784 3662016
12:58:38 55035 8192000000 10122858768 10603393024 1107425344 11.908 98 15 30 1 1197485792 86688480 3371968
13:01:06 62432 8192000000 10122290080 10548367360 1094162160 11.782 97 23 46 0 1139782880 41650624 3970096
13:04:06 86845 8192000000 10068807760 10541907968 1099435968 11.832 97 10 20 0 1136753376 35462416 1854992
13:06:37 22717 8192000000 10106862720 10526433280 1123874944 12.064 99 43 86 0 1174246480 41989888 8381648
13:08:02 124753 8192000000 10078492368 10531549184 1126368096 12.087 99 22 45 0 1188114672 57309200 4437376
13:11:07 55696 8192000000 10061256080 10563084288 1133688496 12.156 100 4 8 0 1164018608 29607120 722992
13:13:27 61905 8192000000 10080160528 10518454272 1106163488 11.896 97 16 32 0 1151469440 42177168 3128784
13:15:29 73664 8192000000 10049529616 10502664192 1090883344 11.751 97 10 19 1 1128023920 34874032 2266544
13:17:51 36501 8192000000 10119811776 10554638336 1102863040 11.865 97 19 37 1 1165931888 59124176 3944672
13:19:29 80461 8192000000 10069050000 10513035264 1110603648 11.938 98 12 24 1 1151872304 38480304 2788352
13:21:50 31026 8192000000 10112639168 10531631104 1120746656 12.034 99 44 88 0 1191458192 61775936 8935600
13:23:26 123062 8192000000 10094258768 10536595456 1100529072 11.843 98 25 50 1 1151594960 45690288 5375600
13:26:35 45325 8192000000 9400105680 9780441088 524316288 6.015 48 4 8 0 577186736 51973456 896992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2020 13:29:28 to 06-11-2020 13:29:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-11-2020 13:29:44 to 06-11-2020 13:29:46.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231242
MON$OLDEST_ACTIVE               231243
MON$OLDEST_SNAPSHOT             231243
MON$NEXT_TRANSACTION            231243
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2094080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Nov  6 13:27:43 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

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