Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2020 10: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 17110980 kB
MemAvailable 31466076 kB
Buffers 0 kB
Cached 14459452 kB
SwapCached 1984 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-07 10:26:53
DTS_END                         2020-12-07 10:26:53.2520
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/51070
TRN_ID                          226364
ATT_ID                          602
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: 8690.51 25634 1042861
customer order: creation 160.03 406 19204
customer order: refuse 13.82 331 1658
order to supplier: creation 42.35 1333 5082
order to supplier: removal 3.06 124092 367
invoice (draft): creation 43.50 3448 5220
invoice (draft): removal 48.26 869 5791
invoice accept: apply 39.90 18073 4788
invoice accept: cancel 43.46 873 5215
customer reserve: creation 3719.31 176 446317
customer reserve: removal 4307.08 71 516850
realization accept: apply 77.75 163 9330
realization accept: cancel 70.85 125 8502
payment from customer: creation 56.28 112 6754
payment from customer: removal 5.31 74 637
payment to supplier: creation 46.46 127 5575
payment to supplier: removal 3.05 65 366
service: total inventory turnovers 3.88 3270 465
service: total monetary turnovers 5.43 638 652
service: aggregate perf. data 0.70 20547 84
service: refresh index statistics 0.03 337890 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-12-2020 10:27:41 to 07-12-2020 10:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1029
TEST_TIME 2 1684
TEST_TIME 3 3322
TEST_TIME 4 3523
TEST_TIME 5 4043
TEST_TIME 6 4520
TEST_TIME 7 5043
TEST_TIME 8 5002
TEST_TIME 9 5439
TEST_TIME 10 5557
TEST_TIME 11 5697
TEST_TIME 12 5810
TEST_TIME 13 5723
TEST_TIME 14 6621
TEST_TIME 15 6674
TEST_TIME 16 6729
TEST_TIME 17 7202
TEST_TIME 18 7663
TEST_TIME 19 7617
TEST_TIME 20 8129
TEST_TIME 21 8104
TEST_TIME 22 8431
TEST_TIME 23 9030
TEST_TIME 24 9363
TEST_TIME 25 9642
TEST_TIME 26 11323
TEST_TIME 27 11152
TEST_TIME 28 10877
TEST_TIME 29 10663
TEST_TIME 30 10526
TEST_TIME 31 10275
TEST_TIME 32 10116
TEST_TIME 33 9980
TEST_TIME 34 10152
TEST_TIME 35 9962
TEST_TIME 36 9817
TEST_TIME 37 9632
TEST_TIME 38 9628
TEST_TIME 39 9618
TEST_TIME 40 9575
TEST_TIME 41 9447
TEST_TIME 42 9442
TEST_TIME 43 9341
TEST_TIME 44 9259
TEST_TIME 45 9269
TEST_TIME 46 9241
TEST_TIME 47 9223
TEST_TIME 48 9217
TEST_TIME 49 9323
TEST_TIME 50 9266
TEST_TIME 51 9224
TEST_TIME 52 9254
TEST_TIME 53 9298
TEST_TIME 54 9366
TEST_TIME 55 9340
TEST_TIME 56 9242
TEST_TIME 57 9094
TEST_TIME 58 9008
TEST_TIME 59 9003
TEST_TIME 60 8895
TEST_TIME 61 8965
TEST_TIME 62 8907
TEST_TIME 63 8906
TEST_TIME 64 8894
TEST_TIME 65 8920
TEST_TIME 66 8949
TEST_TIME 67 8976
TEST_TIME 68 8945
TEST_TIME 69 9038
TEST_TIME 70 9038
TEST_TIME 71 9021
TEST_TIME 72 8976
TEST_TIME 73 9017
TEST_TIME 74 9012
TEST_TIME 75 9154
TEST_TIME 76 9045
TEST_TIME 77 8948
TEST_TIME 78 8904
TEST_TIME 79 8851
TEST_TIME 80 8807
TEST_TIME 81 8811
TEST_TIME 82 8798
TEST_TIME 83 8782
TEST_TIME 84 8770
TEST_TIME 85 8790
TEST_TIME 86 8767
TEST_TIME 87 8746
TEST_TIME 88 8734
TEST_TIME 89 8832
TEST_TIME 90 8844
TEST_TIME 91 8927
TEST_TIME 92 8903
TEST_TIME 93 8825
TEST_TIME 94 8801
TEST_TIME 95 8762
TEST_TIME 96 8702
TEST_TIME 97 8715
TEST_TIME 98 8714
TEST_TIME 99 8670
TEST_TIME 100 8687
TEST_TIME 101 8680
TEST_TIME 102 8726
TEST_TIME 103 8792
TEST_TIME 104 8761
TEST_TIME 105 8762
TEST_TIME 106 8811
TEST_TIME 107 8932
TEST_TIME 108 8907
TEST_TIME 109 8885
TEST_TIME 110 8814
TEST_TIME 111 8788
TEST_TIME 112 8750
TEST_TIME 113 8700
TEST_TIME 114 8672
TEST_TIME 115 8670
TEST_TIME 116 8659
TEST_TIME 117 8686
TEST_TIME 118 8688
TEST_TIME 119 8672
TEST_TIME 120 8691

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-12-2020 10:27:45 to 07-12-2020 10: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 1453953 1453953 0 0.00 0 32004 7 0 0
doc_list_biud 1453953 1453953 0 0.00 0 72430 31 0 0
sp_add_invoice_to_stock 4890 4788 102 2.08 45 186809 18074 0 102
sp_cancel_adding_invoice 5215 5215 0 0.00 6 89049 873 0 0
sp_cancel_client_order 1658 1658 0 0.00 4 13144 331 0 0
sp_cancel_customer_reserve 516850 516850 0 0.00 1 72432 71 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 2928 75 0 0
sp_cancel_pay_to_supplier 366 366 0 0.00 3 2986 66 0 0
sp_cancel_supplier_invoice 5801 5791 10 0.17 2 19752 869 0 10
sp_cancel_supplier_order 367 367 0 0.00 9 1153454 124092 0 0
sp_cancel_write_off 8502 8502 0 0.00 1 12222 125 0 0
sp_client_order 19204 19204 0 0.00 4 21317 406 0 0
sp_customer_reserve 446317 446317 0 0.00 0 123502 176 0 0
sp_fill_shopping_cart 29494 29494 0 0.00 1 22916 50 0 0
sp_fill_shopping_cart_clo_res 446316 446316 0 0.00 0 22433 20 0 0
sp_get_clo_for_invoice 4787 4787 0 0.00 0 33920 411 0 0
sp_kill_cost_storno 14720 14720 0 0.00 0 31990 61 0 0
sp_kill_qstorno_ret_qs2qd 1048360 1048360 0 0.00 0 72427 38 0 0
sp_kill_qty_storno 552499 552499 0 0.00 0 72430 80 0 0
sp_lock_selected_doc 19594 19594 0 0.00 0 8370 5 0 0
sp_make_cost_storno 26445 26445 0 0.00 0 9776 52 0 0
sp_multiply_rows_for_pdistr 26445 26445 0 0.00 0 7950 28 0 0
sp_multiply_rows_for_qdistr 19204 19204 0 0.00 0 15668 215 0 0
sp_pay_from_customer 6754 6754 0 0.00 4 14812 112 0 0
sp_pay_to_supplier 5575 5575 0 0.00 5 12594 128 0 0
sp_payment_common 12329 12329 0 0.00 1 6760 22 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 13133 301 0 0
sp_qd_handle_on_invoice_upd_sts 10001 10001 0 0.00 0 33634 337 0 0
sp_qd_handle_on_reserve_upd_sts 9330 9330 0 0.00 0 8798 49 0 0
sp_reserve_write_off 9330 9330 0 0.00 7 12813 163 0 0
sp_supplier_invoice 5220 5220 0 0.00 7 103551 3448 0 0
sp_supplier_order 5082 5082 0 0.00 5 33893 1333 0 0
srv_aggregate_perf_data 84 84 0 0.00 2722 236389 20548 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2188 167182 49643 0 0
srv_find_qd_qs_mism 996566 996566 0 0.00 0 19042 6 0 0
srv_make_invnt_saldo 465 465 0 0.00 35 92551 3271 0 0
srv_make_money_saldo 652 652 0 0.00 1 22750 638 0 0
srv_recalc_idx_stat 4 4 0 0.00 128955 600506 337890 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4788 4788 0 0.00 0 17876 133 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 4369 103 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 2182 12 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 4817 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 7263 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 366 366 0 0.00 0 2761 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 256 8 0 0
t$perf-norm:sp_cancel_supplier_order 367 367 0 0.00 0 17154 1489 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 578 3 0 0
t$perf-norm:sp_client_order 19204 19204 0 0.00 0 4884 15 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 3262 7 0 0
t$perf-norm:sp_pay_from_customer 6754 6754 0 0.00 0 6722 8 0 0
t$perf-norm:sp_pay_to_supplier 5575 5575 0 0.00 0 9423 11 0 0
t$perf-norm:sp_reserve_write_off 9330 9330 0 0.00 0 3667 8 0 0
t$perf-norm:sp_supplier_invoice 5220 5220 0 0.00 0 7580 16 0 0
t$perf-norm:sp_supplier_order 5082 5082 0 0.00 0 4376 16 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 223 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 167 4 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 179 3 0 0
t$perf-norm:srv_make_money_saldo 652 652 0 0.00 0 235 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9776 9572 204 2.08 0 7490 16 0 204
v_all_customers 35468 35468 0 0.00 0 16 1 0 0
v_all_suppliers 28674 28674 0 0.00 0 15 1 0 0
v_all_wares 38408 38408 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2385 12 0 0
v_cancel_client_order 3316 3316 0 0.00 0 1834 5 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 2474 5 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 2676 7 0 0
v_cancel_payment_to_supplier 732 732 0 0.00 0 868 5 0 0
v_cancel_supplier_invoice 798 778 20 2.50 0 277 11 0 20
v_cancel_supplier_order 734 734 0 0.00 0 1357 5 0 0
v_cancel_write_off 2006 2006 0 0.00 0 5900 17 0 0
v_max_id_clo_ord 5082 5082 0 0.00 0 96 1 0 0
v_max_id_clo_res 938 938 0 0.00 0 1504 8 0 0
v_max_id_ord_sup 5208 5208 0 0.00 0 9832 8 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 2633 4 0 0
v_max_non_paid_realizn 3444 3444 0 0.00 0 2267 3 0 0
v_min_id_clo_ord 5082 5082 0 0.00 0 2818 6 0 0
v_min_id_clo_res 938 938 0 0.00 0 1884 8 0 0
v_min_id_ord_sup 5208 5208 0 0.00 0 6604 11 0 0
v_min_non_paid_invoice 5575 5575 0 0.00 0 6753 14 0 0
v_min_non_paid_realizn 6754 6754 0 0.00 0 6407 15 0 0
v_our_firm 9560 9560 0 0.00 0 11 1 0 0
v_reserve_write_off 18660 18660 0 0.00 0 4949 5 0 0
x_lock_depdocs_on_canc_invoice 10617 10617 0 0.00 0 15994 120 0 0
x_lock_depdocs_on_canc_sup_ord 367 367 0 0.00 0 11299 353 0 0
x_make_qstorno_1000_1200 5082 5082 0 0.00 2 33864 1262 0 0
x_make_qstorno_1200_2000 5220 5220 0 0.00 3 103058 3237 0 0
x_make_qstorno_2100_3300 446317 446317 0 0.00 0 123500 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-12-2020 10:27:49 to 07-12-2020 10: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
07:56:56 8 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:57 32603 8192000000 9723359712 10015178752 793925600 8.835 100 93 186 0 824359024 7997184 22436240
07:59:37 19457 8192000000 9748382864 10051526656 823678192 9.136 100 86 172 0 857785264 13538512 20568560
08:01:02 21882 8192000000 9803732528 10124394496 862807312 9.528 100 75 150 2 906496032 26843312 16845408
08:02:35 11277 8192000000 9888291472 10220650496 928499792 10.180 100 86 168 1 996682560 50095728 18087040
08:03:51 12740 8192000000 9932281232 10277310464 962043584 10.509 100 80 160 2 1038936000 60274656 16617760
08:05:07 6883 8192000000 9972278160 10326601728 999642224 10.875 100 86 172 2 1084679968 68152288 16885456
08:06:15 2594 8192000000 10001703872 10364350464 1023521760 11.106 100 83 167 0 1123832544 83762448 16548336
08:07:19 3026 8192000000 10008155232 10377650176 1044056768 11.304 100 95 191 0 1131599872 67950368 19592736
08:08:23 2364 8192000000 10026269424 10397605888 1059881728 11.455 100 85 169 1 1141031424 63293792 17855904
08:09:30 5375 8192000000 10030498656 10406989824 1070178512 11.554 100 84 168 2 1146749776 59133872 17437392
08:10:36 7033 8192000000 10000167152 10408980480 1071564512 11.567 100 28 56 1 1121321120 44411280 5345328
08:11:46 9957 8192000000 9986148912 10395906048 1081223520 11.659 100 21 42 3 1110426272 24784064 4418688
08:12:58 12826 8192000000 9988850432 10400624640 1083344448 11.679 100 22 41 2 1103075552 14488784 5242320
08:14:12 18517 8192000000 10020597488 10413539328 1087380832 11.718 100 66 133 1 1126598848 24122528 15095488
08:15:33 6171 8192000000 10022249696 10435465216 1093529008 11.776 100 31 62 2 1135099264 35038864 6531392
08:16:40 4351 8192000000 10011264720 10398367744 1072309888 11.574 98 41 83 1 1097905072 17610960 7984224
08:17:46 60627 8192000000 9965885456 10375016448 1022418032 11.095 94 33 64 1 1062378992 32986224 6974736
08:19:48 9441 8192000000 9954766416 10341502976 1036301664 11.229 99 25 48 2 1063099840 21046192 5751984
08:20:58 2582 8192000000 9944255792 10309906432 1012034384 10.995 99 62 121 4 1037645968 12785296 12826288
08:22:01 7148 8192000000 9932935424 10318909440 1016327968 11.037 100 40 80 4 1038259776 13207360 8724448
08:23:09 10903 8192000000 9951227376 10320494592 1030308432 11.171 100 75 149 3 1056087600 8826336 16952832
08:24:20 5303 8192000000 9965245888 10343866368 1023814448 11.109 98 51 102 3 1060138816 24286896 12037472
08:25:26 7234 8192000000 9982803264 10378715136 1059366672 11.450 100 39 78 1 1083170880 15716672 8087536
08:26:33 73399 8192000000 10004436752 10406359040 1085884912 11.704 100 29 57 2 1113777792 21956000 5936880
08:29:14 5726 8192000000 10064275776 10464145408 1113182416 11.963 100 53 104 2 1178700560 54864848 10653296
08:30:23 3615 8192000000 10086567632 10493825024 1126173712 12.085 100 42 84 0 1202722928 67176304 9372912
08:31:33 5796 8192000000 10133982464 10533306368 1139555088 12.211 100 57 111 0 1244033840 92220944 12257808
08:32:51 9737 8192000000 10156119520 10560995328 1151097264 12.320 100 54 106 3 1263374032 100815152 11461616
08:34:09 26739 8192000000 10203465344 10610839552 1157766304 12.382 100 63 124 1 1304095648 132966912 13362432
08:35:40 4645 8192000000 10253375904 10662924288 1179351200 12.584 100 58 115 2 1365345712 173302896 12691616
08:36:49 12269 8192000000 10306618768 10722078720 1189875280 12.682 100 66 132 2 1411336448 206401472 15059696
08:38:11 4578 8192000000 10335105632 10748231680 1188504992 12.669 100 68 136 0 1441892352 237493312 15894048
08:39:21 3042 8192000000 10393171536 10800136192 1180580832 12.596 100 74 147 1 1492449664 295156688 16712144
08:40:37 2334 8192000000 10367893584 10775408640 1187109968 12.656 100 72 145 2 1465732064 261067648 17554448
08:41:45 5192 8192000000 10412960928 10839293952 1190510896 12.688 100 61 121 1 1516275056 311262880 14501280
08:42:56 2186 8192000000 10464709472 10877022208 1201468832 12.790 100 77 150 1 1562477136 344121344 16886960
08:44:01 7085 8192000000 10458536272 10878013440 1184271104 12.630 100 59 116 1 1567714032 369230000 14212928
08:45:16 5185 8192000000 10446955472 10869805056 1155297088 12.359 98 64 126 0 1533381472 362395264 15689120
08:46:27 12539 8192000000 10455717952 10901016576 1166346160 12.463 99 49 99 0 1563981712 385418464 12217088
08:47:52 8411 8192000000 10459639408 10910945280 1145854368 12.271 100 38 76 0 1555112656 399097616 10160672
08:49:04 12918 8192000000 10439934080 10935083008 1152569040 12.334 100 30 60 0 1578489984 417869984 8050960
08:50:21 20358 8192000000 10415605280 10914643968 1128285056 12.105 99 26 52 2 1513168672 378068224 6815392
08:52:10 22479 8192000000 10277127776 10765475840 1086391424 11.708 98 16 32 0 1411660640 320697712 4571504
08:53:33 101396 8192000000 10075338656 10524798976 1110534752 11.937 99 20 40 3 1166127776 50343344 5249680
08:56:15 45782 8192000000 10078771552 10523471872 1100359312 11.841 97 24 46 2 1148890672 44076304 4455056
08:58:05 112574 8192000000 10136369232 10595917824 1097093168 11.810 97 18 37 0 1220091040 119139120 3858752
09:00:58 167182 8192000000 10082952240 10516205568 1076983200 11.619 96 18 36 1 1125457952 44649184 3825568
09:04:52 11561 8192000000 10047748720 10493243392 1085591664 11.701 98 31 62 2 1154820624 62492528 6736432
09:06:12 94533 8192000000 10037693264 10486910976 1052610944 11.386 94 22 44 2 1095028304 37891904 4525456
09:09:33 37696 8192000000 10031421824 10457550848 1082528352 11.672 97 29 55 2 1101181296 13174832 5478112
09:11:11 46655 8192000000 10055972432 10507370496 1109935952 11.932 99 14 28 2 1157369280 44633904 2799424
09:13:09 83325 8192000000 10123657888 10557095936 1111771472 11.949 98 24 48 2 1186537136 69643344 5122320
09:16:47 49789 8192000000 10074427120 10518560768 1127797664 12.101 100 29 57 2 1182346384 48991760 5556960
09:18:37 54422 8192000000 10126064064 10568998912 1136577664 12.183 99 19 38 0 1234413888 94334016 3502208
09:21:26 62178 8192000000 10109215792 10553643008 1148009584 12.291 100 19 36 1 1186195488 34447408 3738496
09:23:39 95243 8192000000 10122000048 10571784192 1130963488 12.130 98 24 48 2 1200679984 64494608 5221888
09:26:15 63103 8192000000 10127816976 10636701696 1149120256 12.301 100 9 18 0 1244354176 93175360 2058560
09:28:20 43942 8192000000 10077921008 10518007808 1125571488 12.080 98 15 30 1 1161820480 32922544 3326448
09:30:04 52038 8192000000 10111763808 10554642432 1124197312 12.067 98 17 34 2 1177290384 49833600 3259472
09:31:58 64182 8192000000 10076912976 10524434432 1100230400 11.840 96 15 30 3 1162525616 59132528 3162688
09:34:02 66353 8192000000 10100813408 10544250880 1109329600 11.926 98 40 79 0 1193893616 76471776 8092240
09:36:18 65556 8192000000 10048383008 10489143296 1045261184 11.315 93 25 49 2 1089255360 38930880 5063296
09:38:23 11868 8192000000 10060569008 10477150208 1101593632 11.853 99 46 90 1 1136444352 25799616 9051104
09:39:38 65076 8192000000 10081324064 10527727616 1090603680 11.748 98 20 40 1 1181453792 86755968 4094144
09:41:56 109370 8192000000 10050582272 10540892160 1100387984 11.841 99 9 18 0 1151952592 49809952 1754656
09:44:56 56653 8192000000 10023549888 10483507200 1070806944 11.560 97 3 6 0 1092851632 21543984 500704
09:46:59 70901 8192000000 10042813728 10489921536 1109943568 11.932 100 15 30 1 1145477552 32140128 3393856
09:49:19 39622 8192000000 10052864672 10468667392 1076571600 11.615 97 26 52 2 1105583200 23415888 5595712
09:51:01 49636 8192000000 10022950752 10445402112 1066593456 11.520 96 18 36 2 1093434224 23371536 3469232
09:52:50 57684 8192000000 10093633456 10528854016 1103100192 11.867 100 29 58 2 1188622384 79661296 5860896
09:54:49 76900 8192000000 10078886736 10513235968 1090503760 11.747 98 18 36 0 1183138208 88814064 3820384
09:57:06 83659 8192000000 10063548912 10516631552 1066482832 11.518 95 9 18 0 1126058848 57988720 1587296
09:59:33 77737 8192000000 10066449664 10509185024 1117039376 11.999 100 36 72 0 1151133248 27097056 6996816
10:01:51 59109 8192000000 10072115968 10528174080 1100296352 11.840 98 8 16 1 1137124496 35173120 1655024
10:03:51 69702 8192000000 10107538336 10584604672 1127973920 12.102 100 18 37 1 1186498256 55193120 3331216
10:06:00 78687 8192000000 10138823280 10579795968 1129411392 12.116 99 10 20 0 1220113504 88700640 2001472
10:08:54 98055 8192000000 10080288896 10519187456 1141482848 12.229 100 10 20 0 1179539232 36219856 1836528
10:11:37 84609 8192000000 10172902720 10678067200 1139580704 12.212 98 30 60 1 1256431872 110461120 6390048
10:14:23 109902 8192000000 10089820624 10538569728 1113917856 11.969 97 16 32 0 1147787968 30768896 3101216
10:18:23 47789 8192000000 10098129568 10567999488 1140280080 12.218 99 4 8 0 1178582064 37491792 810192
10:20:19 69542 8192000000 10106951072 10592591872 1155643792 12.362 100 10 20 1 1232033456 73952048 2437616
10:22:31 77328 8192000000 10108394048 10575032320 1132185136 12.142 98 15 30 0 1210739200 75131216 3422848
10:24:49 27571 8192000000 10075564544 10506100736 1111382896 11.946 97 20 39 1 1142901216 27907616 3610704
10:26:20 41044 8192000000 8927485568 9120043008 84713184 1.023 8 1 2 0 90262672 5514000 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2020 10:28:11 to 07-12-2020 10: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          227057
MON$OLDEST_ACTIVE               227058
MON$OLDEST_SNAPSHOT             227058
MON$NEXT_TRANSACTION            227058
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                       2071840
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             775
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 ---
:99: 
:100: mscs-test-161.budzdorov.ru    Mon Dec  7 10:27:06 2020
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
--- end of diff output ---

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

07.12.20 10: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-07 10:28:17 No severe PSQL-related problems occured
07.12.20 10:28:18. End of report.