Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.2020 07:36
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 16638604 kB
MemAvailable 31461704 kB
Buffers 0 kB
Cached 15074092 kB
SwapCached 2700 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-13 07:35:38
DTS_END                         2020-11-13 07:35:38.2060
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/60604
TRN_ID                          215282
ATT_ID                          655
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: 7703.29 40155 924395
customer order: creation 143.11 392 17173
customer order: refuse 12.75 369 1530
order to supplier: creation 38.73 1688 4647
order to supplier: removal 2.97 172411 356
invoice (draft): creation 39.42 5502 4730
invoice (draft): removal 43.43 1626 5212
invoice accept: apply 34.91 30225 4189
invoice accept: cancel 38.83 1374 4659
customer reserve: creation 3276.66 293 393199
customer reserve: removal 3825.41 101 459049
realization accept: apply 69.92 256 8390
realization accept: cancel 67.43 166 8091
payment from customer: creation 51.18 160 6142
payment from customer: removal 4.93 149 592
payment to supplier: creation 41.99 168 5039
payment to supplier: removal 3.11 140 373
service: total inventory turnovers 2.61 9410 313
service: total monetary turnovers 5.24 1004 629
service: aggregate perf. data 0.64 31067 77
service: refresh index statistics 0.04 546606 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-11-2020 07:37:48 to 13-11-2020 07:37:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4161
TEST_TIME 2 5601
TEST_TIME 3 5279
TEST_TIME 4 5816
TEST_TIME 5 5629
TEST_TIME 6 6234
TEST_TIME 7 6182
TEST_TIME 8 6716
TEST_TIME 9 6304
TEST_TIME 10 6365
TEST_TIME 11 6336
TEST_TIME 12 6340
TEST_TIME 13 6768
TEST_TIME 14 6843
TEST_TIME 15 6993
TEST_TIME 16 7049
TEST_TIME 17 7059
TEST_TIME 18 7655
TEST_TIME 19 8182
TEST_TIME 20 8269
TEST_TIME 21 8521
TEST_TIME 22 8354
TEST_TIME 23 8971
TEST_TIME 24 9256
TEST_TIME 25 9472
TEST_TIME 26 9585
TEST_TIME 27 9443
TEST_TIME 28 9360
TEST_TIME 29 9120
TEST_TIME 30 9167
TEST_TIME 31 9056
TEST_TIME 32 8975
TEST_TIME 33 9081
TEST_TIME 34 8861
TEST_TIME 35 8646
TEST_TIME 36 8456
TEST_TIME 37 8263
TEST_TIME 38 8213
TEST_TIME 39 8036
TEST_TIME 40 7887
TEST_TIME 41 7992
TEST_TIME 42 7908
TEST_TIME 43 7767
TEST_TIME 44 7729
TEST_TIME 45 7593
TEST_TIME 46 7526
TEST_TIME 47 7433
TEST_TIME 48 7383
TEST_TIME 49 7282
TEST_TIME 50 7228
TEST_TIME 51 7134
TEST_TIME 52 7063
TEST_TIME 53 6978
TEST_TIME 54 6924
TEST_TIME 55 6880
TEST_TIME 56 6898
TEST_TIME 57 6868
TEST_TIME 58 6813
TEST_TIME 59 6864
TEST_TIME 60 6823
TEST_TIME 61 6766
TEST_TIME 62 6807
TEST_TIME 63 6871
TEST_TIME 64 6841
TEST_TIME 65 6894
TEST_TIME 66 6901
TEST_TIME 67 7124
TEST_TIME 68 7257
TEST_TIME 69 7419
TEST_TIME 70 7364
TEST_TIME 71 7304
TEST_TIME 72 7315
TEST_TIME 73 7343
TEST_TIME 74 7358
TEST_TIME 75 7465
TEST_TIME 76 7476
TEST_TIME 77 7549
TEST_TIME 78 7611
TEST_TIME 79 7682
TEST_TIME 80 7639
TEST_TIME 81 7598
TEST_TIME 82 7575
TEST_TIME 83 7635
TEST_TIME 84 7600
TEST_TIME 85 7618
TEST_TIME 86 7596
TEST_TIME 87 7570
TEST_TIME 88 7551
TEST_TIME 89 7503
TEST_TIME 90 7527
TEST_TIME 91 7559
TEST_TIME 92 7527
TEST_TIME 93 7534
TEST_TIME 94 7536
TEST_TIME 95 7515
TEST_TIME 96 7482
TEST_TIME 97 7511
TEST_TIME 98 7535
TEST_TIME 99 7635
TEST_TIME 100 7595
TEST_TIME 101 7573
TEST_TIME 102 7552
TEST_TIME 103 7549
TEST_TIME 104 7560
TEST_TIME 105 7530
TEST_TIME 106 7569
TEST_TIME 107 7555
TEST_TIME 108 7587
TEST_TIME 109 7605
TEST_TIME 110 7642
TEST_TIME 111 7652
TEST_TIME 112 7619
TEST_TIME 113 7666
TEST_TIME 114 7698
TEST_TIME 115 7702
TEST_TIME 116 7713
TEST_TIME 117 7720
TEST_TIME 118 7745
TEST_TIME 119 7735
TEST_TIME 120 7703

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2020 07:37:52 to 13-11-2020 07:37:56.

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 1286994 1286994 0 0.00 0 32349 10 0 0
doc_list_biud 1286994 1286994 0 0.00 0 84607 46 0 0
sp_add_invoice_to_stock 4279 4189 90 2.10 52 399368 30226 0 90
sp_cancel_adding_invoice 4659 4659 0 0.00 5 89188 1375 0 0
sp_cancel_client_order 1530 1530 0 0.00 6 3801 370 0 0
sp_cancel_customer_reserve 459049 459049 0 0.00 1 43244 102 0 0
sp_cancel_pay_from_customer 592 592 0 0.00 3 6480 150 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 13221 141 0 0
sp_cancel_supplier_invoice 5222 5212 10 0.19 3 84609 1627 0 10
sp_cancel_supplier_order 356 356 0 0.00 13 1044507 172412 0 0
sp_cancel_write_off 8091 8091 0 0.00 1 12505 167 0 0
sp_client_order 17173 17173 0 0.00 4 25034 392 0 0
sp_customer_reserve 393199 393199 0 0.00 0 121110 294 0 0
sp_fill_shopping_cart 26534 26534 0 0.00 1 25174 61 0 0
sp_fill_shopping_cart_clo_res 393195 393195 0 0.00 0 38070 23 0 0
sp_get_clo_for_invoice 4185 4185 0 0.00 0 30574 559 0 0
sp_kill_cost_storno 13715 13715 0 0.00 0 13213 107 0 0
sp_kill_qstorno_ret_qs2qd 931757 931757 0 0.00 0 84607 56 0 0
sp_kill_qty_storno 491472 491472 0 0.00 0 84607 120 0 0
sp_lock_selected_doc 17494 17494 0 0.00 0 9425 3 0 0
sp_make_cost_storno 23756 23756 0 0.00 0 32105 95 0 0
sp_multiply_rows_for_pdistr 23756 23756 0 0.00 0 14869 50 0 0
sp_multiply_rows_for_qdistr 17173 17173 0 0.00 0 25028 168 0 0
sp_pay_from_customer 6142 6142 0 0.00 4 30646 161 0 0
sp_pay_to_supplier 5039 5039 0 0.00 4 23982 169 0 0
sp_payment_common 11181 11181 0 0.00 2 29767 41 0 0
sp_qd_handle_on_cancel_clo 1530 1530 0 0.00 1 3778 336 0 0
sp_qd_handle_on_invoice_upd_sts 8844 8844 0 0.00 0 33389 607 0 0
sp_qd_handle_on_reserve_upd_sts 8390 8390 0 0.00 0 11616 64 0 0
sp_reserve_write_off 8390 8390 0 0.00 7 32281 256 0 0
sp_supplier_invoice 4730 4730 0 0.00 6 107866 5502 0 0
sp_supplier_order 4647 4647 0 0.00 6 32556 1688 0 0
srv_aggregate_perf_data 77 77 0 0.00 3528 462079 31067 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 1940 141775 34301 0 0
srv_find_qd_qs_mism 883166 883166 0 0.00 0 15402 8 0 0
srv_make_invnt_saldo 313 313 0 0.00 103 173771 9410 0 0
srv_make_money_saldo 629 629 0 0.00 1 15736 1005 0 0
srv_recalc_idx_stat 5 5 0 0.00 138997 1145338 546606 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 4189 4189 0 0.00 0 8293 230 0 0
t$perf-norm:sp_cancel_adding_invoice 334 334 0 0.00 1 2962 115 0 0
t$perf-norm:sp_cancel_client_order 1530 1530 0 0.00 0 3244 19 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 3440 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 592 592 0 0.00 0 816 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 2202 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 304 304 0 0.00 0 853 26 0 0
t$perf-norm:sp_cancel_supplier_order 356 356 0 0.00 0 32613 2507 0 0
t$perf-norm:sp_cancel_write_off 824 824 0 0.00 0 4321 14 0 0
t$perf-norm:sp_client_order 17173 17173 0 0.00 0 11205 27 0 0
t$perf-norm:sp_customer_reserve 873 873 0 0.00 0 1634 8 0 0
t$perf-norm:sp_pay_from_customer 6142 6142 0 0.00 0 6646 15 0 0
t$perf-norm:sp_pay_to_supplier 5039 5039 0 0.00 0 6966 17 0 0
t$perf-norm:sp_reserve_write_off 8390 8390 0 0.00 0 9136 16 0 0
t$perf-norm:sp_supplier_invoice 4730 4730 0 0.00 0 3669 24 0 0
t$perf-norm:sp_supplier_order 4647 4647 0 0.00 0 6949 29 0 0
t$perf-norm:srv_aggregate_perf_data 77 77 0 0.00 0 677 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 614 19 0 0
t$perf-norm:srv_make_invnt_saldo 313 313 0 0.00 0 958 28 0 0
t$perf-norm:srv_make_money_saldo 629 629 0 0.00 0 975 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 8550 8370 180 2.10 0 6168 20 0 180
v_all_customers 31566 31566 0 0.00 0 99 1 0 0
v_all_suppliers 26124 26124 0 0.00 0 106 1 0 0
v_all_wares 34346 34346 0 0.00 0 96 1 0 0
v_cancel_adding_invoice 668 668 0 0.00 0 1205 10 0 0
v_cancel_client_order 3060 3060 0 0.00 0 2109 8 0 0
v_cancel_customer_prepayment 1184 1184 0 0.00 0 6471 17 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 0 4669 9 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 2213 8 0 0
v_cancel_supplier_invoice 628 608 20 3.18 0 2550 23 0 20
v_cancel_supplier_order 712 712 0 0.00 0 2009 13 0 0
v_cancel_write_off 1648 1648 0 0.00 0 3819 19 0 0
v_max_id_clo_ord 4647 4647 0 0.00 0 666 2 0 0
v_max_id_clo_res 873 873 0 0.00 0 2844 13 0 0
v_max_id_ord_sup 4714 4714 0 0.00 0 2442 3 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 531 2 0 0
v_max_non_paid_realizn 3310 3310 0 0.00 0 12898 7 0 0
v_min_id_clo_ord 4647 4647 0 0.00 0 9122 9 0 0
v_min_id_clo_res 873 873 0 0.00 0 430 5 0 0
v_min_id_ord_sup 4714 4714 0 0.00 0 5900 6 0 0
v_min_non_paid_invoice 5039 5039 0 0.00 0 19251 26 0 0
v_min_non_paid_realizn 6142 6142 0 0.00 0 17694 33 0 0
v_our_firm 8444 8444 0 0.00 0 95 1 0 0
v_reserve_write_off 16780 16780 0 0.00 0 4669 7 0 0
x_lock_depdocs_on_canc_invoice 9567 9567 0 0.00 0 17946 206 0 0
x_lock_depdocs_on_canc_sup_ord 356 356 0 0.00 0 17982 597 0 0
x_make_qstorno_1000_1200 4647 4647 0 0.00 2 32546 1573 0 0
x_make_qstorno_1200_2000 4730 4730 0 0.00 2 107846 5268 0 0
x_make_qstorno_2100_3300 393199 393199 0 0.00 0 121108 265 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-11-2020 07:37:56 to 13-11-2020 07:38:01.

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
05:05:42 5 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:06:42 31566 8192000000 9713751968 10010931200 800392688 8.900 100 90 180 0 829835712 7434688 22008336
05:08:17 12039 8192000000 9753342304 10058170368 831260928 9.212 100 85 170 0 861995488 10941968 19792592
05:09:36 14686 8192000000 9783949792 10096877568 856075808 9.461 100 88 176 0 893840576 17461760 20303008
05:11:03 18322 8192000000 9843865120 10178433024 897645568 9.875 100 79 158 1 956222352 41391968 17184816
05:12:26 7528 8192000000 9913895952 10252193792 947125776 10.363 100 83 166 0 1021452992 56928608 17398608
05:13:36 5929 8192000000 9964168304 10315624448 980820736 10.692 100 81 161 0 1075400256 78254928 16324592
05:14:45 2758 8192000000 9994641440 10357751808 1016702464 11.040 100 80 159 1 1108770112 76361808 15705840
05:15:50 3388 8192000000 10008835360 10372792320 1035287616 11.219 100 89 177 1 1117301568 62985376 19028576
05:16:54 1342 8192000000 10054713456 10425966592 1062214352 11.478 100 91 180 2 1172963152 92387856 18360944
05:17:57 3071 8192000000 10035815200 10426216448 1063066576 11.486 100 53 104 1 1155622144 81923104 10632464
05:19:06 36075 8192000000 10008480464 10398404608 1071252144 11.564 100 62 123 4 1098941680 14196384 13493152
05:20:50 7803 8192000000 9986918720 10387279872 1077092864 11.620 100 41 82 4 1100502528 14352320 9057344
05:22:00 8738 8192000000 9999364592 10412634112 1079753776 11.645 100 30 60 3 1123535536 37518224 6263536
05:23:10 8300 8192000000 10011533840 10421841920 1087491184 11.719 100 25 50 0 1112575952 19949680 5135088
05:24:22 39220 8192000000 10021961632 10404245504 1071407600 11.566 98 64 127 2 1098822016 14072720 13341696
05:26:02 6158 8192000000 10019979760 10436591616 1075267952 11.602 99 37 74 1 1119090272 35440352 8381968
05:27:08 6630 8192000000 9977171744 10369269760 1039911888 11.264 98 25 48 4 1075356544 30091136 5353520
05:28:16 5419 8192000000 9967578912 10343149568 1039167344 11.257 99 50 99 2 1071744864 20999728 11577792
05:29:22 49744 8192000000 9871722480 10244222976 928735200 10.182 92 43 86 4 961161024 22881744 9544080
05:31:17 7963 8192000000 9952709888 10336329728 1018642368 11.059 99 47 93 3 1057305328 28157648 10505312
05:32:27 11218 8192000000 9955005072 10365808640 1051558272 11.376 100 37 74 2 1083250064 23569104 8122688
05:33:41 5472 8192000000 9964949328 10365247488 1064380096 11.498 100 33 64 2 1079802112 7792176 7629840
05:34:47 7863 8192000000 9969618496 10372775936 1065419824 11.508 100 28 56 3 1094359824 22864736 6075264
05:35:59 42605 8192000000 10011938352 10409209856 1088251888 11.726 100 51 102 3 1113807392 14734880 10820624
05:37:46 5088 8192000000 10075475024 10476228608 1110747680 11.939 100 61 122 0 1181160288 57362096 13050512
05:38:55 26901 8192000000 10080704496 10501308416 1119870576 12.026 100 28 57 1 1193408560 66838832 6699152
05:40:32 32387 8192000000 10118239200 10532651008 1130126160 12.123 99 46 91 2 1237091248 97061520 9903568
05:42:08 12251 8192000000 10165525328 10571603968 1149157632 12.302 100 66 131 2 1273703200 110536768 14008800
05:43:28 37276 8192000000 10152008704 10603139072 1156237264 12.368 100 37 74 1 1268305120 103647232 8420624
05:45:09 17512 8192000000 10201926880 10621177856 1164275104 12.443 100 42 82 0 1310467696 136746576 9446016
05:46:37 4296 8192000000 10238971568 10653741056 1171777104 12.513 100 52 104 1 1337713376 154102112 11834160
05:47:47 5478 8192000000 10265848848 10678079488 1178171328 12.573 100 68 135 0 1366033104 173026336 14835440
05:49:01 5098 8192000000 10259320400 10672177152 1163851888 12.439 99 73 144 1 1352961728 172862672 16247168
05:50:08 6913 8192000000 10304556000 10723201024 1175514880 12.548 100 60 120 2 1410281600 220897984 13868736
05:51:21 2964 8192000000 10326321248 10734678016 1165418384 12.454 100 72 143 0 1427890672 246397840 16074448
05:52:27 2040 8192000000 10362474880 10762326016 1137830368 12.195 98 80 159 1 1452576560 296069984 18676208
05:53:33 3567 8192000000 10380715376 10781589504 1142703392 12.241 99 96 193 2 1462436720 297984976 21748352
05:54:41 15895 8192000000 10322432528 10802733056 1144104864 12.254 100 43 85 2 1461754672 307236736 10413072
05:56:25 17399 8192000000 10309112144 10726588416 1129544000 12.117 99 44 88 3 1399116432 258817920 10754512
05:57:45 1940 8192000000 10344138176 10750107648 1122095280 12.047 100 66 132 0 1438865104 300413008 16356816
05:58:51 9069 8192000000 10294474800 10717097984 1111023936 11.942 99 32 65 1 1398388720 278258592 9106192
06:00:09 27621 8192000000 10243636672 10690383872 1105686416 11.892 99 19 38 2 1362245952 251491936 5067600
06:01:58 31423 8192000000 10259455600 10679369728 1103730496 11.873 99 26 53 1 1345570256 235345680 6494080
06:03:47 39120 8192000000 10257639264 10706579456 1106538256 11.900 99 27 50 0 1343382896 231589120 5255520
06:05:35 141775 8192000000 10178606352 10604695552 1113291024 11.964 99 19 38 0 1297723696 180350208 4082464
06:08:58 49261 8192000000 10112916880 10575151104 1125531568 12.079 100 21 42 2 1221886640 92136352 4218720
06:11:07 56601 8192000000 10139255984 10564308992 1126142368 12.085 100 21 42 0 1227739024 97510880 4085776
06:13:08 68777 8192000000 10125948816 10542395392 1104717424 11.882 98 42 79 4 1205378608 91549040 9112144
06:15:28 57388 8192000000 10108268672 10549506048 1128604304 12.108 100 37 74 0 1238004912 101427632 7972976
06:17:45 53498 8192000000 10080171312 10505920512 1124306096 12.068 99 25 50 2 1175365584 45869616 5189872
06:19:42 47760 8192000000 10095574512 10535190528 1125005136 12.074 99 31 62 1 1184884736 53025328 6854272
06:21:46 125425 8192000000 10081263296 10509238272 1109839984 11.931 98 21 42 1 1150772688 36733328 4199376
06:25:01 17172 8192000000 10094277696 10520166400 1129627808 12.118 100 36 72 4 1187374800 49553872 8193120
06:26:31 18910 8192000000 10130133792 10544828416 1128167232 12.104 100 55 105 1 1220321920 81557152 10597536
06:28:05 18067 8192000000 10167741056 10573709312 1140094528 12.216 100 73 142 1 1255635952 100107600 15433824
06:29:29 14740 8192000000 10199604208 10611187712 1140672400 12.222 100 73 144 1 1282578848 126834000 15072448
06:30:56 39408 8192000000 10224275088 10656735232 1144972448 12.262 100 68 135 1 1313498224 153289968 15235808
06:32:48 6327 8192000000 10229527328 10637709312 1146127632 12.273 100 60 120 3 1320581504 161781968 12671904
06:33:57 12825 8192000000 10258003824 10699010048 1161342656 12.416 100 54 108 3 1348121056 175082848 11695552
06:35:12 28149 8192000000 10290997008 10708525056 1155042640 12.357 100 57 110 1 1376051392 208823216 12185536
06:36:49 6820 8192000000 10323530560 10741760000 1155839568 12.364 100 64 126 2 1419947104 250061888 14045648
06:37:59 15747 8192000000 10350838832 10782109696 1164475056 12.445 100 59 118 2 1453266240 275452800 13338384
06:39:32 9289 8192000000 10346610112 10767978496 1175749712 12.551 100 58 116 0 1446673904 258496688 12427504
06:40:52 22060 8192000000 10348820128 10798030848 1150340320 12.313 99 41 82 2 1450014656 289942976 9731360
06:42:25 19125 8192000000 10268910704 10732589056 1156473776 12.370 100 37 73 0 1385984672 220744800 8766096
06:43:59 22510 8192000000 10200916160 10645569536 1154880992 12.355 100 32 65 2 1300574256 138298320 7394944
06:45:26 45152 8192000000 10136353600 10559488000 1141647248 12.231 98 41 82 1 1231817136 81823632 8346256
06:48:02 3853 8192000000 10240800272 10674360320 1156664208 12.372 100 52 104 1 1338988496 171024496 11299792
06:49:17 21861 8192000000 10210369984 10674839552 1149781536 12.307 100 18 36 0 1305821248 151891472 4148240
06:50:42 7702 8192000000 10162068512 10619318272 1136530848 12.183 100 42 84 3 1272081536 126661360 8889328
06:51:51 20300 8192000000 10151986864 10569146368 1114980128 11.980 98 62 122 0 1223556064 95242880 13333056
06:53:16 44229 8192000000 10113454144 10595594240 1114043824 11.971 98 26 53 1 1235568480 115848032 5676624
06:55:06 36174 8192000000 10064245008 10493595648 1103624528 11.872 97 19 36 4 1141878928 34618048 3636352
06:57:24 44938 8192000000 10127692016 10572554240 1120578016 12.032 99 29 58 1 1201858384 75485200 5795168
06:59:48 66051 8192000000 10127248128 10595397632 1136627520 12.184 99 19 37 1 1206387776 65597744 4162512
07:01:55 53096 8192000000 10115234272 10568839168 1136588480 12.183 100 19 38 0 1214105360 73637312 3879568
07:03:56 92388 8192000000 10075010992 10578206720 1121787376 12.044 98 15 30 0 1177623648 52601984 3234288
07:06:33 63888 8192000000 10048638976 10484232192 1080870816 11.656 95 26 52 1 1118831552 32780192 5180544
07:08:38 38831 8192000000 10061647248 10499297280 1094617072 11.787 98 32 63 1 1146491856 45788256 6086528
07:10:17 26583 8192000000 10055751200 10501734400 1105543232 11.890 100 16 32 1 1164503792 55258832 3701728
07:11:52 32284 8192000000 10083460912 10511314944 1079814144 11.646 97 34 68 1 1165709520 78177088 7718288
07:13:28 30187 8192000000 10083523440 10512797696 1093967584 11.780 98 40 80 2 1171700704 69035888 8697232
07:15:01 72259 8192000000 10032592512 10489315328 1103695104 11.873 99 6 12 0 1135325424 30644368 985952
07:17:16 59681 8192000000 10071538736 10542735360 1118545744 12.013 99 14 28 2 1163365856 41847392 2972720
07:19:21 96359 8192000000 10110571216 10558750720 1106040592 11.895 98 21 41 0 1193022032 82613360 4368080
07:21:57 35578 8192000000 10082183648 10524532736 1082208320 11.669 96 24 48 0 1168890112 81029744 5652048
07:23:42 38427 8192000000 10096486064 10536878080 1107260320 11.906 98 35 69 2 1189308400 74604112 7443968
07:25:38 68289 8192000000 10096078320 10578489344 1112115264 11.952 98 23 44 1 1163235808 45685968 5434576
07:27:57 40094 8192000000 10141178720 10590269440 1128143696 12.104 100 25 51 2 1250376992 117190816 5042480
07:29:47 51274 8192000000 10061021824 10514505728 1117314128 12.002 99 17 33 0 1155959088 35574032 3070928
07:31:44 53103 8192000000 10090904368 10527838208 1118810032 12.016 99 26 52 0 1184128784 59694464 5624288
07:33:42 27986 8192000000 10075841824 10528677888 1136725120 12.185 100 20 40 1 1182929664 41730992 4473552
07:35:10 31840 8192000000 9779768048 10163716096 824935376 9.148 72 21 40 2 905829760 75929440 4964944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-11-2020 07:38:02 to 13-11-2020 07:38:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 13-11-2020 07:38:19 to 13-11-2020 07:38:21.

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          313
MON$OLDEST_ACTIVE               215626
MON$OLDEST_SNAPSHOT             215626
MON$NEXT_TRANSACTION            215626
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                       2151872
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             761
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2260 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2260 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2260 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.2260 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Nov 13 07:35:54 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Nov 13 07:35:59 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

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