Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2020 04:27
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 16699488 kB
MemAvailable 31342316 kB
Buffers 20 kB
Cached 14876924 kB
SwapCached 3484 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-16 04:26:51
DTS_END                         2020-11-16 04:26:51.3660
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/49316
TRN_ID                          227202
ATT_ID                          644
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: 8788.87 22149 1054664
customer order: creation 160.32 457 19238
customer order: refuse 13.53 333 1623
order to supplier: creation 42.73 1440 5128
order to supplier: removal 3.58 113102 430
invoice (draft): creation 43.22 3824 5186
invoice (draft): removal 51.83 1034 6220
invoice accept: apply 38.79 17304 4655
invoice accept: cancel 46.21 876 5545
customer reserve: creation 3566.19 168 427943
customer reserve: removal 4554.13 69 546496
realization accept: apply 77.72 194 9327
realization accept: cancel 70.89 137 8507
payment from customer: creation 55.97 119 6716
payment from customer: removal 5.20 97 624
payment to supplier: creation 45.52 140 5462
payment to supplier: removal 3.20 115 384
service: total inventory turnovers 3.59 3581 431
service: total monetary turnovers 5.40 700 648
service: aggregate perf. data 0.81 16942 97
service: refresh index statistics 0.03 282344 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2020 04:27:35 to 16-11-2020 04:27:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3199
TEST_TIME 2 3890
TEST_TIME 3 3625
TEST_TIME 4 3847
TEST_TIME 5 4195
TEST_TIME 6 4545
TEST_TIME 7 4912
TEST_TIME 8 5128
TEST_TIME 9 5182
TEST_TIME 10 5151
TEST_TIME 11 5436
TEST_TIME 12 5812
TEST_TIME 13 6121
TEST_TIME 14 6374
TEST_TIME 15 6816
TEST_TIME 16 7807
TEST_TIME 17 7634
TEST_TIME 18 8167
TEST_TIME 19 7986
TEST_TIME 20 8464
TEST_TIME 21 8473
TEST_TIME 22 8701
TEST_TIME 23 8998
TEST_TIME 24 9516
TEST_TIME 25 9304
TEST_TIME 26 9346
TEST_TIME 27 9458
TEST_TIME 28 9959
TEST_TIME 29 11052
TEST_TIME 30 10782
TEST_TIME 31 10675
TEST_TIME 32 10605
TEST_TIME 33 10440
TEST_TIME 34 10257
TEST_TIME 35 10028
TEST_TIME 36 9980
TEST_TIME 37 9936
TEST_TIME 38 9992
TEST_TIME 39 9865
TEST_TIME 40 9787
TEST_TIME 41 9726
TEST_TIME 42 9579
TEST_TIME 43 9550
TEST_TIME 44 9360
TEST_TIME 45 9374
TEST_TIME 46 9253
TEST_TIME 47 9125
TEST_TIME 48 9053
TEST_TIME 49 9023
TEST_TIME 50 8988
TEST_TIME 51 8916
TEST_TIME 52 8863
TEST_TIME 53 8842
TEST_TIME 54 8877
TEST_TIME 55 8938
TEST_TIME 56 8927
TEST_TIME 57 8913
TEST_TIME 58 8991
TEST_TIME 59 8960
TEST_TIME 60 9102
TEST_TIME 61 9023
TEST_TIME 62 9031
TEST_TIME 63 9081
TEST_TIME 64 9086
TEST_TIME 65 9111
TEST_TIME 66 9052
TEST_TIME 67 9020
TEST_TIME 68 8989
TEST_TIME 69 9017
TEST_TIME 70 8907
TEST_TIME 71 8805
TEST_TIME 72 8769
TEST_TIME 73 8708
TEST_TIME 74 8706
TEST_TIME 75 8660
TEST_TIME 76 8640
TEST_TIME 77 8644
TEST_TIME 78 8658
TEST_TIME 79 8666
TEST_TIME 80 8630
TEST_TIME 81 8585
TEST_TIME 82 8679
TEST_TIME 83 8769
TEST_TIME 84 8800
TEST_TIME 85 8843
TEST_TIME 86 8868
TEST_TIME 87 8801
TEST_TIME 88 8747
TEST_TIME 89 8725
TEST_TIME 90 8739
TEST_TIME 91 8690
TEST_TIME 92 8688
TEST_TIME 93 8722
TEST_TIME 94 8715
TEST_TIME 95 8715
TEST_TIME 96 8703
TEST_TIME 97 8706
TEST_TIME 98 8722
TEST_TIME 99 8691
TEST_TIME 100 8736
TEST_TIME 101 8822
TEST_TIME 102 8836
TEST_TIME 103 8825
TEST_TIME 104 8849
TEST_TIME 105 8913
TEST_TIME 106 8858
TEST_TIME 107 8787
TEST_TIME 108 8746
TEST_TIME 109 8717
TEST_TIME 110 8750
TEST_TIME 111 8712
TEST_TIME 112 8697
TEST_TIME 113 8694
TEST_TIME 114 8682
TEST_TIME 115 8689
TEST_TIME 116 8690
TEST_TIME 117 8687
TEST_TIME 118 8717
TEST_TIME 119 8752
TEST_TIME 120 8795

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2020 04:27:39 to 16-11-2020 04:27:42.

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 1451432 1451432 0 0.00 0 33022 6 0 0
doc_list_biud 1451432 1451432 0 0.00 0 68896 33 0 0
sp_add_invoice_to_stock 4736 4655 81 1.71 20 185394 17305 0 81
sp_cancel_adding_invoice 5545 5545 0 0.00 6 81389 876 0 0
sp_cancel_client_order 1623 1623 0 0.00 4 14832 333 0 0
sp_cancel_customer_reserve 546496 546496 0 0.00 1 37585 70 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 11223 98 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 7856 116 0 0
sp_cancel_supplier_invoice 6226 6220 6 0.09 2 68900 1035 0 6
sp_cancel_supplier_order 430 430 0 0.00 15 1175117 113103 0 0
sp_cancel_write_off 8507 8507 0 0.00 1 14449 137 0 0
sp_client_order 19238 19238 0 0.00 4 29601 458 0 0
sp_customer_reserve 427943 427943 0 0.00 0 61614 169 0 0
sp_fill_shopping_cart 29539 29539 0 0.00 1 66151 54 0 0
sp_fill_shopping_cart_clo_res 427930 427930 0 0.00 0 25894 19 0 0
sp_get_clo_for_invoice 4642 4642 0 0.00 0 34616 462 0 0
sp_kill_cost_storno 15060 15060 0 0.00 0 8265 60 0 0
sp_kill_qstorno_ret_qs2qd 1108149 1108149 0 0.00 0 68895 38 0 0
sp_kill_qty_storno 582790 582790 0 0.00 0 68895 82 0 0
sp_lock_selected_doc 19258 19258 0 0.00 0 7851 4 0 0
sp_make_cost_storno 26147 26147 0 0.00 0 16347 60 0 0
sp_multiply_rows_for_pdistr 26147 26147 0 0.00 0 7264 30 0 0
sp_multiply_rows_for_qdistr 19238 19238 0 0.00 0 14530 265 0 0
sp_pay_from_customer 6716 6716 0 0.00 4 16088 120 0 0
sp_pay_to_supplier 5462 5462 0 0.00 5 16083 141 0 0
sp_payment_common 12178 12178 0 0.00 1 8736 22 0 0
sp_qd_handle_on_cancel_clo 1623 1623 0 0.00 0 14825 301 0 0
sp_qd_handle_on_invoice_upd_sts 10187 10187 0 0.00 0 43256 398 0 0
sp_qd_handle_on_reserve_upd_sts 9327 9327 0 0.00 0 12433 63 0 0
sp_reserve_write_off 9327 9327 0 0.00 6 19591 195 0 0
sp_supplier_invoice 5186 5186 0 0.00 6 94289 3824 0 0
sp_supplier_order 5128 5128 0 0.00 5 36044 1440 0 0
srv_aggregate_perf_data 97 97 0 0.00 3041 162863 16943 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2037 159477 51022 0 0
srv_find_qd_qs_mism 1010529 1010529 0 0.00 0 33022 5 0 0
srv_make_invnt_saldo 431 431 0 0.00 23 110049 3582 0 0
srv_make_money_saldo 648 648 0 0.00 1 17848 700 0 0
srv_recalc_idx_stat 4 4 0 0.00 83776 518552 282345 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 4655 4655 0 0.00 0 12497 143 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 1811 69 0 0
t$perf-norm:sp_cancel_client_order 1623 1623 0 0.00 0 1428 9 0 0
t$perf-norm:sp_cancel_customer_reserve 583 583 0 0.00 0 2870 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 2351 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 90 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 870 14 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 20906 1536 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 2401 8 0 0
t$perf-norm:sp_client_order 19238 19238 0 0.00 0 6218 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 2376 7 0 0
t$perf-norm:sp_pay_from_customer 6716 6716 0 0.00 0 5036 8 0 0
t$perf-norm:sp_pay_to_supplier 5462 5462 0 0.00 0 15294 11 0 0
t$perf-norm:sp_reserve_write_off 9327 9327 0 0.00 0 6014 7 0 0
t$perf-norm:sp_supplier_invoice 5186 5186 0 0.00 0 2940 16 0 0
t$perf-norm:sp_supplier_order 5128 5128 0 0.00 0 5214 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 78 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 185 12 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 592 9 0 0
t$perf-norm:srv_make_money_saldo 648 648 0 0.00 0 2601 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9446 9284 162 1.71 0 6994 18 0 162
v_all_customers 35038 35038 0 0.00 0 14 1 0 0
v_all_suppliers 28674 28674 0 0.00 0 18 1 0 0
v_all_wares 38476 38476 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 2596 16 0 0
v_cancel_client_order 3246 3246 0 0.00 0 7554 9 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 11113 20 0 0
v_cancel_customer_reserve 1166 1166 0 0.00 0 377 4 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 4433 11 0 0
v_cancel_supplier_invoice 718 706 12 1.67 0 3721 20 0 12
v_cancel_supplier_order 860 860 0 0.00 0 4524 9 0 0
v_cancel_write_off 1846 1846 0 0.00 0 8095 29 0 0
v_max_id_clo_ord 5127 5127 0 0.00 0 1516 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 4754 22 0 0
v_max_id_ord_sup 5174 5174 0 0.00 0 5579 6 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 4102 6 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 3938 4 0 0
v_min_id_clo_ord 5127 5127 0 0.00 0 3826 7 0 0
v_min_id_clo_res 962 962 0 0.00 0 12640 24 0 0
v_min_id_ord_sup 5174 5174 0 0.00 0 6749 10 0 0
v_min_non_paid_invoice 5462 5462 0 0.00 0 8644 16 0 0
v_min_non_paid_realizn 6716 6716 0 0.00 0 8729 14 0 0
v_our_firm 9626 9626 0 0.00 0 13 1 0 0
v_reserve_write_off 18654 18654 0 0.00 0 7314 9 0 0
x_lock_depdocs_on_canc_invoice 11412 11412 0 0.00 0 22306 116 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 5812 346 0 0
x_make_qstorno_1000_1200 5128 5128 0 0.00 2 36004 1353 0 0
x_make_qstorno_1200_2000 5186 5186 0 0.00 3 88422 3608 0 0
x_make_qstorno_2100_3300 427943 427943 0 0.00 0 55017 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2020 04:27:43 to 16-11-2020 04:27:47.

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
01:56:55 3 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:56 21915 8192000000 9705293344 9997983744 785256864 8.747 100 87 174 0 814962528 7918160 21787504
01:59:27 23140 8192000000 9766025712 10071461888 835637104 9.256 100 96 192 0 871654368 12871344 23145920
02:00:54 25349 8192000000 9810230576 10129117184 874924960 9.649 100 84 168 1 918507472 24437376 19145136
02:02:26 9709 8192000000 9869046288 10200678400 915378112 10.050 100 82 164 0 977520704 43812304 18330288
02:03:37 5752 8192000000 9906188016 10251845632 945807344 10.350 100 77 154 0 1014335264 52384752 16143168
02:04:48 5185 8192000000 9955021760 10312462336 983975216 10.723 100 77 154 0 1065902768 65938640 15988912
02:05:55 3680 8192000000 9983402384 10343165952 1012251456 10.997 100 87 171 2 1096978304 67016144 17710704
02:07:00 2365 8192000000 10023342960 10390982656 1038580128 11.251 100 91 183 3 1140947632 83842800 18524704
02:08:05 1515 8192000000 10037576336 10405109760 1057562496 11.433 100 93 186 2 1153027280 76558512 18906272
02:09:08 1368 8192000000 10004875664 10375884800 1063577472 11.491 100 92 187 2 1114504800 30743200 20184128
02:10:10 4712 8192000000 9997645504 10386128896 1070818864 11.560 100 38 77 1 1105667040 26602976 8245200
02:11:16 8617 8192000000 9977795056 10386759680 1076746832 11.616 100 21 42 1 1099320464 17919056 4654576
02:12:25 40566 8192000000 9984381456 10388967424 1082886384 11.675 100 54 107 0 1106718672 11625184 12207104
02:14:09 5279 8192000000 10003336544 10397282304 1086637568 11.711 100 43 83 1 1129866592 34633456 8595568
02:15:16 5952 8192000000 10007288128 10414501888 1101444064 11.851 100 32 65 2 1124124048 15432368 7247616
02:16:27 5885 8192000000 10008133600 10409123840 1087256288 11.717 100 27 49 1 1116478416 24131344 5090784
02:17:39 31229 8192000000 10000857264 10380603392 1058345536 11.441 98 51 101 2 1093607312 24972160 10289616
02:19:11 5985 8192000000 9939149200 10321678336 980975760 10.694 93 51 102 2 1007491872 15280032 11236080
02:20:18 6334 8192000000 9938224000 10313359360 1016178720 11.035 100 48 97 3 1048803568 22176512 10448336
02:21:25 10038 8192000000 9944364992 10340159488 1015419168 11.028 99 33 64 2 1054585648 31458320 7708160
02:22:35 51632 8192000000 9923645472 10326630400 992026624 10.801 96 35 69 1 1026606480 27082896 7496960
02:24:27 6667 8192000000 9959235424 10346151936 1048519520 11.346 100 34 68 2 1070321472 13431568 8370384
02:25:35 9796 8192000000 9968326576 10364932096 1058939808 11.446 100 35 70 1 1081958416 14355680 8662928
02:26:45 6996 8192000000 9992327024 10392969216 1081356368 11.660 100 32 64 2 1114615520 25883952 7375200
02:27:59 83779 8192000000 10028925744 10431574016 1097480096 11.814 100 33 66 1 1141286608 37013120 6793392
02:30:28 22339 8192000000 10105398112 10505875456 1112875152 11.960 100 52 105 0 1212250768 88530752 10844864
02:31:54 4627 8192000000 10127146176 10523201536 1130889200 12.130 100 72 144 1 1237716704 90941648 15885856
02:33:01 9504 8192000000 10143075072 10550587392 1137501760 12.192 100 54 108 2 1252658928 103135408 12021760
02:34:18 14599 8192000000 10177178288 10587451392 1152325776 12.331 100 48 96 2 1275925952 112574464 11025712
02:35:40 5489 8192000000 10227028752 10634133504 1163367936 12.435 100 52 103 4 1337549824 162835264 11346624
02:36:53 7823 8192000000 10277055344 10683678720 1169679584 12.494 100 58 113 1 1375270976 192841984 12749408
02:38:05 3320 8192000000 10317487456 10732560384 1162864976 12.430 99 81 163 0 1411765696 230203632 18697088
02:39:12 2704 8192000000 10320703216 10737561600 1176827280 12.561 100 72 143 1 1424104064 231126736 16150048
02:40:23 8199 8192000000 10338451248 10763898880 1178901280 12.580 100 63 126 1 1440158672 246846512 14410880
02:41:37 2037 8192000000 10350692544 10774552576 1160904000 12.412 100 75 149 1 1452819728 273232320 18683408
02:42:46 9534 8192000000 10272557792 10704781312 1119750608 12.025 98 38 76 0 1380577744 251217424 9609712
02:44:09 2662 8192000000 10279312288 10680692736 1116296496 11.992 100 69 138 2 1378409280 245660560 16452224
02:45:18 5769 8192000000 10282827536 10692194304 1122103824 12.047 100 51 100 1 1382349472 248215648 12030000
02:46:32 3929 8192000000 10327863808 10737688576 1136880576 12.186 100 58 116 1 1428148816 277873824 13394416
02:47:47 13547 8192000000 10318603344 10741731328 1090900912 11.751 97 41 84 1 1402719680 301274864 10543904
02:49:07 6196 8192000000 10353161232 10770075648 1132386928 12.144 100 44 87 0 1456210640 312851920 10971792
02:50:15 9060 8192000000 10314461856 10776408064 1128710656 12.109 99 41 83 1 1416067104 276565648 10790800
02:51:32 14278 8192000000 10325850912 10762018816 1146335744 12.275 100 26 52 1 1412683680 259740976 6606960
02:53:06 29994 8192000000 10357407504 10822656000 1127640832 12.099 100 26 52 1 1483163968 347842576 7680560
02:54:37 48054 8192000000 10186247824 10735878144 1112668224 11.958 99 22 44 1 1376350192 257904960 5777008
02:57:04 40993 8192000000 10099932000 10545664000 1114947840 11.979 99 34 68 0 1206593888 84354992 7291056
02:58:49 27274 8192000000 10061419792 10498367488 1125758784 12.081 100 37 71 3 1161029216 27465904 7804528
03:00:33 62966 8192000000 10107426848 10547691520 1130947680 12.130 99 18 36 0 1197879600 62872736 4059184
03:02:37 71783 8192000000 10111069568 10608418816 1134336960 12.162 99 15 30 0 1237928320 99996752 3594608
03:04:52 34116 8192000000 10064622816 10494431232 1134266624 12.162 99 28 56 3 1159629952 18775888 6587440
03:06:30 104470 8192000000 10083895168 10510106624 1126740832 12.091 98 29 60 1 1164799184 32345776 5712576
03:09:18 121519 8192000000 10069195104 10530639872 1131915888 12.139 99 26 51 1 1230417136 92539344 5961904
03:12:22 75525 8192000000 10061933552 10502201344 1144787968 12.261 100 20 40 1 1168122288 19526720 3807600
03:14:49 22476 8192000000 10119318720 10553053184 1144195376 12.255 99 39 78 0 1194894352 41827392 8871584
03:16:19 50378 8192000000 10120371920 10594226176 1150690256 12.316 100 16 31 1 1219213824 65037632 3485936
03:18:09 85535 8192000000 10145717792 10619760640 1156392224 12.369 100 17 34 0 1267172512 106929392 3850896
03:20:38 120181 8192000000 10072628464 10550910976 1121311984 12.039 97 8 16 0 1160280400 37394784 1573632
03:23:46 79149 8192000000 10096211648 10558246912 1143846496 12.252 99 16 33 0 1211696144 64377872 3471776
03:26:19 77396 8192000000 10102510608 10579116032 1145826544 12.270 99 20 42 1 1224751152 74914240 4010368
03:28:40 65067 8192000000 10112187792 10556461056 1123175664 12.057 98 22 45 1 1178775696 50391424 5208608
03:30:46 90351 8192000000 10071304960 10509582336 1114793792 11.978 97 8 16 1 1162610832 46472912 1344128
03:33:17 85053 8192000000 10090229872 10544259072 1109462592 11.927 97 15 30 0 1163119376 50829312 2827472
03:35:46 150849 8192000000 10062450880 10527551488 1127669056 12.099 100 23 46 0 1168081664 35696080 4716528
03:39:28 43162 8192000000 10042783888 10506027008 1106818000 11.902 98 15 30 1 1147471728 37472768 3180960
03:41:28 76843 8192000000 10071958560 10520920064 1096341472 11.803 97 10 20 1 1140558032 42237152 1979408
03:43:53 74704 8192000000 10093117488 10554445824 1074306224 11.593 96 20 40 2 1149109712 70790272 4013216
03:46:08 58286 8192000000 10118145152 10543349760 1077062096 11.619 96 21 42 2 1154755872 73368960 4324816
03:48:06 43806 8192000000 10107846288 10550902784 1110719216 11.939 99 23 46 1 1235411600 119314160 5378224
03:50:01 84064 8192000000 10109743872 10549956608 1122003312 12.046 100 25 50 2 1168566288 41188176 5374800
03:52:25 53393 8192000000 10105985216 10577043456 1108664320 11.920 99 28 56 1 1204576592 89906848 6005424
03:54:19 79928 8192000000 10099027728 10585104384 1117690032 12.005 100 17 34 0 1217444400 95998832 3755536
03:59:12 90259 8192000000 10078494896 10514759680 1096718736 11.806 98 14 27 2 1131170896 31414736 3037424
04:01:49 56883 8192000000 10091181088 10535723008 1133254672 12.152 100 27 54 1 1179886272 40650752 5980848
04:03:46 51960 8192000000 10122932112 10557018112 1140720576 12.222 100 22 44 1 1215453408 69937680 4795152
04:05:39 34175 8192000000 10143964624 10587406336 1146694288 12.278 99 40 80 1 1217205600 62043856 8467456
04:07:17 123295 8192000000 10081977168 10552532992 1116065920 11.990 97 24 46 2 1153927376 33222176 4639280
04:10:25 79762 8192000000 10099872160 10621251584 1126108320 12.085 98 35 70 0 1203497424 70334224 7054880
04:12:58 159476 8192000000 10130024288 10629390336 1155940400 12.365 100 15 30 0 1247695376 88148288 3606688
04:16:38 49392 8192000000 10089578992 10563043328 1132389360 12.144 98 8 16 1 1171734304 37556992 1787952
04:18:35 39102 8192000000 10125452672 10575179776 1100785872 11.845 96 33 65 1 1153343680 45851280 6706528
04:20:26 63000 8192000000 10125072160 10597732352 1141947152 12.234 99 19 37 1 1214673776 68366176 4360448
04:22:44 89694 8192000000 10110622448 10593505280 1107901520 11.913 97 15 30 1 1184881216 73734704 3244992
04:25:23 30566 8192000000 10118310960 10584350720 1121049248 12.037 99 37 72 1 1224103984 95810304 7244432

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-11-2020 04:27:49 to 16-11-2020 04:28:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 16-11-2020 04:28:04 to 16-11-2020 04:28:06.

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          224269
MON$OLDEST_ACTIVE               227354
MON$OLDEST_SNAPSHOT             227354
MON$NEXT_TRANSACTION            227354
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                       2099520
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             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Mon Nov 16 04:26:52 2020
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Mon Nov 16 04:27:01 2020
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
--- end of diff output ---

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

16.11.20 04:28:07. 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-16 04:28:10 No severe PSQL-related problems occured
16.11.20 04:28:11. End of report.