Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.2020 04: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 16955884 kB
MemAvailable 31388348 kB
Buffers 20 kB
Cached 14626572 kB
SwapCached 2828 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-06 04:26:53
DTS_END                         2020-11-06 04:26:53.6370
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/33684
TRN_ID                          225457
ATT_ID                          678
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: 8472.38 30209 1016685
customer order: creation 155.38 435 18645
customer order: refuse 13.23 392 1588
order to supplier: creation 43.13 1542 5175
order to supplier: removal 3.24 128445 389
invoice (draft): creation 43.78 4355 5254
invoice (draft): removal 46.93 1023 5632
invoice accept: apply 40.00 19586 4800
invoice accept: cancel 43.14 1054 5177
customer reserve: creation 3653.36 191 438403
customer reserve: removal 4157.50 79 498900
realization accept: apply 77.46 208 9295
realization accept: cancel 75.97 139 9116
payment from customer: creation 55.13 131 6615
payment from customer: removal 5.20 111 624
payment to supplier: creation 45.59 159 5471
payment to supplier: removal 3.08 114 369
service: total inventory turnovers 3.64 4265 437
service: total monetary turnovers 5.92 745 710
service: aggregate perf. data 0.68 28649 81
service: refresh index statistics 0.03 412563 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-11-2020 04:28:00 to 06-11-2020 04:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5958
TEST_TIME 2 6405
TEST_TIME 3 6905
TEST_TIME 4 6397
TEST_TIME 5 6025
TEST_TIME 6 6578
TEST_TIME 7 7125
TEST_TIME 8 7210
TEST_TIME 9 6896
TEST_TIME 10 6872
TEST_TIME 11 6753
TEST_TIME 12 6660
TEST_TIME 13 7017
TEST_TIME 14 6951
TEST_TIME 15 6869
TEST_TIME 16 7021
TEST_TIME 17 7108
TEST_TIME 18 7402
TEST_TIME 19 7382
TEST_TIME 20 7568
TEST_TIME 21 7966
TEST_TIME 22 8186
TEST_TIME 23 8079
TEST_TIME 24 7998
TEST_TIME 25 8137
TEST_TIME 26 8462
TEST_TIME 27 8638
TEST_TIME 28 8761
TEST_TIME 29 8620
TEST_TIME 30 8810
TEST_TIME 31 9050
TEST_TIME 32 9579
TEST_TIME 33 10355
TEST_TIME 34 10647
TEST_TIME 35 10529
TEST_TIME 36 10396
TEST_TIME 37 10218
TEST_TIME 38 10001
TEST_TIME 39 9851
TEST_TIME 40 9738
TEST_TIME 41 9602
TEST_TIME 42 9588
TEST_TIME 43 9569
TEST_TIME 44 9539
TEST_TIME 45 9441
TEST_TIME 46 9377
TEST_TIME 47 9275
TEST_TIME 48 9180
TEST_TIME 49 9142
TEST_TIME 50 9038
TEST_TIME 51 9070
TEST_TIME 52 9086
TEST_TIME 53 8984
TEST_TIME 54 8888
TEST_TIME 55 8864
TEST_TIME 56 8879
TEST_TIME 57 8768
TEST_TIME 58 8712
TEST_TIME 59 8663
TEST_TIME 60 8617
TEST_TIME 61 8587
TEST_TIME 62 8712
TEST_TIME 63 8679
TEST_TIME 64 8662
TEST_TIME 65 8681
TEST_TIME 66 8748
TEST_TIME 67 8791
TEST_TIME 68 8746
TEST_TIME 69 8812
TEST_TIME 70 8891
TEST_TIME 71 8916
TEST_TIME 72 8843
TEST_TIME 73 8756
TEST_TIME 74 8699
TEST_TIME 75 8674
TEST_TIME 76 8671
TEST_TIME 77 8648
TEST_TIME 78 8606
TEST_TIME 79 8684
TEST_TIME 80 8673
TEST_TIME 81 8690
TEST_TIME 82 8695
TEST_TIME 83 8750
TEST_TIME 84 8716
TEST_TIME 85 8672
TEST_TIME 86 8646
TEST_TIME 87 8677
TEST_TIME 88 8773
TEST_TIME 89 8726
TEST_TIME 90 8716
TEST_TIME 91 8656
TEST_TIME 92 8587
TEST_TIME 93 8620
TEST_TIME 94 8544
TEST_TIME 95 8528
TEST_TIME 96 8500
TEST_TIME 97 8464
TEST_TIME 98 8482
TEST_TIME 99 8511
TEST_TIME 100 8486
TEST_TIME 101 8559
TEST_TIME 102 8544
TEST_TIME 103 8590
TEST_TIME 104 8551
TEST_TIME 105 8600
TEST_TIME 106 8659
TEST_TIME 107 8669
TEST_TIME 108 8639
TEST_TIME 109 8723
TEST_TIME 110 8757
TEST_TIME 111 8683
TEST_TIME 112 8629
TEST_TIME 113 8576
TEST_TIME 114 8538
TEST_TIME 115 8493
TEST_TIME 116 8495
TEST_TIME 117 8493
TEST_TIME 118 8484
TEST_TIME 119 8457
TEST_TIME 120 8477

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-11-2020 04:28:04 to 06-11-2020 04:28:07.

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 1420399 1420399 0 0.00 0 19018 7 0 0
doc_list_biud 1420396 1420396 0 0.00 0 54246 35 0 0
sp_add_invoice_to_stock 4901 4800 101 2.06 31 225140 19586 0 101
sp_cancel_adding_invoice 5177 5177 0 0.00 5 66712 1055 0 0
sp_cancel_client_order 1588 1588 0 0.00 4 7303 393 0 0
sp_cancel_customer_reserve 498900 498900 0 0.00 1 54249 80 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 7040 111 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 7072 114 0 0
sp_cancel_supplier_invoice 5642 5632 10 0.17 2 21503 1023 0 10
sp_cancel_supplier_order 389 389 0 0.00 7 1270036 128446 0 0
sp_cancel_write_off 9116 9116 0 0.00 1 8121 140 0 0
sp_client_order 18645 18645 0 0.00 4 33155 435 0 0
sp_customer_reserve 438403 438403 0 0.00 0 81149 191 0 0
sp_fill_shopping_cart 29042 29042 0 0.00 1 73310 58 0 0
sp_fill_shopping_cart_clo_res 438396 438396 0 0.00 0 28892 18 0 0
sp_get_clo_for_invoice 4791 4791 0 0.00 0 60420 453 0 0
sp_kill_cost_storno 15286 15286 0 0.00 0 8119 72 0 0
sp_kill_qstorno_ret_qs2qd 1012937 1012937 0 0.00 0 54243 42 0 0
sp_kill_qty_storno 534885 534885 0 0.00 0 54246 92 0 0
sp_lock_selected_doc 19415 19415 0 0.00 0 9963 7 0 0
sp_make_cost_storno 26172 26172 0 0.00 0 13115 65 0 0
sp_multiply_rows_for_pdistr 26172 26172 0 0.00 0 13827 35 0 0
sp_multiply_rows_for_qdistr 18645 18645 0 0.00 0 16501 232 0 0
sp_pay_from_customer 6615 6615 0 0.00 4 17975 132 0 0
sp_pay_to_supplier 5471 5471 0 0.00 4 13847 159 0 0
sp_payment_common 12086 12086 0 0.00 1 7459 20 0 0
sp_qd_handle_on_cancel_clo 1588 1588 0 0.00 0 7289 358 0 0
sp_qd_handle_on_invoice_upd_sts 9965 9965 0 0.00 0 48771 450 0 0
sp_qd_handle_on_reserve_upd_sts 9295 9295 0 0.00 0 14112 64 0 0
sp_reserve_write_off 9295 9295 0 0.00 6 18920 209 0 0
sp_supplier_invoice 5254 5254 0 0.00 7 132303 4355 0 0
sp_supplier_order 5175 5175 0 0.00 5 36318 1542 0 0
srv_aggregate_perf_data 82 81 1 1.21 2373 837884 28649 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1038 145116 49891 0 0
srv_find_qd_qs_mism 971121 971121 0 0.00 0 19018 6 0 0
srv_make_invnt_saldo 437 437 0 0.00 22 141794 4265 0 0
srv_make_money_saldo 710 710 0 0.00 1 16432 745 0 0
srv_recalc_idx_stat 4 4 0 0.00 217228 549459 412563 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4800 4800 0 0.00 0 6836 192 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 3304 118 0 0
t$perf-norm:sp_cancel_client_order 1588 1588 0 0.00 0 2949 21 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 3538 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 1802 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 125 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 1246 28 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 18521 1513 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 1301 10 0 0
t$perf-norm:sp_client_order 18645 18645 0 0.00 0 4679 19 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 3572 13 0 0
t$perf-norm:sp_pay_from_customer 6615 6615 0 0.00 0 5315 10 0 0
t$perf-norm:sp_pay_to_supplier 5471 5471 0 0.00 0 5282 10 0 0
t$perf-norm:sp_reserve_write_off 9295 9295 0 0.00 0 5341 10 0 0
t$perf-norm:sp_supplier_invoice 5254 5254 0 0.00 0 3338 21 0 0
t$perf-norm:sp_supplier_order 5175 5175 0 0.00 0 6406 20 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 76 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2348 40 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 628 10 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 2463 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 9762 9560 202 2.06 0 6297 21 0 202
v_all_customers 34358 34358 0 0.00 0 17 1 0 0
v_all_suppliers 28428 28428 0 0.00 0 24 1 0 0
v_all_wares 37266 37266 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2056 11 0 0
v_cancel_client_order 3176 3176 0 0.00 0 4502 6 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 7035 17 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 3542 10 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 7055 24 0 0
v_cancel_supplier_invoice 728 708 20 2.74 0 1897 18 0 20
v_cancel_supplier_order 778 778 0 0.00 0 942 6 0 0
v_cancel_write_off 1948 1948 0 0.00 0 7887 22 0 0
v_max_id_clo_ord 5168 5168 0 0.00 0 770 2 0 0
v_max_id_clo_res 959 959 0 0.00 0 1484 6 0 0
v_max_id_ord_sup 5241 5241 0 0.00 0 4567 5 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 1573 3 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 1003 3 0 0
v_min_id_clo_ord 5168 5168 0 0.00 0 3322 8 0 0
v_min_id_clo_res 959 959 0 0.00 0 9200 22 0 0
v_min_id_ord_sup 5241 5241 0 0.00 0 7061 13 0 0
v_min_non_paid_invoice 5471 5471 0 0.00 0 5004 12 0 0
v_min_non_paid_realizn 6615 6615 0 0.00 0 5084 12 0 0
v_our_firm 9264 9264 0 0.00 0 22 1 0 0
v_reserve_write_off 18586 18586 0 0.00 0 8611 8 0 0
x_lock_depdocs_on_canc_invoice 10455 10455 0 0.00 0 20352 128 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 5051 372 0 0
x_make_qstorno_1000_1200 5175 5175 0 0.00 2 36310 1444 0 0
x_make_qstorno_1200_2000 5254 5254 0 0.00 4 117556 4131 0 0
x_make_qstorno_2100_3300 438403 438403 0 0.00 0 81090 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-11-2020 04:28:08 to 06-11-2020 04:28:12.

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
01:56:57 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:57 38698 8192000000 9720040848 10014584832 793371312 8.829 100 93 186 0 823752736 8039152 22342272
01:59:39 12147 8192000000 9751409056 10052755456 824435824 9.143 100 95 190 0 861105552 13432928 23236800
02:01:00 21002 8192000000 9795326240 10111647744 857017264 9.470 100 80 161 2 899109344 23534624 18557456
02:02:28 14250 8192000000 9859383760 10191200256 914260704 10.039 100 80 160 3 975217648 43414048 17542896
02:03:49 8518 8192000000 9926736288 10272251904 963939696 10.528 100 81 162 1 1040654416 60019344 16695376
02:05:02 6049 8192000000 9972103152 10325757952 994606528 10.826 100 84 168 2 1090998416 78962672 17429216
02:06:10 4860 8192000000 10007398144 10366078976 1021461248 11.086 100 86 171 2 1117516000 79278784 16775968
02:07:16 1526 8192000000 10006471968 10372386816 1044927024 11.312 100 88 176 1 1120523248 57944944 17651280
02:08:19 1459 8192000000 10033407840 10406219776 1066430832 11.518 100 92 183 3 1141748576 55300640 20017104
02:09:22 6006 8192000000 10011920272 10396385280 1070360704 11.556 100 71 143 1 1127210720 41877056 14972960
02:10:29 4441 8192000000 9995392000 10391498752 1073199744 11.583 100 48 96 3 1117879280 34387552 10291984
02:11:35 4574 8192000000 9999514512 10398380032 1084846352 11.694 100 51 101 1 1119492928 23304176 11342400
02:12:40 10519 8192000000 10007156240 10418130944 1090325792 11.746 100 27 51 1 1114917328 18324880 6266656
02:13:52 62033 8192000000 10022390864 10428342272 1095386496 11.794 100 35 70 2 1125204352 22602848 7215008
02:15:55 8087 8192000000 10006930848 10411155456 1094427184 11.785 100 31 62 0 1118226112 16499632 7299296
02:17:08 10212 8192000000 9992529888 10401284096 1063487936 11.490 97 35 69 3 1100143776 29123792 7532048
02:18:19 9181 8192000000 9991117392 10395324416 1056432336 11.422 98 26 52 3 1090890272 28963152 5494784
02:19:32 51558 8192000000 9960627040 10328883200 979335200 10.678 93 54 105 1 1007132448 17360480 10436768
02:21:23 8010 8192000000 9923848448 10308947968 977536656 10.660 96 30 58 3 1015110768 31474624 6099488
02:22:34 8288 8192000000 9953420480 10346520576 1021713568 11.089 98 29 55 1 1036113600 7304128 7095904
02:23:47 5932 8192000000 9967777840 10359816192 1049964848 11.360 100 39 79 2 1072490912 13503728 9022336
02:24:54 54830 8192000000 9969636928 10364411904 1057017664 11.428 100 27 54 0 1077736880 14995280 5723936
02:26:49 8427 8192000000 9986767152 10381336576 1069667632 11.549 100 44 88 0 1092232832 12695024 9870176
02:28:03 4107 8192000000 10021370128 10411978752 1087788048 11.722 100 53 104 2 1124776992 25860784 11128160
02:29:11 11500 8192000000 10057237984 10457325568 1101606896 11.853 100 64 128 2 1169936544 54188800 14140848
02:30:27 39221 8192000000 10069340032 10486247424 1110484592 11.937 100 30 60 1 1197323248 80322944 6515712
02:32:16 4977 8192000000 10120238464 10518114304 1116377600 11.993 100 75 149 2 1231240032 99640272 15222160
02:33:25 12260 8192000000 10110406176 10521653248 1134833856 12.167 100 49 98 2 1237911280 92824896 10252528
02:34:45 32487 8192000000 10135665216 10554847232 1144498112 12.258 100 60 117 1 1234311120 77533376 12279632
02:36:21 5515 8192000000 10166013808 10568228864 1146850400 12.280 100 61 118 0 1267074672 107659152 12565120
02:37:36 2683 8192000000 10183347024 10594578432 1165067856 12.451 100 72 140 1 1289811040 109591296 15151888
02:38:47 13432 8192000000 10205373920 10616438784 1163092320 12.432 99 67 129 3 1303136320 126093344 13950656
02:40:03 2528 8192000000 10213073728 10612883456 1169415568 12.491 100 84 167 0 1313599952 125313040 18871344
02:41:09 5891 8192000000 10269372672 10675675136 1186778848 12.653 100 69 137 1 1369599024 167965888 14854288
02:42:22 2395 8192000000 10289010640 10688270336 1158339776 12.388 98 78 152 1 1372555136 196499264 17716096
02:43:29 1482 8192000000 10324323776 10719543296 1176049440 12.553 100 88 175 1 1414366496 218567680 19749376
02:44:37 4862 8192000000 10332775920 10729566208 1155681296 12.363 100 88 174 1 1428011424 252443264 19886864
02:45:46 1230 8192000000 10363304560 10756329472 1149523072 12.305 100 88 176 0 1459211488 289315264 20373152
02:46:53 1036 8192000000 10387495264 10778460160 1141435376 12.229 100 93 179 2 1486612384 323852928 21324080
02:47:57 6299 8192000000 10377830256 10782007296 1143214512 12.246 100 59 118 1 1480922976 324466432 13242032
02:49:05 11943 8192000000 10337429760 10795405312 1123608096 12.061 98 35 70 2 1424821776 292053536 9160144
02:50:27 1695 8192000000 10399761984 10800005120 1143730416 12.251 100 77 154 1 1493310448 331156448 18423584
02:51:36 9231 8192000000 10413538672 10825191424 1141700400 12.232 100 47 94 2 1506807920 352554976 12552544
02:53:04 9310 8192000000 10402205504 10849247232 1157306128 12.378 100 38 76 1 1516406656 349501920 9598608
02:54:15 13868 8192000000 10401995008 10856042496 1156335328 12.369 100 29 58 0 1491249168 327146096 7767744
02:55:36 16988 8192000000 10447007280 10879057920 1133107920 12.151 98 36 72 2 1512053744 369906992 9038832
02:56:57 12562 8192000000 10452651232 10926034944 1149720752 12.307 100 27 54 1 1555610320 398447936 7441632
02:58:11 19882 8192000000 10360022208 10891169792 1148979392 12.300 100 23 46 3 1521406592 366299328 6127872
02:59:45 78484 8192000000 10085390480 10556825600 1094789456 11.788 97 17 34 2 1170169968 71862480 3518032
03:02:13 44091 8192000000 10034528576 10501226496 1099366064 11.832 98 9 18 2 1139853920 38779040 1708816
03:04:11 65603 8192000000 10087826624 10518384640 1099071856 11.829 97 26 52 1 1137526928 32925328 5529744
03:06:51 85718 8192000000 10112651456 10552152064 1099605360 11.834 97 19 39 0 1182419440 78951824 3862256
03:09:18 144779 8192000000 10062524144 10535428096 1118069648 12.009 99 24 48 0 1207431360 83894688 5467024
03:12:50 145114 8192000000 10098024320 10527567872 1119456464 12.022 98 45 90 3 1166755728 38295200 9004064
03:16:15 90602 8192000000 10110368032 10602004480 1138510400 12.202 99 20 39 1 1222590480 79474176 4605904
03:20:13 89175 8192000000 10109027232 10582073344 1138791552 12.204 99 15 29 0 1214502160 72682048 3028560
03:22:44 87040 8192000000 10060372208 10542276608 1143255936 12.246 100 8 16 1 1177375440 32563728 1555776
03:25:12 65443 8192000000 10085646848 10543726592 1132285920 12.143 98 25 50 1 1176021504 38343856 5391728
03:27:18 69235 8192000000 10082501232 10565783552 1128571008 12.108 98 18 36 0 1183255104 50869488 3814608
03:29:28 39313 8192000000 10087057008 10523152384 1138683840 12.203 99 28 56 0 1173608384 29220096 5704448
03:31:10 98874 8192000000 10091326656 10539139072 1124605264 12.070 98 12 24 1 1160462240 33122480 2734496
03:33:52 32775 8192000000 10111503408 10556440576 1143501392 12.248 100 41 82 2 1183192704 31092048 8599264
03:35:29 122291 8192000000 10067346720 10540597248 1043120336 11.295 91 8 16 0 1117013408 72117936 1775136
03:38:41 94451 8192000000 10053465568 10499850240 1123945952 12.064 100 18 36 0 1148953104 21567760 3439392
03:41:22 60046 8192000000 10043891040 10525310976 1089215936 11.735 96 9 18 0 1129225168 38189648 1819584
03:43:23 37955 8192000000 10049990880 10482745344 1097331936 11.812 98 21 42 0 1133954880 32153456 4469488
03:45:05 58354 8192000000 10047805440 10535563264 1094474624 11.785 97 17 34 0 1165331024 67052288 3804112
03:47:05 35027 8192000000 10102658048 10538876928 1129215040 12.114 100 34 68 2 1204912304 67869168 7828096
03:48:46 87521 8192000000 10062050464 10479325184 1100850816 11.846 98 11 22 1 1144790080 41747360 2191904
03:51:19 65105 8192000000 10150773024 10593374208 1126856640 12.092 100 29 58 0 1200258864 67802432 5599792
03:53:25 140484 8192000000 10064303424 10494767104 1091676912 11.759 97 10 20 1 1155729520 61951712 2100896
03:56:47 59380 8192000000 10139644976 10574876672 1113959664 11.970 98 39 78 2 1191375376 69682512 7733200
03:59:55 65351 8192000000 10097682448 10590900224 1137069584 12.188 100 12 24 2 1195512896 56047968 2395344
04:02:01 78537 8192000000 10095853584 10551836672 1135524800 12.173 100 15 30 0 1206301808 67344000 3433008
04:04:21 73832 8192000000 10113217760 10592055296 1137602432 12.193 99 25 50 1 1205690944 62351008 5737504
04:07:19 68868 8192000000 10067045904 10504716288 1120668192 12.033 99 15 30 2 1151762528 28617952 2476384
04:09:28 55631 8192000000 10048521840 10474414080 1076034288 11.610 95 12 23 1 1102063424 23473776 2555360
04:11:26 126870 8192000000 10081549856 10538479616 1082657920 11.673 96 11 22 1 1131629328 46824848 2146560
04:14:34 67036 8192000000 10092801872 10597851136 1091773232 11.760 97 25 49 1 1190056576 93195616 5087728
04:16:50 123473 8192000000 10052356832 10515664896 1113206192 11.963 99 12 24 0 1156672528 41050848 2415488
04:20:43 98966 8192000000 10055708464 10530967552 1119526800 12.023 99 8 16 1 1182951872 61737072 1688000
04:23:23 99884 8192000000 10079120720 10516787200 1100737824 11.845 97 20 40 1 1138461792 33044704 4679264
04:26:27 32162 8192000000 9377669968 9690464256 487997136 5.622 44 14 28 1 518849264 28270688 2581440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2020 04:28:14 to 06-11-2020 04:28:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 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 06-11-2020 04:28:30 to 06-11-2020 04:28:32.

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          225456
MON$OLDEST_ACTIVE               225704
MON$OLDEST_SNAPSHOT             225704
MON$NEXT_TRANSACTION            225704
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                       2079648
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             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2247 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.2247 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Nov  6 04:27:01 2020
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Nov  6 04:27:03 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Nov  6 04:27:04 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

06.11.20 04:28: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
2020-11-06 04:28:35 No severe PSQL-related problems occured
06.11.20 04:28:36. End of report.