Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.12.2020 07: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 17020272 kB
MemAvailable 31477364 kB
Buffers 0 kB
Cached 14557980 kB
SwapCached 1972 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-12-06 07:26:48
DTS_END                         2020-12-06 07:26:48.9670
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(68:17)
IP                              ::1/33402
TRN_ID                          238130
ATT_ID                          647
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8796.79 21570 1055615
customer order: creation 172.38 457 20686
customer order: refuse 14.19 338 1703
order to supplier: creation 44.30 1435 5316
order to supplier: removal 3.26 106696 391
invoice (draft): creation 46.65 3393 5598
invoice (draft): removal 48.39 961 5807
invoice accept: apply 41.73 17481 5007
invoice accept: cancel 43.52 820 5222
customer reserve: creation 3796.85 173 455622
customer reserve: removal 4294.96 63 515395
realization accept: apply 83.30 160 9996
realization accept: cancel 79.67 127 9560
payment from customer: creation 59.46 115 7135
payment from customer: removal 5.23 62 627
payment to supplier: creation 48.85 136 5862
payment to supplier: removal 3.23 94 387
service: total inventory turnovers 3.99 2926 479
service: total monetary turnovers 5.94 496 713
service: aggregate perf. data 0.88 15764 105
service: refresh index statistics 0.03 279708 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-12-2020 07:27:42 to 06-12-2020 07:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2123
TEST_TIME 2 2835
TEST_TIME 3 3622
TEST_TIME 4 4756
TEST_TIME 5 4897
TEST_TIME 6 4807
TEST_TIME 7 4863
TEST_TIME 8 4952
TEST_TIME 9 5199
TEST_TIME 10 5226
TEST_TIME 11 5582
TEST_TIME 12 5938
TEST_TIME 13 6391
TEST_TIME 14 6638
TEST_TIME 15 6707
TEST_TIME 16 7065
TEST_TIME 17 7501
TEST_TIME 18 8297
TEST_TIME 19 8349
TEST_TIME 20 8252
TEST_TIME 21 8582
TEST_TIME 22 8966
TEST_TIME 23 10300
TEST_TIME 24 10769
TEST_TIME 25 10548
TEST_TIME 26 10472
TEST_TIME 27 10264
TEST_TIME 28 10228
TEST_TIME 29 10004
TEST_TIME 30 9822
TEST_TIME 31 9935
TEST_TIME 32 9733
TEST_TIME 33 9630
TEST_TIME 34 9541
TEST_TIME 35 9578
TEST_TIME 36 9481
TEST_TIME 37 9382
TEST_TIME 38 9228
TEST_TIME 39 9183
TEST_TIME 40 9169
TEST_TIME 41 9131
TEST_TIME 42 9236
TEST_TIME 43 9175
TEST_TIME 44 9260
TEST_TIME 45 9423
TEST_TIME 46 9480
TEST_TIME 47 9579
TEST_TIME 48 9532
TEST_TIME 49 9460
TEST_TIME 50 9431
TEST_TIME 51 9266
TEST_TIME 52 9202
TEST_TIME 53 9145
TEST_TIME 54 9038
TEST_TIME 55 8966
TEST_TIME 56 8930
TEST_TIME 57 8893
TEST_TIME 58 8967
TEST_TIME 59 9031
TEST_TIME 60 9005
TEST_TIME 61 9007
TEST_TIME 62 9020
TEST_TIME 63 9118
TEST_TIME 64 9124
TEST_TIME 65 9168
TEST_TIME 66 9209
TEST_TIME 67 9267
TEST_TIME 68 9307
TEST_TIME 69 9279
TEST_TIME 70 9222
TEST_TIME 71 9112
TEST_TIME 72 9054
TEST_TIME 73 8974
TEST_TIME 74 8934
TEST_TIME 75 8904
TEST_TIME 76 8878
TEST_TIME 77 8890
TEST_TIME 78 8829
TEST_TIME 79 8839
TEST_TIME 80 8928
TEST_TIME 81 8966
TEST_TIME 82 8937
TEST_TIME 83 8974
TEST_TIME 84 8968
TEST_TIME 85 8980
TEST_TIME 86 8960
TEST_TIME 87 8919
TEST_TIME 88 8852
TEST_TIME 89 8844
TEST_TIME 90 8784
TEST_TIME 91 8792
TEST_TIME 92 8798
TEST_TIME 93 8789
TEST_TIME 94 8775
TEST_TIME 95 8814
TEST_TIME 96 8791
TEST_TIME 97 8830
TEST_TIME 98 8872
TEST_TIME 99 8842
TEST_TIME 100 8880
TEST_TIME 101 8870
TEST_TIME 102 8901
TEST_TIME 103 8922
TEST_TIME 104 8973
TEST_TIME 105 8955
TEST_TIME 106 8879
TEST_TIME 107 8835
TEST_TIME 108 8801
TEST_TIME 109 8752
TEST_TIME 110 8718
TEST_TIME 111 8740
TEST_TIME 112 8729
TEST_TIME 113 8721
TEST_TIME 114 8717
TEST_TIME 115 8735
TEST_TIME 116 8743
TEST_TIME 117 8809
TEST_TIME 118 8807
TEST_TIME 119 8779
TEST_TIME 120 8801

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2020 07:27:46 to 06-12-2020 07:27:49.

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 1477021 1477021 0 0.00 0 17021 7 0 0
doc_list_biud 1477021 1477021 0 0.00 0 57798 29 0 0
sp_add_invoice_to_stock 5113 5007 106 2.07 33 168804 17482 0 106
sp_cancel_adding_invoice 5222 5222 0 0.00 5 67596 820 0 0
sp_cancel_client_order 1703 1703 0 0.00 4 22243 339 0 0
sp_cancel_customer_reserve 515395 515395 0 0.00 1 55794 64 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 2629 63 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 3781 95 0 0
sp_cancel_supplier_invoice 5813 5807 6 0.10 2 57808 961 0 6
sp_cancel_supplier_order 391 391 0 0.00 7 1044436 106696 0 0
sp_cancel_write_off 9560 9560 0 0.00 1 15135 127 0 0
sp_client_order 20686 20686 0 0.00 4 48835 458 0 0
sp_customer_reserve 455622 455622 0 0.00 0 106607 174 0 0
sp_fill_shopping_cart 31596 31596 0 0.00 1 31060 49 0 0
sp_fill_shopping_cart_clo_res 455617 455617 0 0.00 0 20124 18 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 41173 429 0 0
sp_kill_cost_storno 15796 15796 0 0.00 0 5191 55 0 0
sp_kill_qstorno_ret_qs2qd 1046548 1046548 0 0.00 0 57796 35 0 0
sp_kill_qty_storno 553076 553076 0 0.00 0 57798 75 0 0
sp_lock_selected_doc 20635 20635 0 0.00 0 9439 4 0 0
sp_make_cost_storno 27995 27995 0 0.00 0 15641 56 0 0
sp_multiply_rows_for_pdistr 27995 27995 0 0.00 0 7290 30 0 0
sp_multiply_rows_for_qdistr 20686 20686 0 0.00 0 48666 271 0 0
sp_pay_from_customer 7135 7135 0 0.00 4 20175 115 0 0
sp_pay_to_supplier 5862 5862 0 0.00 4 16881 136 0 0
sp_payment_common 12997 12997 0 0.00 1 20169 24 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 22237 315 0 0
sp_qd_handle_on_invoice_upd_sts 10224 10224 0 0.00 0 49835 346 0 0
sp_qd_handle_on_reserve_upd_sts 9996 9996 0 0.00 0 9015 41 0 0
sp_reserve_write_off 9996 9996 0 0.00 7 19443 160 0 0
sp_supplier_invoice 5598 5598 0 0.00 6 123810 3393 0 0
sp_supplier_order 5316 5316 0 0.00 6 42587 1435 0 0
srv_aggregate_perf_data 105 105 0 0.00 2656 262004 15764 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1628 182051 51894 0 0
srv_find_qd_qs_mism 1008046 1008046 0 0.00 0 13454 6 0 0
srv_make_invnt_saldo 479 479 0 0.00 49 120810 2927 0 0
srv_make_money_saldo 713 713 0 0.00 1 13206 497 0 0
srv_recalc_idx_stat 4 4 0 0.00 163148 537576 279708 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 5007 5007 0 0.00 0 18880 159 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 2368 62 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 1583 11 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 856 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 203 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 380 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 6616 34 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 24396 1298 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 444 3 0 0
t$perf-norm:sp_client_order 20686 20686 0 0.00 0 10188 17 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 18238 31 0 0
t$perf-norm:sp_pay_from_customer 7135 7135 0 0.00 0 7147 8 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 8801 8 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 8460 11 0 0
t$perf-norm:sp_supplier_invoice 5598 5598 0 0.00 0 8785 16 0 0
t$perf-norm:sp_supplier_order 5316 5316 0 0.00 0 8073 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1953 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1086 22 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 829 7 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 756 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10216 10004 212 2.07 0 5229 15 0 212
v_all_customers 37656 37656 0 0.00 0 32 1 0 0
v_all_suppliers 30152 30152 0 0.00 0 154 1 0 0
v_all_wares 41370 41370 0 0.00 0 132 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 169 5 0 0
v_cancel_client_order 3406 3406 0 0.00 0 3041 6 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 578 4 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 3054 5 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 3279 13 0 0
v_cancel_supplier_invoice 782 770 12 1.53 0 1214 12 0 12
v_cancel_supplier_order 782 782 0 0.00 0 1194 5 0 0
v_cancel_write_off 2036 2036 0 0.00 0 1367 12 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 3250 2 0 0
v_max_id_clo_res 973 973 0 0.00 0 2571 22 0 0
v_max_id_ord_sup 5596 5596 0 0.00 0 6024 9 0 0
v_max_non_paid_invoice 1697 1697 0 0.00 0 380 2 0 0
v_max_non_paid_realizn 3849 3849 0 0.00 0 1956 4 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 6842 5 0 0
v_min_id_clo_res 973 973 0 0.00 0 6432 25 0 0
v_min_id_ord_sup 5596 5596 0 0.00 0 5432 9 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 5256 17 0 0
v_min_non_paid_realizn 7135 7135 0 0.00 0 5136 14 0 0
v_our_firm 10286 10286 0 0.00 0 28 1 0 0
v_reserve_write_off 19992 19992 0 0.00 0 4098 5 0 0
x_lock_depdocs_on_canc_invoice 10644 10644 0 0.00 0 29011 121 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 13451 305 0 0
x_make_qstorno_1000_1200 5316 5316 0 0.00 2 42507 1367 0 0
x_make_qstorno_1200_2000 5598 5598 0 0.00 3 123679 3191 0 0
x_make_qstorno_2100_3300 455622 455622 0 0.00 0 106605 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2020 07:27:50 to 06-12-2020 07:27:54.

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
04:56:52 6 8192000000 8796641392 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:53 23710 8192000000 9701990752 9996464128 782020800 8.714 100 80 160 0 809223088 6935664 20266624
04:59:22 19627 8192000000 9755992528 10059984896 824651344 9.145 100 88 176 0 857414352 11575696 21187312
05:00:44 12255 8192000000 9789814176 10103320576 860956144 9.510 100 90 180 0 900021008 17792656 21272208
05:02:00 16397 8192000000 9853473968 10184630272 915221744 10.049 100 77 154 1 961932384 29690144 17020496
05:03:21 6603 8192000000 9911782288 10252886016 956749328 10.457 100 87 174 2 1027940960 53286432 17905200
05:04:30 6322 8192000000 9961922416 10315235328 988951200 10.771 100 86 172 0 1082147168 75837664 17358304
05:05:39 1872 8192000000 10008012304 10369675264 1017377072 11.047 100 87 173 0 1121346208 86992112 16977024
05:06:44 1448 8192000000 10011922768 10381496320 1044233600 11.305 100 86 170 0 1133063680 71893920 16936160
05:07:47 26376 8192000000 10014978352 10397585408 1058617312 11.443 100 69 138 1 1161471760 88729328 14125120
05:09:14 5283 8192000000 9974801584 10373820416 1064869728 11.503 100 34 68 1 1099891440 27422784 7598928
05:10:23 6610 8192000000 9989770928 10398859264 1074444304 11.595 100 29 59 1 1111176480 30788848 5943328
05:11:33 71167 8192000000 9984436304 10388623360 1082827456 11.674 100 26 52 0 1108819424 20177184 5814784
05:13:45 6718 8192000000 10010926368 10418212864 1089959376 11.742 100 41 77 4 1125819744 27337968 8522400
05:14:56 6815 8192000000 9999505552 10398367744 1084131872 11.687 99 33 66 0 1110915872 18967408 7816592
05:16:03 6639 8192000000 10006897056 10411630592 1086196944 11.706 99 26 52 4 1109059680 17020528 5842208
05:17:12 42053 8192000000 9995796864 10402889728 1034565888 11.212 94 42 81 2 1060276912 16924144 8786880
05:18:55 7240 8192000000 9963891984 10351353856 1012556544 11.000 95 46 93 2 1051602912 29019104 10027264
05:20:02 6496 8192000000 9985357184 10371682304 1038992832 11.255 99 51 101 0 1066216304 15723696 11499776
05:21:13 12512 8192000000 9948007024 10339635200 1002801152 10.906 98 28 58 2 1022262048 12551344 6909552
05:22:27 28995 8192000000 9945590432 10347532288 1005981120 10.936 97 16 32 2 1040561456 31513744 3066592
05:23:57 8610 8192000000 9978298576 10379747328 1049648032 11.357 100 52 101 3 1086103536 24944800 11510704
05:25:08 6006 8192000000 9968888608 10367414272 1062732496 11.483 100 43 84 1 1097513744 24976400 9804848
05:26:15 6929 8192000000 9989569984 10392190976 1086296992 11.707 100 31 59 2 1106893824 12850368 7746464
05:27:23 79740 8192000000 10021011744 10427113472 1093713792 11.778 99 27 53 1 1133159248 34088480 5356976
05:29:48 8042 8192000000 10093958624 10494738432 1120411104 12.031 100 56 110 3 1197977104 65787808 11778192
05:30:59 14262 8192000000 10127138704 10532352000 1141672064 12.231 100 54 108 0 1228217184 74986880 11558240
05:32:16 7662 8192000000 10158676368 10566995968 1148942000 12.300 100 53 104 2 1268192144 108347328 10902816
05:33:27 7874 8192000000 10190150000 10612273152 1163926528 12.440 100 43 86 0 1297649936 123706032 10017376
05:34:52 7078 8192000000 10259054304 10684108800 1176734160 12.560 100 59 116 2 1372944512 183314992 12895360
05:36:07 3813 8192000000 10300684944 10709454848 1191560368 12.698 100 61 120 1 1403959024 199367952 13030704
05:37:15 1628 8192000000 10343036720 10755137536 1190615968 12.689 100 71 137 1 1442991488 236613360 15762160
05:38:19 2224 8192000000 10367858800 10775326720 1176144848 12.554 99 74 148 0 1467636368 274658192 16833328
05:39:25 5287 8192000000 10383241840 10795274240 1176326112 12.556 100 76 152 0 1492108336 298114560 17667664
05:40:35 4088 8192000000 10362442848 10772480000 1165696016 12.457 99 71 137 3 1460195440 279180288 15319136
05:41:44 3250 8192000000 10417915440 10821992448 1149536896 12.305 98 83 167 2 1500721936 332137760 19047280
05:42:53 2177 8192000000 10394558400 10791895040 1127468928 12.097 100 86 171 0 1491197184 344543424 19184832
05:43:58 8210 8192000000 10363317040 10779860992 1119190304 12.019 99 42 83 2 1478522688 348946816 10385568
05:45:11 14656 8192000000 10343573072 10783318016 1131420512 12.135 100 26 52 1 1443105280 305020016 6664752
05:46:49 21916 8192000000 10401453456 10850476032 1115576064 11.985 99 35 70 1 1484701856 359997040 9128752
05:48:24 27674 8192000000 10317839776 10745831424 1100203856 11.840 99 27 53 0 1448163472 339562016 8397600
05:50:01 43337 8192000000 10067797360 10546774016 1109600304 11.929 100 8 16 0 1234752480 123083712 2068464
05:51:47 27625 8192000000 10069421776 10498072576 1099802480 11.836 99 31 62 2 1153969616 47486576 6680560
05:53:19 50510 8192000000 10102459328 10525839360 1108970880 11.923 99 23 45 0 1201818560 87242912 5604768
05:55:14 166516 8192000000 10014377840 10452107264 1059685904 11.453 95 15 28 0 1138846784 76380576 2780304
05:59:06 56660 8192000000 10068254096 10505699328 1110944560 11.941 99 22 44 1 1160067776 44284624 4838592
06:01:12 37706 8192000000 10065680752 10512109568 1120147344 12.028 99 16 32 0 1172380448 48875488 3357616
06:03:08 93239 8192000000 10077960576 10517553152 1098923248 11.827 97 11 22 0 1147407936 46401744 2082944
06:06:19 69978 8192000000 10161662160 10586624000 1124647552 12.071 98 24 48 1 1224152096 94692240 4812304
06:08:46 58098 8192000000 10148398976 10592931840 1142905376 12.243 100 17 34 0 1255289728 108553552 3830800
06:10:49 68994 8192000000 10117179232 10576019456 1119715360 12.024 99 14 28 0 1186088016 63285952 3086704
06:13:02 75213 8192000000 10082636384 10558455808 1125273616 12.077 99 6 12 0 1172259440 45835776 1150048
06:15:40 62358 8192000000 10085955920 10530398208 1148768688 12.298 100 30 60 1 1211669328 57182464 5718176
06:17:43 72310 8192000000 10084836880 10543960064 1154717056 12.354 100 27 52 2 1187634640 27811424 5106160
06:20:00 182050 8192000000 10086498528 10562650112 1086490064 11.709 94 15 30 0 1138070752 48703024 2877664
06:24:02 63807 8192000000 10108292608 10592174080 1132294480 12.143 98 20 40 1 1197664848 60775008 4595360
06:26:06 41900 8192000000 10078989840 10507882496 1134441056 12.163 99 20 40 1 1163759632 25085936 4232640
06:27:51 69251 8192000000 10084764464 10519912448 1057564096 11.433 93 17 32 3 1129090432 68167232 3359104
06:30:02 35878 8192000000 10114172688 10531270656 1095197232 11.792 96 41 82 2 1184559344 80448608 8913504
06:31:42 68017 8192000000 10041742912 10487783424 1023167568 11.103 91 13 26 0 1076913296 51259808 2485920
06:35:03 32947 8192000000 10063354992 10492993536 1070048224 11.553 95 37 77 0 1117667152 39274480 8344448
06:36:40 116471 8192000000 10057423984 10503774208 1113569424 11.966 99 23 46 0 1152810272 34422880 4817968
06:39:52 84367 8192000000 10048151248 10501476352 1091955664 11.761 98 13 25 2 1162333152 66957376 3420112
06:42:17 41929 8192000000 10076630848 10505936896 1116165040 11.991 100 27 55 2 1178485536 56649264 5671232
06:43:59 54814 8192000000 10116394384 10543038464 1108359472 11.917 98 32 64 1 1192626496 77063936 7203088
06:45:54 78019 8192000000 10075583808 10507292672 1073264560 11.583 96 21 42 0 1144702464 67199472 4238432
06:48:13 23884 8192000000 10071658480 10492424192 1103801312 11.874 99 59 115 3 1148365648 32352640 12211696
06:49:41 72771 8192000000 10050260800 10491150336 1107607328 11.910 99 29 57 3 1167451296 53845104 5998864
06:51:56 63431 8192000000 10108370528 10537697280 1130449104 12.126 100 27 54 0 1183136672 47101248 5586320
06:54:00 88181 8192000000 10060299232 10546528256 1121765888 12.044 99 15 30 1 1184270608 59239712 3265008
06:56:31 88278 8192000000 10066607056 10545840128 1107974000 11.913 98 8 16 0 1150009984 40557952 1478032
06:59:00 41252 8192000000 10057203568 10504663040 1113402480 11.965 98 17 32 2 1154681728 38014368 3264880
07:00:42 85646 8192000000 10098726608 10577367040 1131941072 12.140 99 17 33 1 1198788512 62996016 3851424
07:03:08 47152 8192000000 10094721184 10574753792 1141254640 12.227 100 23 46 1 1190491248 44163216 5073392
07:05:01 39456 8192000000 10109783328 10539716608 1150306592 12.312 100 47 94 0 1199518832 39794784 9417456
07:06:42 48253 8192000000 10098559104 10549387264 1119933184 12.026 98 26 52 1 1174171408 48398512 5839712
07:08:31 44766 8192000000 10086272672 10558488576 1096538144 11.805 96 24 48 1 1155559888 54297632 4724112
07:11:36 113084 8192000000 10043345216 10485800960 1106838528 11.902 98 27 54 0 1139857152 26965888 6052736
07:14:36 67578 8192000000 10048066368 10509213696 1107659440 11.910 98 12 24 0 1144274048 34277424 2337184
07:16:44 71034 8192000000 10071703344 10541670400 1091724304 11.759 97 13 26 3 1151267216 56824208 2718704
07:18:55 69749 8192000000 10116735792 10565894144 1133552720 12.155 100 23 46 1 1199903248 61398656 4951872
07:21:13 44992 8192000000 10126879840 10595602432 1099868832 11.836 97 35 68 2 1206704496 98871040 7964624
07:23:24 66952 8192000000 10058205376 10531192832 1091692688 11.759 97 12 24 3 1162194384 67996880 2504816
07:25:35 65481 8192000000 10085000160 10557251584 1072192064 11.573 96 39 78 0 1135181856 55023568 7966224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-12-2020 07:27:56 to 06-12-2020 07:28:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 06-12-2020 07:28:11 to 06-12-2020 07:28:13.

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          235822
MON$OLDEST_ACTIVE               238836
MON$OLDEST_SNAPSHOT             238836
MON$NEXT_TRANSACTION            238836
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                       2080736
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             811
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:38: 
:39: mscs-test-161.budzdorov.ru    Sun Dec  6 07:27:13 2020
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
:42: 
:43: mscs-test-161.budzdorov.ru    Sun Dec  6 07:27:14 2020
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
--- end of diff output ---

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

06.12.20 07:28:14. 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-12-06 07:28:17 No severe PSQL-related problems occured
06.12.20 07:28:18. End of report.