Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.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 16951816 kB
MemAvailable 31454036 kB
Buffers 0 kB
Cached 14689812 kB
SwapCached 2868 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-03 10:26:52
DTS_END                         2020-11-03 10:26:52.6690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46896
TRN_ID                          228241
ATT_ID                          627
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: 8730.44 28176 1047653
customer order: creation 162.28 421 19473
customer order: refuse 14.06 370 1687
order to supplier: creation 44.37 1429 5324
order to supplier: removal 3.33 117274 399
invoice (draft): creation 43.87 3472 5264
invoice (draft): removal 48.58 991 5830
invoice accept: apply 40.66 20313 4879
invoice accept: cancel 44.09 980 5291
customer reserve: creation 3719.04 200 446285
customer reserve: removal 4328.94 70 519473
realization accept: apply 79.92 178 9590
realization accept: cancel 78.32 134 9398
payment from customer: creation 56.41 118 6769
payment from customer: removal 5.73 114 688
payment to supplier: creation 46.78 137 5613
payment to supplier: removal 3.43 122 411
service: total inventory turnovers 3.60 3905 432
service: total monetary turnovers 6.09 624 731
service: aggregate perf. data 0.93 14166 112
service: refresh index statistics 0.03 398493 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-11-2020 10:27:29 to 03-11-2020 10:27:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2810
TEST_TIME 2 4231
TEST_TIME 3 5028
TEST_TIME 4 5304
TEST_TIME 5 4892
TEST_TIME 6 5411
TEST_TIME 7 5698
TEST_TIME 8 5667
TEST_TIME 9 5864
TEST_TIME 10 5793
TEST_TIME 11 5922
TEST_TIME 12 6135
TEST_TIME 13 6558
TEST_TIME 14 6928
TEST_TIME 15 7038
TEST_TIME 16 7263
TEST_TIME 17 7624
TEST_TIME 18 7982
TEST_TIME 19 8127
TEST_TIME 20 8419
TEST_TIME 21 8363
TEST_TIME 22 8413
TEST_TIME 23 8377
TEST_TIME 24 8695
TEST_TIME 25 8632
TEST_TIME 26 8778
TEST_TIME 27 8915
TEST_TIME 28 9504
TEST_TIME 29 10309
TEST_TIME 30 10168
TEST_TIME 31 10146
TEST_TIME 32 10325
TEST_TIME 33 10178
TEST_TIME 34 10339
TEST_TIME 35 10158
TEST_TIME 36 10003
TEST_TIME 37 9923
TEST_TIME 38 9782
TEST_TIME 39 9699
TEST_TIME 40 9618
TEST_TIME 41 9522
TEST_TIME 42 9379
TEST_TIME 43 9259
TEST_TIME 44 9130
TEST_TIME 45 9355
TEST_TIME 46 9222
TEST_TIME 47 9111
TEST_TIME 48 9085
TEST_TIME 49 9119
TEST_TIME 50 9047
TEST_TIME 51 8959
TEST_TIME 52 8833
TEST_TIME 53 8805
TEST_TIME 54 8752
TEST_TIME 55 8830
TEST_TIME 56 8821
TEST_TIME 57 8808
TEST_TIME 58 8810
TEST_TIME 59 8778
TEST_TIME 60 8822
TEST_TIME 61 8778
TEST_TIME 62 8849
TEST_TIME 63 8799
TEST_TIME 64 8779
TEST_TIME 65 8923
TEST_TIME 66 8987
TEST_TIME 67 9007
TEST_TIME 68 8925
TEST_TIME 69 8824
TEST_TIME 70 8762
TEST_TIME 71 8705
TEST_TIME 72 8660
TEST_TIME 73 8630
TEST_TIME 74 8634
TEST_TIME 75 8612
TEST_TIME 76 8571
TEST_TIME 77 8557
TEST_TIME 78 8725
TEST_TIME 79 8729
TEST_TIME 80 8756
TEST_TIME 81 8727
TEST_TIME 82 8739
TEST_TIME 83 8871
TEST_TIME 84 8832
TEST_TIME 85 8817
TEST_TIME 86 8816
TEST_TIME 87 8723
TEST_TIME 88 8674
TEST_TIME 89 8602
TEST_TIME 90 8604
TEST_TIME 91 8576
TEST_TIME 92 8582
TEST_TIME 93 8607
TEST_TIME 94 8574
TEST_TIME 95 8551
TEST_TIME 96 8643
TEST_TIME 97 8639
TEST_TIME 98 8602
TEST_TIME 99 8614
TEST_TIME 100 8695
TEST_TIME 101 8644
TEST_TIME 102 8710
TEST_TIME 103 8739
TEST_TIME 104 8658
TEST_TIME 105 8615
TEST_TIME 106 8595
TEST_TIME 107 8582
TEST_TIME 108 8597
TEST_TIME 109 8578
TEST_TIME 110 8560
TEST_TIME 111 8540
TEST_TIME 112 8559
TEST_TIME 113 8573
TEST_TIME 114 8554
TEST_TIME 115 8600
TEST_TIME 116 8630
TEST_TIME 117 8654
TEST_TIME 118 8687
TEST_TIME 119 8716
TEST_TIME 120 8734

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-11-2020 10:27:33 to 03-11-2020 10:27:37.

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 1458691 1458691 0 0.00 0 30542 7 0 0
doc_list_biud 1458689 1458689 0 0.00 0 47031 32 0 0
sp_add_invoice_to_stock 5002 4879 123 2.45 42 208908 20314 0 123
sp_cancel_adding_invoice 5291 5291 0 0.00 4 56570 980 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 13151 371 0 0
sp_cancel_customer_reserve 519473 519473 0 0.00 1 47033 71 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 8718 114 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 9076 122 0 0
sp_cancel_supplier_invoice 5840 5830 10 0.17 1 36956 992 0 10
sp_cancel_supplier_order 399 399 0 0.00 8 963720 117275 0 0
sp_cancel_write_off 9398 9398 0 0.00 1 14781 135 0 0
sp_client_order 19473 19473 0 0.00 4 47819 422 0 0
sp_customer_reserve 446285 446285 0 0.00 0 97548 201 0 0
sp_fill_shopping_cart 30051 30051 0 0.00 1 18360 43 0 0
sp_fill_shopping_cart_clo_res 446281 446281 0 0.00 0 24999 18 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 54199 441 0 0
sp_kill_cost_storno 15788 15788 0 0.00 0 30528 69 0 0
sp_kill_qstorno_ret_qs2qd 1054573 1054573 0 0.00 0 46892 38 0 0
sp_kill_qty_storno 556542 556542 0 0.00 0 47030 83 0 0
sp_lock_selected_doc 19989 19989 0 0.00 0 9047 6 0 0
sp_make_cost_storno 26847 26847 0 0.00 0 24582 55 0 0
sp_multiply_rows_for_pdistr 26846 26846 0 0.00 0 5530 30 0 0
sp_multiply_rows_for_qdistr 19473 19473 0 0.00 0 47506 217 0 0
sp_pay_from_customer 6769 6769 0 0.00 4 24591 118 0 0
sp_pay_to_supplier 5613 5613 0 0.00 5 12262 138 0 0
sp_payment_common 12381 12381 0 0.00 1 10004 22 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 13144 336 0 0
sp_qd_handle_on_invoice_upd_sts 10165 10165 0 0.00 0 27707 411 0 0
sp_qd_handle_on_reserve_upd_sts 9590 9590 0 0.00 0 26334 58 0 0
sp_reserve_write_off 9590 9590 0 0.00 6 26392 178 0 0
sp_supplier_invoice 5264 5264 0 0.00 7 99016 3472 0 0
sp_supplier_order 5324 5324 0 0.00 6 43543 1430 0 0
srv_aggregate_perf_data 112 112 0 0.00 2775 191007 14167 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2102 117630 46067 0 0
srv_find_qd_qs_mism 1000882 1000882 0 0.00 0 27918 7 0 0
srv_make_invnt_saldo 432 432 0 0.00 52 99154 3906 0 0
srv_make_money_saldo 731 731 0 0.00 1 16352 624 0 0
srv_recalc_idx_stat 4 4 0 0.00 237098 489265 398494 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 4879 4879 0 0.00 0 15697 180 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3201 109 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 2501 13 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 710 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 1106 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1003 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 997 21 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 22380 1682 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1801 11 0 0
t$perf-norm:sp_client_order 19473 19473 0 0.00 0 9352 18 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 2138 10 0 0
t$perf-norm:sp_pay_from_customer 6769 6769 0 0.00 0 3655 7 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 10020 15 0 0
t$perf-norm:sp_reserve_write_off 9590 9590 0 0.00 0 3266 10 0 0
t$perf-norm:sp_supplier_invoice 5264 5264 0 0.00 0 6582 18 0 0
t$perf-norm:sp_supplier_order 5324 5324 0 0.00 0 6680 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 519 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 159 6 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 1038 6 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 716 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 6 0 0
v_add_invoice_to_stock 9994 9748 246 2.46 0 9001 20 0 246
v_all_customers 35126 35126 0 0.00 0 169 1 0 0
v_all_suppliers 29128 29128 0 0.00 0 169 1 0 0
v_all_wares 38942 38942 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1229 10 0 0
v_cancel_client_order 3374 3374 0 0.00 0 6152 8 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 3038 12 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 6149 13 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 4576 16 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 3301 22 0 20
v_cancel_supplier_order 798 798 0 0.00 0 7031 24 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4543 26 0 0
v_max_id_clo_ord 5322 5322 0 0.00 0 487 1 0 0
v_max_id_clo_res 925 925 0 0.00 0 4904 19 0 0
v_max_id_ord_sup 5258 5258 0 0.00 0 4499 6 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 111 2 0 0
v_max_non_paid_realizn 3773 3773 0 0.00 0 9999 6 0 0
v_min_id_clo_ord 5322 5322 0 0.00 0 2840 8 0 0
v_min_id_clo_res 925 925 0 0.00 0 5072 17 0 0
v_min_id_ord_sup 5258 5258 0 0.00 0 4238 7 0 0
v_min_non_paid_invoice 5612 5612 0 0.00 0 5144 14 0 0
v_min_non_paid_realizn 6769 6769 0 0.00 0 5521 12 0 0
v_our_firm 9808 9808 0 0.00 0 17 1 0 0
v_reserve_write_off 19180 19180 0 0.00 0 6438 7 0 0
x_lock_depdocs_on_canc_invoice 10742 10742 0 0.00 0 17216 110 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 33070 548 0 0
x_make_qstorno_1000_1200 5324 5324 0 0.00 2 43489 1341 0 0
x_make_qstorno_1200_2000 5264 5264 0 0.00 3 98997 3322 0 0
x_make_qstorno_2100_3300 446285 446285 0 0.00 0 97547 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2020 10:27:38 to 03-11-2020 10:27:42.

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:55 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:56 29405 8192000000 9719921264 10014183424 793171888 8.827 100 86 171 0 821251392 7582128 20497376
07:59:30 21419 8192000000 9760702000 10065399808 833744192 9.237 100 90 180 0 867969440 12744608 21480640
08:01:05 17336 8192000000 9802079408 10116300800 866536240 9.565 100 87 174 0 907760016 21183904 20039872
08:02:29 12859 8192000000 9856671408 10191343616 910295840 10.000 100 75 150 1 967278080 40275344 16706896
08:03:47 9804 8192000000 9916276624 10260328448 960376656 10.493 100 78 154 2 1028076976 51257792 16442528
08:04:58 4545 8192000000 9972179936 10323083264 990139056 10.783 100 90 179 1 1085806544 77979296 17688192
08:06:05 3015 8192000000 9996392704 10359103488 1016285392 11.036 100 85 167 2 1108954464 75389088 17279984
08:07:10 2421 8192000000 10022652912 10389131264 1045897792 11.321 100 93 187 0 1135394256 70342224 19154240
08:08:14 1822 8192000000 10052076544 10422734848 1058336144 11.441 100 90 180 1 1160159952 83550672 18273136
08:09:17 4988 8192000000 10034739744 10416685056 1066444512 11.518 100 65 123 2 1149961600 70941984 12575104
08:10:23 4659 8192000000 10011898320 10396176384 1073251424 11.583 100 43 85 2 1114540720 32684000 8605296
08:11:31 36408 8192000000 9992401120 10392838144 1081023552 11.657 100 32 64 5 1119876944 31889008 6964384
08:13:08 6622 8192000000 10014313856 10412363776 1092906464 11.770 100 48 96 0 1129987968 26128592 10952912
08:14:17 7253 8192000000 10010393792 10409660416 1095678640 11.797 100 36 69 2 1123157840 19489184 7990016
08:15:27 5188 8192000000 10013708144 10410840064 1095451536 11.794 100 46 90 1 1134198416 29451376 9295504
08:16:32 33779 8192000000 10011129296 10403024896 1089669088 11.740 99 67 133 3 1127111680 23078304 14364288
08:18:07 5361 8192000000 10005933152 10406277120 1084594192 11.691 100 36 72 4 1133478480 41354880 7529408
08:19:13 10903 8192000000 9969417616 10371747840 1045474800 11.317 99 28 49 3 1075246112 24190576 5580736
08:20:24 5373 8192000000 9934065344 10310320128 1005336336 10.930 98 43 88 3 1028400176 13613760 9450080
08:21:32 19923 8192000000 9953848064 10321772544 1024409072 11.115 100 71 143 1 1054254384 13576896 16268416
08:22:52 7136 8192000000 9935849632 10315489280 1008495648 10.961 100 35 68 0 1039271616 21838144 8937824
08:24:00 6410 8192000000 9956671744 10337742848 1032139344 11.189 99 35 71 2 1055276880 15110400 8027136
08:25:07 8684 8192000000 9969763584 10358247424 1048037104 11.342 99 45 89 2 1071194512 12620944 10536464
08:26:18 10053 8192000000 9993347744 10397507584 1056887056 11.427 100 38 76 1 1096510912 30905680 8718176
08:27:32 16330 8192000000 10015163968 10402603008 1075733680 11.607 100 46 92 0 1114832320 29700128 9398512
08:28:54 25887 8192000000 10052056688 10454761472 1092659904 11.768 100 49 99 0 1173912928 70817856 10435168
08:30:30 5397 8192000000 10084630976 10480218112 1111404080 11.946 100 53 106 1 1190508688 67403120 11701488
08:31:39 14760 8192000000 10138860672 10543706112 1119245184 12.020 100 49 98 0 1250547744 120880272 10422288
08:33:06 6342 8192000000 10155090832 10557657088 1132202032 12.142 100 46 92 2 1259306464 117557904 9546528
08:34:18 20888 8192000000 10182199312 10592960512 1139429712 12.210 100 54 106 1 1286492176 135662592 11399872
08:35:46 29603 8192000000 10190435728 10596478976 1147782448 12.289 100 54 105 1 1291927600 132048016 12097136
08:37:22 8354 8192000000 10228510256 10640216064 1153091648 12.339 100 63 126 0 1321329792 154885488 13352656
08:38:35 4994 8192000000 10277414240 10681278464 1166180048 12.461 100 82 163 0 1378675360 194416080 18079232
08:39:48 11309 8192000000 10233387232 10681778176 1173035744 12.525 100 64 126 1 1340751392 153649840 14065808
08:41:05 3417 8192000000 10264429216 10663231488 1173478176 12.529 100 83 164 0 1364094720 172620816 17995728
08:42:12 2101 8192000000 10256069680 10657816576 1137575600 12.193 98 80 160 0 1343530144 188030048 17924496
08:43:20 7464 8192000000 10320424704 10725191680 1150999296 12.319 99 74 146 0 1413227584 246317616 15910672
08:44:33 3703 8192000000 10293600768 10700050432 1168241536 12.480 100 69 136 0 1391382272 208073376 15067360
08:45:43 9028 8192000000 10293358704 10703253504 1154588048 12.353 100 65 130 0 1389908112 220087968 15232096
08:46:58 8371 8192000000 10268037472 10676293632 1153747264 12.345 99 46 89 1 1362882496 198837424 10297808
08:48:14 9250 8192000000 10310976560 10732597248 1155368208 12.360 100 39 79 1 1417182064 251530160 10283696
08:49:26 3239 8192000000 10354300848 10757378048 1137456048 12.192 99 75 152 3 1446412736 290055920 18900768
08:50:33 15722 8192000000 10396261152 10834710528 1151105440 12.320 99 39 78 2 1478151936 317055248 9991248
08:51:57 4527 8192000000 10408758976 10827091968 1141670432 12.231 99 49 97 0 1503241536 349805056 11766048
08:53:04 24417 8192000000 10400087408 10849083392 1130239904 12.124 100 36 72 0 1504155328 364800560 9114864
08:54:40 54807 8192000000 10168120512 10667556864 1090645648 11.749 97 25 49 0 1384378480 287004416 6728416
08:56:40 36289 8192000000 10139245952 10564333568 1077563024 11.624 96 33 67 0 1213202272 128099248 7540000
08:58:20 57241 8192000000 10118212384 10638843904 1110721456 11.939 100 15 30 0 1274305568 160002128 3581984
09:00:26 49446 8192000000 10034361888 10462556160 1104685904 11.882 99 17 33 1 1137667744 28454560 4527280
09:02:16 46279 8192000000 10052477552 10496819200 1109259008 11.925 99 9 18 0 1156805712 45598816 1947888
09:04:26 47604 8192000000 10086997552 10512015360 1135719680 12.175 100 48 96 0 1193566080 46548432 11297968
09:06:23 26361 8192000000 10107930224 10523770880 1143687088 12.250 100 56 110 1 1199817248 44567776 11562384
09:07:59 96021 8192000000 10151906080 10610847744 1146531056 12.277 99 33 67 1 1235157984 81807056 6819872
09:10:36 99598 8192000000 10072005104 10575405056 1120646464 12.033 98 16 32 1 1235028624 110732800 3649360
09:13:17 18925 8192000000 10086516688 10537381888 1133566448 12.155 99 8 16 1 1193338656 57538912 2233296
09:14:38 61156 8192000000 10059197296 10555310080 1133278656 12.152 100 18 36 1 1202095056 65525504 3290896
09:16:39 85689 8192000000 10062087376 10535407616 1131814544 12.138 99 9 18 0 1174746976 41100288 1832144
09:19:05 61998 8192000000 10083749040 10553536512 1137239376 12.190 99 15 30 1 1195213792 54612640 3361776
09:21:08 52253 8192000000 10070675520 10567122944 1117228064 12.001 98 6 12 1 1173378032 55158704 991264
09:23:06 47153 8192000000 10055925456 10502778880 1114906144 11.979 98 17 33 2 1145738768 27264848 3567776
09:24:54 35057 8192000000 10077891392 10509549568 1119072960 12.018 98 22 44 1 1158521984 34730816 4718208
09:26:29 51729 8192000000 10096171472 10541740032 1149249984 12.302 100 35 69 0 1185143120 28407136 7486000
09:28:32 49935 8192000000 10122914480 10560286720 1128956496 12.112 98 20 40 1 1198807696 66077120 3774080
09:30:30 55242 8192000000 10146857264 10607783936 1139379280 12.210 100 18 36 0 1247087232 103924656 3783296
09:32:26 67777 8192000000 10062132384 10499526656 1075666656 11.606 95 11 22 0 1105860240 28307584 1886000
09:34:41 107151 8192000000 10071943488 10508398592 1122419552 12.050 100 27 54 0 1168868848 41149632 5299664
09:37:38 75397 8192000000 10074652864 10541682688 1116079184 11.990 98 13 26 1 1172456064 53650224 2726656
09:39:55 54749 8192000000 10090944528 10535243776 1132204624 12.142 100 16 31 2 1190580512 54988608 3387280
09:41:57 32703 8192000000 10135975872 10560405504 1115188096 11.982 98 17 34 0 1201119984 82179024 3752864
09:43:31 70528 8192000000 10188783152 10630033408 1103713728 11.873 97 21 42 0 1236270192 127431616 5124848
09:45:46 58845 8192000000 10090431872 10534547456 1088259248 11.726 97 27 53 4 1157142416 63691072 5192096
09:47:49 66085 8192000000 10106294288 10519683072 1098449264 11.823 97 46 92 0 1150204032 42657984 9096784
09:49:56 77491 8192000000 10061422304 10490290176 1060649472 11.463 94 14 28 1 1103802896 40562160 2591264
09:52:16 100798 8192000000 10064466944 10489671680 1119686496 12.024 100 18 36 1 1168957200 45865904 3404800
09:55:18 98992 8192000000 10086561568 10571202560 1126999600 12.093 100 7 14 0 1173634832 45183024 1452208
09:58:03 79857 8192000000 10126910064 10605568000 1139472992 12.211 100 17 34 1 1239213680 95716272 4024416
10:01:43 65345 8192000000 10074214624 10569158656 1141367120 12.228 100 9 18 0 1215897120 72503840 2026160
10:03:50 26165 8192000000 10075746544 10504867840 1118058240 12.009 98 22 44 0 1160919568 38216512 4644816
10:05:22 67510 8192000000 10095553152 10584834048 1078692288 11.635 95 20 40 1 1162691776 79839856 4159632
10:07:31 56176 8192000000 10121346432 10557358080 1111098288 11.943 98 25 50 0 1193343488 77157200 5088000
10:09:29 89599 8192000000 10088634256 10545790976 1141696704 12.231 100 18 36 0 1198283728 53076400 3510624
10:12:35 55181 8192000000 10071065968 10560323584 1134733216 12.166 99 9 18 0 1185564256 49227824 1603216
10:14:33 51729 8192000000 10082883312 10556960768 1132607584 12.146 99 13 27 0 1183872448 48779872 2484992
10:16:32 53099 8192000000 10126222320 10572795904 1129254944 12.114 98 37 74 0 1190762912 53315760 8192208
10:19:00 80118 8192000000 10127832912 10597560320 1146440832 12.276 100 16 32 0 1214985024 65174624 3369568
10:21:23 117629 8192000000 10088648464 10519474176 1080737680 11.655 94 17 34 1 1133585792 49538960 3309152
10:24:22 81212 8192000000 10117612000 10644926464 1108082224 11.914 97 17 34 0 1148909392 37168704 3658464

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-11-2020 10:27:43 to 03-11-2020 10:27:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 03-11-2020 10:27:59 to 03-11-2020 10:28:01.

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          227592
MON$OLDEST_ACTIVE               228398
MON$OLDEST_SNAPSHOT             228398
MON$NEXT_TRANSACTION            228398
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                       2089120
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2243 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.2243 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 ---
:51: 
:52: mscs-test-161.budzdorov.ru    Tue Nov  3 10:27:00 2020
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
--- end of diff output ---

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

03.11.20 10:28:02. 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-03 10:28:05 No severe PSQL-related problems occured
03.11.20 10:28:06. End of report.