Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2020 07:37
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 16834996 kB
MemAvailable 31387776 kB
Buffers 0 kB
Cached 14723564 kB
SwapCached 3696 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-12-19 07:37:03
DTS_END                         2020-12-19 07:37:03.5430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40060
TRN_ID                          237633
ATT_ID                          694
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: 8970.62 16759 1076474
customer order: creation 170.87 456 20504
customer order: refuse 15.00 301 1800
order to supplier: creation 45.07 1380 5408
order to supplier: removal 3.54 108466 425
invoice (draft): creation 45.51 3762 5461
invoice (draft): removal 51.29 922 6155
invoice accept: apply 41.61 16598 4993
invoice accept: cancel 45.89 887 5507
customer reserve: creation 3775.90 164 453108
customer reserve: removal 4481.96 68 537835
realization accept: apply 83.31 164 9997
realization accept: cancel 82.78 121 9933
payment from customer: creation 58.89 102 7067
payment from customer: removal 5.95 64 714
payment to supplier: creation 48.93 120 5871
payment to supplier: removal 3.33 73 399
service: total inventory turnovers 3.98 3143 477
service: total monetary turnovers 5.82 561 698
service: aggregate perf. data 0.98 14276 117
service: refresh index statistics 0.04 183547 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2020 07:38:18 to 19-12-2020 07:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7413
TEST_TIME 2 5258
TEST_TIME 3 5629
TEST_TIME 4 5958
TEST_TIME 5 6592
TEST_TIME 6 6183
TEST_TIME 7 6059
TEST_TIME 8 5933
TEST_TIME 9 6149
TEST_TIME 10 6346
TEST_TIME 11 6665
TEST_TIME 12 6794
TEST_TIME 13 6943
TEST_TIME 14 7234
TEST_TIME 15 7510
TEST_TIME 16 7950
TEST_TIME 17 8389
TEST_TIME 18 8908
TEST_TIME 19 9159
TEST_TIME 20 9011
TEST_TIME 21 9493
TEST_TIME 22 9686
TEST_TIME 23 9779
TEST_TIME 24 10204
TEST_TIME 25 10081
TEST_TIME 26 10615
TEST_TIME 27 11612
TEST_TIME 28 11543
TEST_TIME 29 11249
TEST_TIME 30 11116
TEST_TIME 31 10771
TEST_TIME 32 10559
TEST_TIME 33 10531
TEST_TIME 34 10499
TEST_TIME 35 10400
TEST_TIME 36 10221
TEST_TIME 37 10116
TEST_TIME 38 10025
TEST_TIME 39 9920
TEST_TIME 40 9948
TEST_TIME 41 9988
TEST_TIME 42 10038
TEST_TIME 43 10065
TEST_TIME 44 10091
TEST_TIME 45 10061
TEST_TIME 46 10070
TEST_TIME 47 10204
TEST_TIME 48 10159
TEST_TIME 49 10036
TEST_TIME 50 10206
TEST_TIME 51 10165
TEST_TIME 52 9976
TEST_TIME 53 9919
TEST_TIME 54 9938
TEST_TIME 55 9849
TEST_TIME 56 9801
TEST_TIME 57 9723
TEST_TIME 58 9714
TEST_TIME 59 9695
TEST_TIME 60 9645
TEST_TIME 61 9616
TEST_TIME 62 9635
TEST_TIME 63 9601
TEST_TIME 64 9754
TEST_TIME 65 9844
TEST_TIME 66 9826
TEST_TIME 67 9833
TEST_TIME 68 9913
TEST_TIME 69 10004
TEST_TIME 70 9864
TEST_TIME 71 9820
TEST_TIME 72 9752
TEST_TIME 73 9651
TEST_TIME 74 9621
TEST_TIME 75 9555
TEST_TIME 76 9528
TEST_TIME 77 9471
TEST_TIME 78 9479
TEST_TIME 79 9456
TEST_TIME 80 9483
TEST_TIME 81 9473
TEST_TIME 82 9422
TEST_TIME 83 9431
TEST_TIME 84 9586
TEST_TIME 85 9585
TEST_TIME 86 9557
TEST_TIME 87 9601
TEST_TIME 88 9627
TEST_TIME 89 9585
TEST_TIME 90 9480
TEST_TIME 91 9439
TEST_TIME 92 9370
TEST_TIME 93 9292
TEST_TIME 94 9308
TEST_TIME 95 9273
TEST_TIME 96 9270
TEST_TIME 97 9263
TEST_TIME 98 9276
TEST_TIME 99 9261
TEST_TIME 100 9243
TEST_TIME 101 9249
TEST_TIME 102 9340
TEST_TIME 103 9353
TEST_TIME 104 9346
TEST_TIME 105 9332
TEST_TIME 106 9325
TEST_TIME 107 9334
TEST_TIME 108 9313
TEST_TIME 109 9237
TEST_TIME 110 9219
TEST_TIME 111 9177
TEST_TIME 112 9141
TEST_TIME 113 9102
TEST_TIME 114 9075
TEST_TIME 115 9072
TEST_TIME 116 9044
TEST_TIME 117 9025
TEST_TIME 118 9025
TEST_TIME 119 9047
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2020 07:38:22 to 19-12-2020 07:38:25.

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 1497090 1497090 0 0.00 0 12928 6 0 0
doc_list_biud 1497089 1497089 0 0.00 0 60393 31 0 0
sp_add_invoice_to_stock 5114 4993 121 2.36 19 236295 16598 0 121
sp_cancel_adding_invoice 5507 5507 0 0.00 5 86933 888 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 7569 302 0 0
sp_cancel_customer_reserve 537835 537835 0 0.00 1 37790 69 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 5212 65 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 2768 73 0 0
sp_cancel_supplier_invoice 6167 6155 12 0.19 2 60444 922 0 12
sp_cancel_supplier_order 425 425 0 0.00 7 1112750 108467 0 0
sp_cancel_write_off 9933 9933 0 0.00 1 10166 121 0 0
sp_client_order 20504 20504 0 0.00 4 74876 457 0 0
sp_customer_reserve 453108 453108 0 0.00 0 72647 164 0 0
sp_fill_shopping_cart 31373 31373 0 0.00 1 40745 47 0 0
sp_fill_shopping_cart_clo_res 453105 453105 0 0.00 0 27486 17 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 30420 437 0 0
sp_kill_cost_storno 16553 16553 0 0.00 0 8647 57 0 0
sp_kill_qstorno_ret_qs2qd 1092183 1092183 0 0.00 0 60391 37 0 0
sp_kill_qty_storno 576642 576642 0 0.00 0 60393 80 0 0
sp_lock_selected_doc 20890 20890 0 0.00 0 7773 5 0 0
sp_make_cost_storno 27925 27925 0 0.00 0 11259 49 0 0
sp_multiply_rows_for_pdistr 27925 27925 0 0.00 0 12920 27 0 0
sp_multiply_rows_for_qdistr 20504 20504 0 0.00 0 74627 272 0 0
sp_pay_from_customer 7067 7067 0 0.00 4 10165 103 0 0
sp_pay_to_supplier 5871 5871 0 0.00 5 14946 121 0 0
sp_payment_common 12938 12938 0 0.00 1 4867 14 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 7049 274 0 0
sp_qd_handle_on_invoice_upd_sts 10497 10497 0 0.00 0 40642 389 0 0
sp_qd_handle_on_reserve_upd_sts 9997 9997 0 0.00 0 13395 52 0 0
sp_reserve_write_off 9997 9997 0 0.00 7 13500 164 0 0
sp_supplier_invoice 5461 5461 0 0.00 7 147215 3763 0 0
sp_supplier_order 5408 5408 0 0.00 5 31983 1380 0 0
srv_aggregate_perf_data 117 117 0 0.00 2270 104219 14276 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1104 144559 47242 0 0
srv_find_qd_qs_mism 1029841 1029841 0 0.00 0 10205 5 0 0
srv_make_invnt_saldo 477 477 0 0.00 30 98218 3143 0 0
srv_make_money_saldo 698 698 0 0.00 1 20638 561 0 0
srv_recalc_idx_stat 5 5 0 0.00 112908 244391 183548 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 34148 149 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5119 103 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 5054 13 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 10609 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 1690 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1057 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 782 6 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 30237 1539 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 6365 13 0 0
t$perf-norm:sp_client_order 20504 20504 0 0.00 0 16032 21 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1404 8 0 0
t$perf-norm:sp_pay_from_customer 7067 7067 0 0.00 0 4327 8 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 11353 11 0 0
t$perf-norm:sp_reserve_write_off 9997 9997 0 0.00 0 6363 10 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 13888 19 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 5555 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 324 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 75 3 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 904 9 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 489 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10222 9980 242 2.36 0 6554 20 0 242
v_all_customers 37308 37308 0 0.00 0 71 1 0 0
v_all_suppliers 30194 30194 0 0.00 0 105 1 0 0
v_all_wares 41008 41008 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2366 9 0 0
v_cancel_client_order 3600 3600 0 0.00 0 1982 4 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 639 5 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 1073 5 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1962 8 0 0
v_cancel_supplier_invoice 870 846 24 2.75 0 1967 20 0 24
v_cancel_supplier_order 850 850 0 0.00 0 1309 7 0 0
v_cancel_write_off 2108 2108 0 0.00 0 996 12 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 431 1 0 0
v_max_id_clo_res 999 999 0 0.00 0 1855 15 0 0
v_max_id_ord_sup 5461 5461 0 0.00 0 6672 6 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 1520 2 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 4863 4 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 1942 5 0 0
v_min_id_clo_res 999 999 0 0.00 0 4792 11 0 0
v_min_id_ord_sup 5461 5461 0 0.00 0 6777 13 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 2364 7 0 0
v_min_non_paid_realizn 7067 7067 0 0.00 0 2515 9 0 0
v_our_firm 10154 10154 0 0.00 0 17 1 0 0
v_reserve_write_off 19994 19994 0 0.00 0 2804 6 0 0
x_lock_depdocs_on_canc_invoice 11239 11239 0 0.00 0 18421 105 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 9290 342 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 2 31968 1310 0 0
x_make_qstorno_1200_2000 5461 5461 0 0.00 3 146947 3569 0 0
x_make_qstorno_2100_3300 453108 453108 0 0.00 0 72647 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2020 07:38:26 to 19-12-2020 07:38:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:07 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:08:08 20854 8192000000 9718695040 10014326784 797901920 8.875 100 85 170 0 826877792 7929360 21046512
05:09:38 26426 8192000000 9787726096 10096779264 860142128 9.502 100 95 190 0 895110080 12759872 22208080
05:11:07 17601 8192000000 9823457264 10145341440 887825216 9.777 100 84 168 2 935569008 27960464 19783328
05:12:30 14578 8192000000 9890230384 10226941952 935373312 10.248 100 80 160 0 1003466496 50644144 17449040
05:13:46 6389 8192000000 9946692336 10290438144 968468432 10.572 100 82 162 3 1056868800 71640192 16760176
05:14:56 5402 8192000000 9976891248 10329706496 1000580768 10.884 100 78 152 3 1088634368 72545888 15507712
05:16:03 3341 8192000000 10002410192 10363478016 1029577408 11.164 100 85 170 2 1106328240 59708592 17042240
05:17:09 2058 8192000000 10041819680 10406682624 1050688400 11.367 100 92 184 0 1149973472 80956992 18328080
05:18:11 1189 8192000000 10054586240 10425540608 1072752704 11.578 100 97 194 0 1165709264 72799200 20157360
05:19:13 1266 8192000000 10026604544 10404085760 1077655648 11.625 100 86 175 2 1132476736 36596704 18224384
05:20:17 4096 8192000000 10006942240 10405785600 1079665552 11.644 100 33 67 2 1122364352 35452528 7246272
05:21:22 6369 8192000000 9981234144 10383171584 1086399424 11.708 100 33 65 1 1105233120 11399504 7434192
05:22:32 69723 8192000000 10006214336 10403012608 1090496288 11.747 100 22 44 1 1116530336 20417856 5616192
05:24:42 5143 8192000000 10013879456 10409295872 1093161776 11.773 100 46 93 1 1126193744 22654624 10377344
05:25:50 5906 8192000000 10011410080 10408640512 1096144640 11.801 100 30 61 2 1121336080 18906928 6284512
05:26:58 47731 8192000000 9987561376 10369175552 1058134960 11.439 97 37 74 0 1079167920 13422592 7610368
05:28:46 9541 8192000000 9946471552 10335952896 1002001776 10.898 94 31 62 1 1030222976 21060176 7161024
05:29:56 7768 8192000000 9926199456 10308399104 973584608 10.622 96 37 75 2 993681200 11001536 9095056
05:31:04 6504 8192000000 9925762880 10308599808 1002579168 10.904 98 47 92 3 1025855280 12780032 10496080
05:32:13 55890 8192000000 9941746736 10346856448 1014342752 11.017 99 42 82 1 1038881488 15843200 8695536
05:34:10 4174 8192000000 9964016960 10348195840 1046840128 11.330 100 49 99 1 1085107136 27534176 10732832
05:35:18 9486 8192000000 9988095600 10384961536 1064106304 11.496 100 45 90 1 1094996992 20044624 10846064
05:36:31 9234 8192000000 9979308432 10372919296 1070307232 11.555 100 19 38 0 1087848480 13497072 4044176
05:37:43 88668 8192000000 10024973808 10437787648 1099209904 11.830 100 27 51 6 1134375760 30316096 4849760
05:40:18 24210 8192000000 10088990656 10489724928 1128677856 12.109 100 42 83 2 1185284656 48066400 8540400
05:41:49 17644 8192000000 10145972608 10559913984 1132262160 12.143 99 55 111 0 1239351936 95432096 11657680
05:43:13 3829 8192000000 10160885328 10567319552 1152397680 12.332 100 63 123 0 1264162624 98943680 12821264
05:44:20 13088 8192000000 10197626048 10609438720 1154046704 12.347 100 50 100 1 1308375232 142811040 11517488
05:45:38 8366 8192000000 10221775040 10634608640 1165477456 12.455 100 65 123 4 1329896144 150123296 14295392
05:46:50 14626 8192000000 10262493744 10690011136 1177032624 12.563 100 55 110 0 1379944848 189744912 13167312
05:48:17 3997 8192000000 10292253824 10694897664 1178998064 12.581 100 78 149 5 1386797664 191092560 16707040
05:49:25 3561 8192000000 10333568336 10748833792 1171418368 12.510 100 66 132 0 1437003456 250192176 15392912
05:50:37 15292 8192000000 10324397904 10736926720 1159166880 12.395 99 52 102 0 1418229136 247546240 11516016
05:52:10 3317 8192000000 10336644928 10751107072 1157258064 12.378 100 75 148 3 1434467584 259711376 17498144
05:53:18 11012 8192000000 10313911888 10741919744 1126009840 12.084 100 41 81 1 1426323056 290477872 9835344
05:54:31 1101 8192000000 10345566800 10746925056 1118408288 12.012 100 87 172 2 1439133808 300308272 20417248
05:55:35 14363 8192000000 10274896096 10690457600 1117456528 12.003 99 34 68 2 1356999264 231068496 8474240
05:56:57 11363 8192000000 10290768576 10718093312 1122245696 12.048 99 31 62 0 1379449008 249516176 7687136
05:58:18 2998 8192000000 10369559744 10799652864 1138662816 12.203 100 72 144 1 1465817824 309675824 17479184
05:59:24 15199 8192000000 10336515808 10807365632 1128752416 12.110 100 28 56 3 1432766128 296044688 7969024
06:01:06 20530 8192000000 10336589488 10770657280 1119006720 12.018 99 27 55 0 1420091680 293770336 7314624
06:02:42 20513 8192000000 10311262016 10751086592 1092172864 11.763 99 15 30 1 1393008608 296345424 4490320
06:04:05 21569 8192000000 10127419504 10585731072 1104499456 11.880 100 20 40 2 1265496704 156112032 4885216
06:05:33 31363 8192000000 10051769456 10469994496 1111645872 11.948 100 34 68 2 1143809792 25207888 6956032
06:07:12 127631 8192000000 10061270912 10505502720 1112451136 11.956 100 51 99 4 1181318704 58426240 10441328
06:10:22 30934 8192000000 10044948944 10466848768 1103829344 11.874 98 30 58 1 1130907056 21116816 5960896
06:11:55 104819 8192000000 10075775616 10521169920 1117131760 12.000 98 17 34 0 1171584768 50496576 3956432
06:14:51 68311 8192000000 10114740704 10550558720 1136825504 12.186 100 28 56 0 1213089664 70240208 6023952
06:17:02 40672 8192000000 10104145568 10558472192 1134981328 12.168 100 17 34 0 1212811120 73931280 3898512
06:18:44 30157 8192000000 10092536192 10543271936 1126691136 12.090 99 39 79 1 1182718880 47032544 8995200
06:20:39 44602 8192000000 10111127520 10598817792 1137042688 12.188 100 17 34 0 1197874352 57175120 3656544
06:22:25 30430 8192000000 10139787648 10594373632 1145394512 12.266 100 24 48 2 1235277856 84258288 5625056
06:23:56 64159 8192000000 10079626992 10543792128 1127939664 12.102 99 22 44 0 1194514016 62044768 4529584
06:26:06 79492 8192000000 10108579088 10642702336 1119191744 12.019 97 23 45 0 1196243904 72161024 4891136
06:28:27 144558 8192000000 10096567232 10572914688 1119424432 12.022 98 15 29 1 1176295072 53189584 3681056
06:31:58 103082 8192000000 10058644368 10513674240 1106222032 11.897 97 6 12 0 1134728816 27364496 1142288
06:34:41 66299 8192000000 10083631392 10531348480 1101259280 11.850 97 31 62 0 1143480320 35438624 6782416
06:36:51 62051 8192000000 10116235504 10558648320 1104416000 11.880 97 22 44 1 1184092368 74827152 4849216
06:39:03 82148 8192000000 10133510144 10566963200 1073971056 11.590 95 19 38 2 1168210576 90068192 4171328
06:41:29 46172 8192000000 10067683008 10548305920 1076909216 11.618 95 17 34 0 1165572384 85413760 3249408
06:43:17 27023 8192000000 10099856640 10534465536 1110482544 11.937 98 30 60 2 1151601520 34982288 6136688
06:44:48 77226 8192000000 10111654544 10670444544 1103278992 11.869 98 12 24 1 1246402160 140426064 2697104
06:47:28 119976 8192000000 10073738512 10541916160 1130430224 12.125 100 17 34 0 1165766816 31656928 3679664
06:50:31 33083 8192000000 10062720576 10500825088 1117891344 12.007 99 19 38 1 1162561616 40554864 4115408
06:52:13 25808 8192000000 10074868688 10554933248 1126794928 12.091 100 13 26 0 1180865088 50685568 3384592
06:53:39 64349 8192000000 10082180560 10563624960 1109196352 11.925 99 13 26 0 1164551104 52557040 2797712
06:55:45 59480 8192000000 10112774720 10563690496 1113665984 11.967 98 17 34 0 1189146528 71563328 3917216
06:57:59 70640 8192000000 10104388768 10545278976 1081810064 11.665 95 18 36 2 1139374720 53781744 3782912
07:00:21 47953 8192000000 10172295856 10641661952 1116090128 11.990 98 24 48 1 1273509424 151864912 5554384
07:02:11 54399 8192000000 10076688240 10496999424 1104420928 11.880 99 23 46 2 1142117728 32856944 4839856
07:04:12 59748 8192000000 10071473520 10523111424 1103468912 11.871 98 21 42 1 1167243344 59769760 4004672
07:06:12 122289 8192000000 10046150320 10491944960 1104024608 11.876 98 18 36 1 1138692752 31185920 3482224
07:09:22 69704 8192000000 10076124624 10542264320 1133842688 12.158 100 6 12 0 1164402176 29717552 841936
07:11:32 38044 8192000000 10065742128 10565378048 1132164960 12.142 100 7 15 0 1163850912 30444336 1241616
07:13:14 56534 8192000000 10077823536 10537078784 1107571312 11.909 97 19 38 0 1164999248 53554560 3873376
07:15:10 48247 8192000000 10087919840 10533732352 1134697680 12.166 99 24 48 2 1175730400 35921296 5111424
07:17:05 53003 8192000000 10163106992 10612350976 1170180288 12.499 100 19 37 0 1231605184 57242704 4182192
07:19:11 84483 8192000000 10087102416 10563629056 1106020432 11.895 95 9 18 2 1167785200 60050912 1713856
07:21:36 45226 8192000000 10097463952 10547900416 1135232800 12.171 98 25 48 1 1177377856 36648752 5496304
07:23:24 30273 8192000000 10112863472 10536046592 1144214400 12.255 99 48 95 3 1188800256 34365264 10220592
07:24:56 83101 8192000000 10097966608 10578038784 1130937216 12.130 99 21 42 2 1204431136 69425360 4068560
07:27:58 65110 8192000000 10062541152 10530402304 1136613776 12.184 100 9 17 1 1176681904 37927168 2140960
07:30:05 35188 8192000000 10058605856 10532163584 1127145936 12.094 99 4 8 0 1157797216 29743664 907616
07:31:52 24091 8192000000 10084247856 10530164736 1131932048 12.140 100 28 55 1 1164928944 27132096 5864800
07:33:21 58938 8192000000 10107249984 10552340480 1127462736 12.097 99 21 41 0 1197539376 65796112 4280528
07:35:23 50161 8192000000 10162313056 10623188992 1119076048 12.018 100 33 65 3 1241963088 115148512 7738528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2020 07:38:32 to 19-12-2020 07:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2020 07:38:48 to 19-12-2020 07:38:50.

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          231797
MON$OLDEST_ACTIVE               237773
MON$OLDEST_SNAPSHOT             237773
MON$NEXT_TRANSACTION            237773
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                       2087264
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             805
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec 19 07:37:14 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec 19 07:37:14 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

19.12.20 07:38:51. 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-12-19 07:38:53 No severe PSQL-related problems occured
19.12.20 07:38:55. End of report.