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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16726776 kB
MemAvailable 31434960 kB
Buffers 20 kB
Cached 14900484 kB
SwapCached 2832 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-08 04:26:53
DTS_END                         2020-11-08 04:26:53.5230
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/36488
TRN_ID                          238955
ATT_ID                          647
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: 9079.32 21500 1089518
customer order: creation 175.22 425 21026
customer order: refuse 14.76 308 1771
order to supplier: creation 46.48 1348 5578
order to supplier: removal 3.63 106147 435
invoice (draft): creation 45.64 3453 5477
invoice (draft): removal 49.80 900 5976
invoice accept: apply 42.82 17586 5138
invoice accept: cancel 44.55 855 5346
customer reserve: creation 3975.44 172 477053
customer reserve: removal 4384.52 70 526143
realization accept: apply 85.73 163 10287
realization accept: cancel 79.72 110 9567
payment from customer: creation 59.93 104 7191
payment from customer: removal 5.45 88 654
payment to supplier: creation 50.68 116 6082
payment to supplier: removal 3.89 68 467
service: total inventory turnovers 3.83 3734 459
service: total monetary turnovers 6.46 616 775
service: aggregate perf. data 0.74 19637 89
service: refresh index statistics 0.03 274093 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3002
TEST_TIME 2 2799
TEST_TIME 3 4016
TEST_TIME 4 4546
TEST_TIME 5 4789
TEST_TIME 6 5197
TEST_TIME 7 4975
TEST_TIME 8 5531
TEST_TIME 9 5852
TEST_TIME 10 6113
TEST_TIME 11 6073
TEST_TIME 12 6068
TEST_TIME 13 6732
TEST_TIME 14 7164
TEST_TIME 15 7148
TEST_TIME 16 7479
TEST_TIME 17 8066
TEST_TIME 18 8237
TEST_TIME 19 8674
TEST_TIME 20 8817
TEST_TIME 21 9036
TEST_TIME 22 9239
TEST_TIME 23 9500
TEST_TIME 24 9490
TEST_TIME 25 9546
TEST_TIME 26 9648
TEST_TIME 27 9688
TEST_TIME 28 9974
TEST_TIME 29 10393
TEST_TIME 30 11164
TEST_TIME 31 11184
TEST_TIME 32 10965
TEST_TIME 33 11195
TEST_TIME 34 11144
TEST_TIME 35 11077
TEST_TIME 36 11027
TEST_TIME 37 10889
TEST_TIME 38 10639
TEST_TIME 39 10478
TEST_TIME 40 10332
TEST_TIME 41 10287
TEST_TIME 42 10215
TEST_TIME 43 10111
TEST_TIME 44 10206
TEST_TIME 45 10088
TEST_TIME 46 10122
TEST_TIME 47 10143
TEST_TIME 48 10060
TEST_TIME 49 10064
TEST_TIME 50 10110
TEST_TIME 51 10141
TEST_TIME 52 10180
TEST_TIME 53 10127
TEST_TIME 54 10151
TEST_TIME 55 10239
TEST_TIME 56 10178
TEST_TIME 57 10154
TEST_TIME 58 10240
TEST_TIME 59 10241
TEST_TIME 60 10202
TEST_TIME 61 10059
TEST_TIME 62 9998
TEST_TIME 63 9940
TEST_TIME 64 9865
TEST_TIME 65 9774
TEST_TIME 66 9716
TEST_TIME 67 9827
TEST_TIME 68 9806
TEST_TIME 69 9771
TEST_TIME 70 9827
TEST_TIME 71 9810
TEST_TIME 72 9762
TEST_TIME 73 9805
TEST_TIME 74 9827
TEST_TIME 75 9886
TEST_TIME 76 9842
TEST_TIME 77 9837
TEST_TIME 78 9863
TEST_TIME 79 9800
TEST_TIME 80 9683
TEST_TIME 81 9600
TEST_TIME 82 9557
TEST_TIME 83 9477
TEST_TIME 84 9480
TEST_TIME 85 9443
TEST_TIME 86 9410
TEST_TIME 87 9398
TEST_TIME 88 9400
TEST_TIME 89 9414
TEST_TIME 90 9408
TEST_TIME 91 9446
TEST_TIME 92 9481
TEST_TIME 93 9480
TEST_TIME 94 9504
TEST_TIME 95 9538
TEST_TIME 96 9519
TEST_TIME 97 9448
TEST_TIME 98 9385
TEST_TIME 99 9380
TEST_TIME 100 9346
TEST_TIME 101 9299
TEST_TIME 102 9262
TEST_TIME 103 9276
TEST_TIME 104 9251
TEST_TIME 105 9323
TEST_TIME 106 9296
TEST_TIME 107 9310
TEST_TIME 108 9324
TEST_TIME 109 9330
TEST_TIME 110 9314
TEST_TIME 111 9360
TEST_TIME 112 9315
TEST_TIME 113 9239
TEST_TIME 114 9209
TEST_TIME 115 9178
TEST_TIME 116 9160
TEST_TIME 117 9140
TEST_TIME 118 9113
TEST_TIME 119 9097
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-11-2020 04:28:34 to 08-11-2020 04:28:38.

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 1530407 1530407 0 0.00 0 17714 6 0 0
doc_list_biud 1530406 1530406 0 0.00 0 48907 31 0 0
sp_add_invoice_to_stock 5246 5138 108 2.05 34 191636 17587 0 108
sp_cancel_adding_invoice 5346 5346 0 0.00 5 72720 855 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 11804 309 0 0
sp_cancel_customer_reserve 526143 526143 0 0.00 1 48909 71 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 5967 88 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 2 3637 69 0 0
sp_cancel_supplier_invoice 5984 5976 8 0.13 2 13828 901 0 8
sp_cancel_supplier_order 435 435 0 0.00 6 1163031 106147 0 0
sp_cancel_write_off 9567 9567 0 0.00 1 7950 111 0 0
sp_client_order 21026 21026 0 0.00 4 25950 425 0 0
sp_customer_reserve 477053 477053 0 0.00 0 89597 172 0 0
sp_fill_shopping_cart 32052 32052 0 0.00 1 12907 37 0 0
sp_fill_shopping_cart_clo_res 477051 477051 0 0.00 0 28820 16 0 0
sp_get_clo_for_invoice 5136 5136 0 0.00 0 45099 354 0 0
sp_kill_cost_storno 16034 16034 0 0.00 0 4755 55 0 0
sp_kill_qstorno_ret_qs2qd 1068264 1068264 0 0.00 0 48906 38 0 0
sp_kill_qty_storno 564660 564660 0 0.00 0 48907 82 0 0
sp_lock_selected_doc 21327 21327 0 0.00 0 8196 4 0 0
sp_make_cost_storno 28696 28696 0 0.00 0 12993 49 0 0
sp_multiply_rows_for_pdistr 28696 28696 0 0.00 0 17177 30 0 0
sp_multiply_rows_for_qdistr 21026 21026 0 0.00 0 25926 230 0 0
sp_pay_from_customer 7191 7191 0 0.00 4 11953 104 0 0
sp_pay_to_supplier 6082 6082 0 0.00 5 10974 117 0 0
sp_payment_common 13273 13273 0 0.00 1 9209 20 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 11797 287 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 18435 380 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 20800 49 0 0
sp_reserve_write_off 10287 10287 0 0.00 6 20814 164 0 0
sp_supplier_invoice 5477 5477 0 0.00 6 93084 3453 0 0
sp_supplier_order 5578 5578 0 0.00 6 25627 1349 0 0
srv_aggregate_perf_data 89 89 0 0.00 3154 294346 19637 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 861 130782 44210 0 0
srv_find_qd_qs_mism 1040351 1040351 0 0.00 0 17713 5 0 0
srv_make_invnt_saldo 459 459 0 0.00 63 121523 3734 0 0
srv_make_money_saldo 775 775 0 0.00 1 15940 617 0 0
srv_recalc_idx_stat 4 4 0 0.00 117136 479376 274094 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5138 5138 0 0.00 0 28381 160 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 7087 84 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 4206 12 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 738 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 1030 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 656 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 616 10 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 33692 1507 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 4318 12 0 0
t$perf-norm:sp_client_order 21026 21026 0 0.00 0 43433 20 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 913 6 0 0
t$perf-norm:sp_pay_from_customer 7191 7191 0 0.00 0 44698 15 0 0
t$perf-norm:sp_pay_to_supplier 6082 6082 0 0.00 0 5238 9 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 19174 10 0 0
t$perf-norm:sp_supplier_invoice 5477 5477 0 0.00 0 21934 20 0 0
t$perf-norm:sp_supplier_order 5578 5578 0 0.00 0 5751 16 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 512 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1885 30 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 24081 66 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 872 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10482 10266 216 2.06 0 7630 16 0 216
v_all_customers 37940 37940 0 0.00 0 85 1 0 0
v_all_suppliers 30940 30940 0 0.00 0 16 1 0 0
v_all_wares 42044 42044 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 493 6 0 0
v_cancel_client_order 3542 3542 0 0.00 0 1598 4 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 2143 8 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 2662 6 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 329 3 0 0
v_cancel_supplier_invoice 840 824 16 1.90 0 2002 16 0 16
v_cancel_supplier_order 870 870 0 0.00 0 829 5 0 0
v_cancel_write_off 2090 2090 0 0.00 0 5987 20 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 267 1 0 0
v_max_id_clo_res 982 982 0 0.00 0 1563 13 0 0
v_max_id_ord_sup 5455 5455 0 0.00 0 3070 3 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 347 2 0 0
v_max_non_paid_realizn 3887 3887 0 0.00 0 9203 6 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 5801 6 0 0
v_min_id_clo_res 982 982 0 0.00 0 2798 10 0 0
v_min_id_ord_sup 5455 5455 0 0.00 0 5847 5 0 0
v_min_non_paid_invoice 6082 6082 0 0.00 0 5690 12 0 0
v_min_non_paid_realizn 7191 7191 0 0.00 0 4970 13 0 0
v_our_firm 10712 10712 0 0.00 0 18 1 0 0
v_reserve_write_off 20570 20570 0 0.00 0 3763 5 0 0
x_lock_depdocs_on_canc_invoice 10910 10910 0 0.00 0 13618 106 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 17049 301 0 0
x_make_qstorno_1000_1200 5578 5578 0 0.00 2 25618 1268 0 0
x_make_qstorno_1200_2000 5477 5477 0 0.00 3 93026 3318 0 0
x_make_qstorno_2100_3300 477053 477053 0 0.00 0 89596 154 0 0

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

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 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:57 33116 8192000000 9723362944 10021691392 800991600 8.906 100 90 180 1 830587600 7637360 21958640
01:59:38 22001 8192000000 9764304480 10070097920 835959696 9.259 100 91 182 0 871456464 13565120 21931648
02:01:05 13066 8192000000 9802274176 10115977216 857756800 9.478 100 84 168 0 900099824 23086608 19256416
02:02:22 10385 8192000000 9845993312 10178949120 903341984 9.931 100 76 152 2 962450480 42458064 16650432
02:03:43 11434 8192000000 9910270256 10255261696 959538176 10.484 100 84 168 0 1028889488 52064848 17286464
02:04:59 5710 8192000000 9961295584 10316128256 990406144 10.785 100 78 156 1 1072098272 66023088 15669040
02:06:07 2421 8192000000 10008034192 10369781760 1022454112 11.096 100 89 178 2 1122682672 82219296 18009264
02:07:10 1204 8192000000 10015877632 10385707008 1050473872 11.365 100 90 178 2 1139738560 70706528 18558160
02:08:12 19946 8192000000 10029840960 10410127360 1063208640 11.487 100 57 113 2 1145859120 71327504 11322976
02:09:33 7406 8192000000 9983802848 10377547776 1071562496 11.567 100 37 72 1 1116892464 37753984 7575984
02:10:44 9498 8192000000 9994628784 10399776768 1078461648 11.633 100 34 67 3 1100625984 15486112 6678224
02:11:55 53686 8192000000 10019955312 10439335936 1086007280 11.705 100 39 78 0 1109406432 14167952 9231200
02:13:50 3813 8192000000 10006059312 10400415744 1083562736 11.681 99 45 86 3 1102397424 8604160 10230528
02:14:55 9413 8192000000 10017664336 10428428288 1096725872 11.807 100 36 72 1 1121219152 16068288 8424992
02:16:05 10622 8192000000 9992389968 10404777984 1081251424 11.659 99 26 53 2 1118302912 30925520 6125968
02:17:19 44150 8192000000 9999391056 10399916032 1029960544 11.168 94 36 67 2 1060348128 23515552 6872032
02:19:07 8043 8192000000 9977314144 10369490944 1018220976 11.055 96 40 81 1 1050838384 23568928 9048480
02:20:16 3631 8192000000 9938742192 10316365824 1022620192 11.097 99 46 93 1 1039654592 5252416 11781984
02:21:23 9747 8192000000 9929408448 10311880704 1018817152 11.061 100 17 34 2 1037237616 15453536 2966928
02:22:33 28217 8192000000 9942693568 10331004928 994782480 10.828 96 25 50 0 1013157760 12972048 5403232
02:24:02 6049 8192000000 9983224736 10372644864 1053556144 11.395 100 50 100 3 1090608544 25165008 11887392
02:25:09 6783 8192000000 9982778512 10370412544 1067720192 11.530 100 46 92 0 1095258960 17408096 10130672
02:26:18 62137 8192000000 10000985744 10395262976 1084125872 11.687 100 48 94 2 1103633312 8881008 10626432
02:28:29 21007 8192000000 10076845856 10474876928 1109206848 11.925 100 55 110 1 1182600592 62478224 10915520
02:30:13 13333 8192000000 10094010864 10490003456 1125592512 12.080 100 50 100 0 1193412032 56089792 11729728
02:31:32 4421 8192000000 10139478064 10538057728 1138039360 12.197 100 65 130 1 1246294528 93544896 14710272
02:32:39 25386 8192000000 10144496464 10564665344 1148546096 12.296 100 41 82 0 1265534992 107338016 9650880
02:34:06 4782 8192000000 10207077184 10607681536 1148635712 12.297 99 69 134 0 1308205120 144726416 14842992
02:35:14 3148 8192000000 10241665776 10641240064 1155429088 12.360 100 80 158 2 1339768976 166930720 17409168
02:36:20 11813 8192000000 10251403696 10672283648 1159093744 12.395 100 58 115 1 1357717248 185730832 12892672
02:37:40 4833 8192000000 10273579120 10670772224 1153121952 12.339 99 67 131 3 1363390672 196009776 14258944
02:38:48 5247 8192000000 10300780960 10705133568 1151162592 12.320 99 71 140 0 1399463440 231704944 16595904
02:39:58 861 8192000000 10313464144 10722250752 1171867888 12.514 100 72 144 1 1421201200 231878992 17454320
02:41:04 1372 8192000000 10310030176 10705133568 1149863456 12.308 98 81 158 3 1395339504 226783616 18692432
02:42:09 3953 8192000000 10320779936 10744303616 1153340080 12.341 100 70 140 1 1431193584 260888016 16965488
02:43:23 6677 8192000000 10311321440 10713583616 1140731040 12.222 99 78 155 1 1393877984 235367040 17779904
02:44:33 3227 8192000000 10247734880 10677710848 1143179408 12.245 100 58 114 2 1342458224 186130848 13147968
02:45:41 5175 8192000000 10224287920 10643456000 1129534288 12.117 99 40 79 3 1318612256 180348304 8729664
02:46:51 14763 8192000000 10268347040 10714017792 1142831968 12.242 100 43 85 1 1382792096 228963600 10996528
02:48:19 1815 8192000000 10322022064 10742714368 1164538416 12.446 100 60 116 1 1420479504 241930592 14010496
02:49:23 4768 8192000000 10271149360 10705854464 1137486016 12.192 100 43 86 0 1370343872 221795856 11062000
02:50:40 6147 8192000000 10335712176 10753462272 1140013008 12.216 100 42 84 0 1438967440 288551344 10403088
02:51:51 11916 8192000000 10354688928 10789048320 1143373152 12.247 100 30 60 0 1444684848 293435744 7875952
02:53:11 16104 8192000000 10347477216 10797756416 1130202336 12.123 99 33 66 0 1440118656 301343680 8572640
02:54:36 25946 8192000000 10315196656 10778972160 1106291808 11.897 99 23 46 2 1394269744 281967072 6010864
02:56:03 45686 8192000000 10118302000 10618773504 1112984640 11.961 100 26 53 0 1274082448 155073936 6023872
02:57:51 63404 8192000000 10099334656 10525167616 1139717040 12.213 100 33 66 2 1188987904 42378176 6892688
03:00:24 130782 8192000000 10114342592 10581282816 1134475696 12.164 99 15 31 0 1184787168 46799488 3511984
03:03:39 129356 8192000000 10056932144 10504880128 1110952768 11.941 97 27 54 0 1149224832 32012992 6259072
03:06:50 55550 8192000000 10086851504 10544594944 1137505344 12.192 100 19 38 2 1196439152 54457232 4476576
03:08:46 43904 8192000000 10155501584 10610724864 1153297440 12.340 100 37 74 3 1274258416 113461376 7499600
03:10:35 55893 8192000000 10134124848 10627690496 1132671440 12.147 98 19 38 0 1236790032 100108240 4010352
03:12:31 25016 8192000000 10102979728 10568347648 1123909168 12.064 99 7 14 1 1202318080 76752016 1656896
03:14:06 67942 8192000000 10101576480 10567892992 1144826928 12.261 100 19 36 2 1236053840 86736272 4490640
03:16:24 79428 8192000000 10088259264 10574503936 1120481232 12.032 98 8 16 1 1176458672 54245872 1731568
03:18:44 31117 8192000000 10090943888 10543017984 1137269344 12.190 99 19 38 3 1195945280 54825856 3850080
03:20:18 67967 8192000000 10101567552 10581385216 1131002672 12.131 98 25 50 0 1207668496 71564464 5101360
03:22:31 55925 8192000000 10093627584 10556387328 1127776128 12.100 99 23 46 1 1187250752 54707392 4767232
03:24:28 54226 8192000000 10086041184 10551123968 1072970432 11.580 94 10 20 0 1129656688 54564144 2122112
03:27:13 118665 8192000000 10040465840 10481147904 1093370528 11.775 97 24 45 4 1118356688 20820000 4166160
03:30:17 46700 8192000000 10038170640 10487357440 1094580576 11.786 98 12 23 1 1142920768 45698416 2641776
03:32:09 41258 8192000000 10102517392 10566250496 1084732288 11.693 97 11 22 2 1179983216 92871312 2379616
03:33:51 38109 8192000000 10050429984 10492420096 1094137776 11.782 98 22 44 3 1131369168 32325552 4905840
03:35:41 42690 8192000000 10058190448 10517864448 1081677456 11.663 98 17 34 1 1172005776 86202176 4126144
03:37:23 36788 8192000000 10091921328 10511777792 1103400400 11.870 99 33 66 1 1147866816 37988032 6478384
03:39:13 25992 8192000000 10091789888 10513723392 1088762016 11.731 98 35 70 2 1170501856 74533808 7206032
03:40:41 27664 8192000000 10118167632 10526679040 1116546496 11.994 100 53 106 0 1186367072 58044848 11775728
03:42:11 51979 8192000000 10081895472 10502426624 1085366640 11.699 97 27 54 0 1133946096 43597856 4981600
03:44:05 73301 8192000000 10076687536 10535292928 1076212144 11.611 96 31 62 1 1124810176 42810736 5787296
03:46:34 84132 8192000000 10048842800 10491170816 1101488736 11.852 99 21 42 0 1125511136 19598000 4424400
03:49:04 46887 8192000000 10055157648 10514051072 1095614368 11.796 98 10 20 0 1143758192 45925632 2218192
03:50:51 58689 8192000000 10069529536 10532827136 1110839680 11.940 99 13 26 0 1153439952 39846000 2754272
03:52:53 44685 8192000000 10099551808 10535759872 1127959792 12.102 100 22 44 0 1186787840 54268304 4559744
03:54:38 87114 8192000000 10120970752 10579099648 1120412816 12.031 98 30 60 1 1212622944 85841664 6368464
03:57:16 78167 8192000000 10107506688 10565341184 1130669200 12.128 99 24 48 0 1215011168 79568032 4773936
03:59:36 53254 8192000000 10088745536 10518589440 1127423360 12.097 99 28 55 2 1171193840 38169680 5600800
04:01:29 50849 8192000000 10121360336 10575253504 1109615424 11.929 97 23 46 0 1170751520 56527040 4609056
04:03:20 105383 8192000000 10081328672 10533724160 1129055648 12.112 99 11 22 0 1191291360 60116928 2118784
04:06:06 71007 8192000000 10094303776 10591997952 1135393024 12.172 99 6 12 0 1197193904 60584016 1216864
04:08:17 100239 8192000000 10141166656 10613219328 1133743360 12.157 98 25 50 1 1200771392 61503072 5524960
04:10:58 72666 8192000000 10106628400 10608500736 1123658672 12.062 98 13 26 1 1181751776 54136144 3956960
04:13:11 31468 8192000000 10066198528 10493374464 1102045456 11.857 98 32 62 2 1136597264 28066336 6485472
04:14:47 68880 8192000000 10071967776 10542149632 1099316560 11.831 98 21 42 1 1145117392 41788704 4012128
04:16:58 80503 8192000000 10045622448 10495922176 1085817344 11.703 96 21 41 1 1127791152 37849824 4123984
04:19:20 115968 8192000000 10063721328 10523500544 1085305904 11.698 97 17 34 0 1152312080 63360288 3645888
04:22:23 32964 8192000000 10022278480 10520924160 1086066224 11.705 98 4 8 1 1117907120 31242272 598624
04:23:57 30407 8192000000 10029804800 10460631040 1076394432 11.613 97 24 48 2 1105841152 24570096 4876624
04:25:35 36386 8192000000 10084585088 10519654400 1086300800 11.707 98 20 40 2 1160164784 69323632 4540352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-11-2020 04:29:01 to 08-11-2020 04:29:03.

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          234076
MON$OLDEST_ACTIVE               239429
MON$OLDEST_SNAPSHOT             239429
MON$NEXT_TRANSACTION            239429
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                       2103680
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             810
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2248 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.2248 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:05 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:07 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:09 2020
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:10 2020
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:12 2020
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Nov  8 04:27:12 2020
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

08.11.20 04:29:04. 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-08 04:29:06 No severe PSQL-related problems occured
08.11.20 04:29:08. End of report.