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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16360940 kB
MemAvailable 31353260 kB
Buffers 0 kB
Cached 15241260 kB
SwapCached 4864 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 2021-01-03 07:26:54
DTS_END                         2021-01-03 07:26:54.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/51204
TRN_ID                          236514
ATT_ID                          704
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: 9343.59 24457 1121231
customer order: creation 167.31 424 20077
customer order: refuse 14.03 350 1683
order to supplier: creation 46.68 1429 5602
order to supplier: removal 3.50 117212 420
invoice (draft): creation 45.67 3636 5480
invoice (draft): removal 54.67 864 6560
invoice accept: apply 41.06 17947 4927
invoice accept: cancel 48.98 866 5878
customer reserve: creation 3836.43 174 460371
customer reserve: removal 4786.02 67 574322
realization accept: apply 81.31 189 9757
realization accept: cancel 90.38 112 10845
payment from customer: creation 58.80 115 7056
payment from customer: removal 5.78 92 693
payment to supplier: creation 48.85 139 5862
payment to supplier: removal 3.31 63 397
service: total inventory turnovers 3.72 3573 446
service: total monetary turnovers 6.21 504 745
service: aggregate perf. data 0.88 13428 105
service: refresh index statistics 0.04 327952 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-01-2021 07:28:59 to 03-01-2021 07:29:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4051
TEST_TIME 2 3950
TEST_TIME 3 3676
TEST_TIME 4 4144
TEST_TIME 5 4045
TEST_TIME 6 4654
TEST_TIME 7 4783
TEST_TIME 8 4958
TEST_TIME 9 5866
TEST_TIME 10 5875
TEST_TIME 11 6589
TEST_TIME 12 6435
TEST_TIME 13 6493
TEST_TIME 14 6833
TEST_TIME 15 7031
TEST_TIME 16 7145
TEST_TIME 17 7813
TEST_TIME 18 8136
TEST_TIME 19 8659
TEST_TIME 20 8761
TEST_TIME 21 8768
TEST_TIME 22 8945
TEST_TIME 23 8888
TEST_TIME 24 8880
TEST_TIME 25 9254
TEST_TIME 26 9445
TEST_TIME 27 9503
TEST_TIME 28 10050
TEST_TIME 29 11247
TEST_TIME 30 11304
TEST_TIME 31 11163
TEST_TIME 32 10981
TEST_TIME 33 10937
TEST_TIME 34 10941
TEST_TIME 35 10940
TEST_TIME 36 10867
TEST_TIME 37 10849
TEST_TIME 38 10861
TEST_TIME 39 10647
TEST_TIME 40 10468
TEST_TIME 41 10251
TEST_TIME 42 10323
TEST_TIME 43 10314
TEST_TIME 44 10223
TEST_TIME 45 10242
TEST_TIME 46 10289
TEST_TIME 47 10188
TEST_TIME 48 10052
TEST_TIME 49 10097
TEST_TIME 50 10078
TEST_TIME 51 9907
TEST_TIME 52 9988
TEST_TIME 53 9902
TEST_TIME 54 9935
TEST_TIME 55 9959
TEST_TIME 56 9922
TEST_TIME 57 10010
TEST_TIME 58 9989
TEST_TIME 59 10090
TEST_TIME 60 10006
TEST_TIME 61 9997
TEST_TIME 62 10129
TEST_TIME 63 10104
TEST_TIME 64 10099
TEST_TIME 65 9993
TEST_TIME 66 9925
TEST_TIME 67 9842
TEST_TIME 68 9736
TEST_TIME 69 9640
TEST_TIME 70 9557
TEST_TIME 71 9509
TEST_TIME 72 9499
TEST_TIME 73 9598
TEST_TIME 74 9694
TEST_TIME 75 9790
TEST_TIME 76 9860
TEST_TIME 77 9832
TEST_TIME 78 9820
TEST_TIME 79 9916
TEST_TIME 80 9935
TEST_TIME 81 10004
TEST_TIME 82 9941
TEST_TIME 83 9864
TEST_TIME 84 9809
TEST_TIME 85 9741
TEST_TIME 86 9669
TEST_TIME 87 9674
TEST_TIME 88 9673
TEST_TIME 89 9675
TEST_TIME 90 9639
TEST_TIME 91 9668
TEST_TIME 92 9652
TEST_TIME 93 9714
TEST_TIME 94 9733
TEST_TIME 95 9736
TEST_TIME 96 9739
TEST_TIME 97 9720
TEST_TIME 98 9771
TEST_TIME 99 9680
TEST_TIME 100 9599
TEST_TIME 101 9566
TEST_TIME 102 9513
TEST_TIME 103 9483
TEST_TIME 104 9449
TEST_TIME 105 9396
TEST_TIME 106 9455
TEST_TIME 107 9427
TEST_TIME 108 9440
TEST_TIME 109 9467
TEST_TIME 110 9439
TEST_TIME 111 9466
TEST_TIME 112 9540
TEST_TIME 113 9520
TEST_TIME 114 9503
TEST_TIME 115 9556
TEST_TIME 116 9511
TEST_TIME 117 9459
TEST_TIME 118 9425
TEST_TIME 119 9392
TEST_TIME 120 9352

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2021 07:29:03 to 03-01-2021 07:29:06.

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 1547249 1547249 0 0.00 0 27773 6 0 0
doc_list_biud 1547249 1547249 0 0.00 0 46656 31 0 0
sp_add_invoice_to_stock 5036 4927 109 2.16 27 189492 17948 0 109
sp_cancel_adding_invoice 5878 5878 0 0.00 6 68008 866 0 0
sp_cancel_client_order 1683 1683 0 0.00 4 32253 351 0 0
sp_cancel_customer_reserve 574322 574322 0 0.00 1 46660 68 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 5587 93 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 1684 63 0 0
sp_cancel_supplier_invoice 6571 6560 11 0.16 2 30793 864 0 11
sp_cancel_supplier_order 420 420 0 0.00 10 1058012 117213 0 0
sp_cancel_write_off 10845 10845 0 0.00 1 10602 113 0 0
sp_client_order 20077 20077 0 0.00 4 22631 424 0 0
sp_customer_reserve 460371 460371 0 0.00 0 54650 175 0 0
sp_fill_shopping_cart 31148 31148 0 0.00 1 17061 39 0 0
sp_fill_shopping_cart_clo_res 460363 460363 0 0.00 0 22076 17 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 38805 370 0 0
sp_kill_cost_storno 17813 17813 0 0.00 0 17835 58 0 0
sp_kill_qstorno_ret_qs2qd 1166469 1166469 0 0.00 0 46609 36 0 0
sp_kill_qty_storno 614384 614384 0 0.00 0 46655 78 0 0
sp_lock_selected_doc 20392 20392 0 0.00 0 13227 6 0 0
sp_make_cost_storno 27594 27594 0 0.00 0 19290 59 0 0
sp_multiply_rows_for_pdistr 27594 27594 0 0.00 0 8488 31 0 0
sp_multiply_rows_for_qdistr 20077 20077 0 0.00 0 14061 226 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 12938 116 0 0
sp_pay_to_supplier 5862 5862 0 0.00 5 19306 139 0 0
sp_payment_common 12918 12918 0 0.00 1 12927 21 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 32246 323 0 0
sp_qd_handle_on_invoice_upd_sts 10797 10797 0 0.00 0 29041 392 0 0
sp_qd_handle_on_reserve_upd_sts 9757 9757 0 0.00 0 27298 62 0 0
sp_reserve_write_off 9757 9757 0 0.00 7 31108 190 0 0
sp_supplier_invoice 5480 5480 0 0.00 6 140949 3637 0 0
sp_supplier_order 5602 5602 0 0.00 5 93190 1430 0 0
srv_aggregate_perf_data 105 105 0 0.00 2489 162762 13429 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1538 139694 44217 0 0
srv_find_qd_qs_mism 1073819 1073819 0 0.00 0 27772 5 0 0
srv_make_invnt_saldo 446 446 0 0.00 56 89477 3574 0 0
srv_make_money_saldo 745 745 0 0.00 1 19280 504 0 0
srv_recalc_idx_stat 5 5 0 0.00 244853 511184 327952 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4929 4929 0 0.00 0 17647 193 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 1991 75 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 8103 19 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 2155 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 558 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 909 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 5046 25 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 24713 1622 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 2007 9 0 0
t$perf-norm:sp_client_order 20077 20077 0 0.00 0 8778 19 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 5625 16 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 4247 9 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 7590 10 0 0
t$perf-norm:sp_reserve_write_off 9757 9757 0 0.00 0 8065 11 0 0
t$perf-norm:sp_supplier_invoice 5480 5480 0 0.00 0 7068 19 0 0
t$perf-norm:sp_supplier_order 5602 5602 0 0.00 0 12486 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 305 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 195 6 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 5373 29 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 4013 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 64 21 0 0
v_add_invoice_to_stock 10056 9838 218 2.16 0 5920 17 0 218
v_all_customers 36980 36980 0 0.00 0 72 1 0 0
v_all_suppliers 30940 30940 0 0.00 0 17 1 0 0
v_all_wares 40154 40154 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 7294 17 0 0
v_cancel_client_order 3366 3366 0 0.00 0 1082 3 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 2715 6 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3186 10 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 683 4 0 0
v_cancel_supplier_invoice 844 822 22 2.60 0 672 12 0 22
v_cancel_supplier_order 840 840 0 0.00 0 299 3 0 0
v_cancel_write_off 2036 2036 0 0.00 0 3198 17 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 706 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 3979 14 0 0
v_max_id_ord_sup 5473 5473 0 0.00 0 8550 6 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 336 2 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 6171 6 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 1859 7 0 0
v_min_id_clo_res 951 951 0 0.00 0 2525 7 0 0
v_min_id_ord_sup 5473 5473 0 0.00 0 6623 5 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 5963 12 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 7177 13 0 0
v_our_firm 10166 10166 0 0.00 0 17 1 0 0
v_reserve_write_off 19514 19514 0 0.00 0 5344 5 0 0
x_lock_depdocs_on_canc_invoice 12027 12027 0 0.00 0 21382 118 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 12122 346 0 0
x_make_qstorno_1000_1200 5602 5602 0 0.00 2 90485 1343 0 0
x_make_qstorno_1200_2000 5480 5480 0 0.00 3 140903 3503 0 0
x_make_qstorno_2100_3300 460371 460371 0 0.00 0 54647 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2021 07:29:07 to 03-01-2021 07:29:11.

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:58 9 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:58 30788 8192000000 9721783712 10020470784 795769408 8.853 100 81 162 0 822683984 7126064 19788512
04:59:34 9513 8192000000 9750805952 10054656000 829139152 9.191 100 87 174 0 862047104 12283776 20624176
05:00:52 16458 8192000000 9793677440 10107875328 859551264 9.496 100 85 170 0 898356880 18800112 20005504
05:02:13 11128 8192000000 9869811680 10200186880 913868464 10.036 100 81 162 0 979211136 47551600 17791072
05:03:27 8482 8192000000 9909243104 10251296768 946470784 10.356 100 81 161 1 1022064608 58541408 17052416
05:04:38 3814 8192000000 9955914672 10306039808 987638464 10.759 100 76 152 2 1067661856 64806448 15216944
05:05:44 3306 8192000000 9975445872 10340986880 1022724480 11.098 100 79 156 1 1093109840 54072096 16313264
05:06:50 2260 8192000000 10018174512 10385608704 1046864976 11.331 100 92 183 1 1135419696 70215248 18339472
05:07:53 1206 8192000000 10035454864 10403045376 1057739008 11.435 100 93 184 2 1148444480 72446400 18259072
05:08:55 2957 8192000000 10000209712 10388451328 1058634112 11.443 100 46 92 0 1117776656 49815280 9327264
05:09:59 61361 8192000000 9967497312 10377674752 1063438576 11.489 100 32 64 4 1085144624 14839488 6866560
05:12:11 9504 8192000000 9983544384 10380382208 1074126592 11.591 100 27 55 3 1113401584 33663024 5611968
05:13:22 7103 8192000000 9987039632 10389516288 1080320592 11.651 100 29 58 1 1117754944 31095840 6338512
05:14:29 6286 8192000000 9993168960 10393829376 1089122928 11.734 100 40 80 1 1112903504 15177904 8602672
05:15:37 30122 8192000000 9995313040 10405072896 1068888208 11.541 98 28 56 0 1094372016 19259984 6223824
05:17:07 4231 8192000000 9979828176 10374909952 1049234144 11.353 98 38 76 0 1076828672 19075600 8518928
05:18:14 5835 8192000000 9979996384 10369122304 1034510912 11.212 98 43 86 0 1063685136 19874992 9299232
05:19:20 56991 8192000000 9962460112 10336722944 1002573408 10.903 95 43 88 1 1028640352 16587920 9479024
05:21:17 8643 8192000000 9942012992 10331152384 998961264 10.868 96 33 64 4 1023353264 16573072 7818928
05:22:32 7704 8192000000 9948997728 10334457856 1020210528 11.074 100 27 54 1 1040604720 14944512 5449680
05:23:43 6376 8192000000 9961357216 10352717824 1038756848 11.253 99 47 95 1 1075696544 26197664 10742032
05:24:50 45703 8192000000 9962238976 10377535488 1053133344 11.391 100 30 60 0 1077269408 16505408 7630656
05:26:40 3557 8192000000 9973498976 10367131648 1065248320 11.507 100 54 106 3 1089789648 12219952 12321376
05:27:51 7559 8192000000 9998069440 10390470656 1074297632 11.593 100 41 82 3 1109680672 26258352 9124688
05:29:06 14085 8192000000 10037872512 10433069056 1092857072 11.770 100 47 94 1 1147250992 44498896 9895024
05:30:24 13359 8192000000 10089829904 10491916288 1101853600 11.855 100 46 92 0 1209626064 98108016 9664448
05:31:55 36457 8192000000 10149439840 10572800000 1114822208 11.978 100 37 73 1 1267765184 144834896 8108080
05:33:42 19302 8192000000 10146914352 10555260928 1126278736 12.086 100 50 96 1 1268034560 130827424 10928400
05:35:05 16659 8192000000 10209391648 10610962432 1134480352 12.164 100 70 141 1 1305579088 155740112 15358624
05:36:30 6177 8192000000 10216081664 10611929088 1143584224 12.249 99 69 138 0 1322182096 163965808 14632064
05:37:41 10627 8192000000 10230018368 10643800064 1149042912 12.301 100 52 102 2 1338600688 178383392 11174384
05:38:56 3162 8192000000 10254615440 10654253056 1155800192 12.364 100 70 141 2 1351063824 179393088 15870544
05:40:04 5152 8192000000 10257158768 10661302272 1152253056 12.331 99 62 120 1 1354864688 189196592 13415040
05:41:15 2073 8192000000 10272034304 10681335808 1151856656 12.327 100 77 154 0 1371362832 201616864 17889312
05:42:21 1716 8192000000 10309230784 10704158720 1133864000 12.158 99 86 171 1 1399353920 245515568 19974352
05:43:26 5746 8192000000 10317432688 10771984384 1145185088 12.264 100 83 166 1 1463515424 298848864 19481472
05:44:36 1535 8192000000 10289611296 10675949568 1127990592 12.102 98 93 184 0 1372607296 223302672 21314032
05:45:41 7699 8192000000 10260947504 10679549952 1129141152 12.113 100 38 75 2 1366503696 228378800 8983744
05:46:51 4803 8192000000 10346520576 10764144640 1147635904 12.287 99 47 95 0 1443342224 283815680 11890640
05:48:00 6125 8192000000 10369663280 10782912512 1142427392 12.238 99 63 122 3 1457506384 299824112 15254880
05:49:11 12956 8192000000 10386250496 10807169024 1163251792 12.434 100 34 68 0 1490461808 318300656 8909360
05:50:28 11415 8192000000 10478938672 10916114432 1167657520 12.475 100 35 70 2 1572138880 394996240 9485120
05:51:44 12069 8192000000 10461330464 10930184192 1159498416 12.399 100 39 78 1 1573584128 404095184 9990528
05:52:57 25722 8192000000 10451041824 10896949248 1137359264 12.191 99 31 62 0 1532850768 387096240 8395264
05:54:27 27364 8192000000 10374191856 10877775872 1119651072 12.024 99 25 50 4 1502708464 375793776 7263616
05:55:59 49831 8192000000 10060551536 10475266048 1080761776 11.655 96 34 68 0 1112944944 25124112 7059056
05:57:52 73624 8192000000 10090250208 10524098560 1126676640 12.090 100 24 49 1 1173573264 40784832 6111792
06:00:12 86967 8192000000 10057691808 10528018432 1103269920 11.869 98 14 28 1 1152036000 45878640 2887440
06:02:39 30148 8192000000 10082703664 10533695488 1128998736 12.112 100 12 24 0 1149875040 18603024 2273280
06:04:10 71962 8192000000 10085491200 10529497088 1120644480 12.033 99 17 34 1 1177496480 52901216 3950784
06:06:30 139694 8192000000 10084274640 10597793792 1126908944 12.092 99 25 50 1 1228243184 95038640 6295600
06:10:10 28633 8192000000 10084292272 10538332160 1136503056 12.183 100 17 34 1 1180462864 40356576 3603232
06:11:42 12401 8192000000 10088641184 10538774528 1138538768 12.202 100 60 121 2 1185524496 35026768 11958960
06:12:56 49801 8192000000 10073113088 10526068736 1140504544 12.220 100 19 38 1 1184718624 39828832 4385248
06:14:47 45355 8192000000 10106769616 10583445504 1142741712 12.241 100 15 30 0 1207030496 61076672 3212112
06:16:51 63412 8192000000 10108116800 10559881216 1126451024 12.088 98 13 26 1 1187594448 58411344 2732080
06:19:26 43892 8192000000 10082024208 10546917376 1112364000 11.955 97 16 32 2 1148163760 32755824 3043936
06:21:10 102643 8192000000 10143126912 10592215040 1110250144 11.935 97 15 30 1 1182057744 68625040 3182560
06:23:53 30884 8192000000 10082745152 10568151040 1115234416 11.982 98 12 24 0 1166582112 48756496 2591200
06:25:24 69244 8192000000 10059891984 10491973632 1107617232 11.910 97 19 38 0 1137384272 25916096 3850944
06:27:39 36083 8192000000 10131638128 10600747008 1094580928 11.786 96 28 56 1 1208707328 108096848 6029552
06:29:15 10831 8192000000 10056382880 10479226880 1116864544 11.997 100 59 118 3 1142913424 13700224 12348656
06:30:32 63794 8192000000 10063199664 10512359424 1092580688 11.767 97 31 62 2 1146920000 48643280 5696032
06:32:54 47999 8192000000 10058260800 10520113152 1115153360 11.981 99 20 40 0 1154472160 34857696 4461104
06:34:54 79500 8192000000 10106967952 10552459264 1118646944 12.014 99 18 36 0 1190698592 68501072 3550576
06:37:13 58837 8192000000 10131254880 10556108800 1046774960 11.330 93 23 48 0 1176010032 123644816 5590256
06:39:12 82959 8192000000 10187632032 10728914944 1113493744 11.965 100 16 32 0 1323055024 205236480 4324800
06:41:44 70197 8192000000 10087682976 10591068160 1115218464 11.982 100 8 16 0 1218946256 101864192 1863600
06:43:54 32237 8192000000 10109726800 10543783936 1095183152 11.792 98 31 62 0 1164451696 62764208 6504336
06:45:28 39421 8192000000 10090719536 10521378816 1118497712 12.013 100 38 76 0 1162929456 37194640 7237104
06:47:10 55085 8192000000 10080324096 10518564864 1107323184 11.907 98 21 42 1 1195337744 83920400 4094160
06:49:05 75513 8192000000 10068011984 10506625024 1131409472 12.135 100 14 27 0 1168729184 34553280 2766432
06:51:22 77658 8192000000 10096674128 10551017472 1124055008 12.065 99 12 24 2 1175168768 48662768 2450992
06:53:49 48576 8192000000 10098104176 10544017408 1122431776 12.050 99 18 36 0 1188259472 61834432 3993264
06:55:38 27797 8192000000 10087809712 10524839936 1147029008 12.282 100 41 81 2 1187552288 31315888 9207392
06:57:07 75440 8192000000 10085637552 10575687680 1100417296 11.842 96 18 36 2 1181646064 77189072 4039696
06:59:29 46349 8192000000 10114208144 10619060224 1138600672 12.202 100 35 70 3 1259000496 113049456 7350368
07:01:17 89985 8192000000 10092280496 10580525056 1141592624 12.231 100 13 25 0 1186186048 41672336 2921088
07:03:47 65507 8192000000 10094930304 10605879296 1132077488 12.141 99 12 23 2 1219863456 85247024 2538944
07:05:52 99716 8192000000 10054039520 10502840320 1112194240 11.953 97 18 36 0 1142683536 26788208 3701088
07:08:36 57013 8192000000 10070921344 10550546432 1140235616 12.218 100 5 9 1 1161441136 20507680 697840
07:10:33 75016 8192000000 10119852176 10564456448 1099247712 11.831 95 26 52 1 1165994960 60705360 6041888
07:13:00 70353 8192000000 10141853552 10595794944 1130502544 12.126 98 17 34 0 1211169904 76940160 3727200
07:15:10 50621 8192000000 10077648096 10517377024 1089014928 11.733 95 28 56 2 1151341920 56492224 5834768
07:17:04 67563 8192000000 10107149856 10582675456 1047372832 11.335 92 21 42 1 1128297280 76850768 4073680
07:19:12 45091 8192000000 10073871776 10505158656 1091009792 11.752 98 23 46 0 1128406704 32426320 4970592
07:21:19 44289 8192000000 10092175168 10554908672 1098950240 11.828 98 38 75 1 1158727120 52066896 7709984
07:23:11 118614 8192000000 10060289856 10506665984 1100791552 11.845 98 18 36 0 1151041744 46434512 3815680
07:26:10 55984 8192000000 9462003552 9837223936 546705440 6.256 49 5 10 0 598743424 50813504 1224480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-01-2021 07:29:13 to 03-01-2021 07:29:29.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 03-01-2021 07:29:30 to 03-01-2021 07:29:31.

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          236796
MON$OLDEST_ACTIVE               236797
MON$OLDEST_SNAPSHOT             236797
MON$NEXT_TRANSACTION            236797
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                       2137088
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:98: 
:99: mscs-test-161.budzdorov.ru    Sun Jan  3 07:27:24 2021
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Sun Jan  3 07:27:27 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Sun Jan  3 07:27:29 2021
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:109: 
--- end of diff output ---

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

03.01.21 07:29:33. 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
2021-01-03 07:29:35 No severe PSQL-related problems occured
03.01.21 07:29:36. End of report.