Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2021 07:38
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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 16542808 kB
MemAvailable 31393220 kB
Buffers 0 kB
Cached 15119764 kB
SwapCached 2440 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.1
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 2021-06-19 07:37:10
DTS_END                         2021-06-19 07:37:10.4790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38226
TRN_ID                          229709
ATT_ID                          671
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: 9083.96 22708 1090075
customer order: creation 166.81 360 20017
customer order: refuse 13.93 360 1671
order to supplier: creation 45.39 1325 5447
order to supplier: removal 3.57 123506 428
invoice (draft): creation 44.86 3402 5383
invoice (draft): removal 52.65 1099 6318
invoice accept: apply 40.58 17726 4870
invoice accept: cancel 47.43 947 5691
customer reserve: creation 3763.58 172 451630
customer reserve: removal 4614.67 73 553760
realization accept: apply 79.95 186 9594
realization accept: cancel 86.42 132 10370
payment from customer: creation 56.33 109 6759
payment from customer: removal 5.63 90 676
payment to supplier: creation 48.23 124 5788
payment to supplier: removal 3.28 88 393
service: total inventory turnovers 3.45 4178 414
service: total monetary turnovers 6.35 562 762
service: aggregate perf. data 0.83 21397 99
service: refresh index statistics 0.04 278316 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-06-2021 07:38:37 to 19-06-2021 07:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3807
TEST_TIME 2 4625
TEST_TIME 3 4983
TEST_TIME 4 5502
TEST_TIME 5 5027
TEST_TIME 6 5034
TEST_TIME 7 5014
TEST_TIME 8 5021
TEST_TIME 9 5485
TEST_TIME 10 5627
TEST_TIME 11 5797
TEST_TIME 12 5951
TEST_TIME 13 6066
TEST_TIME 14 6273
TEST_TIME 15 7044
TEST_TIME 16 7475
TEST_TIME 17 7665
TEST_TIME 18 7775
TEST_TIME 19 8166
TEST_TIME 20 8355
TEST_TIME 21 8597
TEST_TIME 22 8506
TEST_TIME 23 8512
TEST_TIME 24 8693
TEST_TIME 25 8884
TEST_TIME 26 8720
TEST_TIME 27 8898
TEST_TIME 28 10856
TEST_TIME 29 11389
TEST_TIME 30 11196
TEST_TIME 31 11280
TEST_TIME 32 11234
TEST_TIME 33 11143
TEST_TIME 34 11334
TEST_TIME 35 11218
TEST_TIME 36 11046
TEST_TIME 37 10858
TEST_TIME 38 10842
TEST_TIME 39 11123
TEST_TIME 40 10993
TEST_TIME 41 11014
TEST_TIME 42 10962
TEST_TIME 43 10912
TEST_TIME 44 10942
TEST_TIME 45 10759
TEST_TIME 46 10576
TEST_TIME 47 10368
TEST_TIME 48 10248
TEST_TIME 49 10164
TEST_TIME 50 10201
TEST_TIME 51 10202
TEST_TIME 52 10135
TEST_TIME 53 10087
TEST_TIME 54 9984
TEST_TIME 55 9970
TEST_TIME 56 9914
TEST_TIME 57 9823
TEST_TIME 58 9707
TEST_TIME 59 9760
TEST_TIME 60 9710
TEST_TIME 61 9719
TEST_TIME 62 9703
TEST_TIME 63 9674
TEST_TIME 64 9718
TEST_TIME 65 9757
TEST_TIME 66 9783
TEST_TIME 67 9778
TEST_TIME 68 9833
TEST_TIME 69 9772
TEST_TIME 70 9760
TEST_TIME 71 9913
TEST_TIME 72 9870
TEST_TIME 73 9820
TEST_TIME 74 9726
TEST_TIME 75 9638
TEST_TIME 76 9729
TEST_TIME 77 9631
TEST_TIME 78 9571
TEST_TIME 79 9534
TEST_TIME 80 9491
TEST_TIME 81 9442
TEST_TIME 82 9402
TEST_TIME 83 9368
TEST_TIME 84 9342
TEST_TIME 85 9330
TEST_TIME 86 9355
TEST_TIME 87 9450
TEST_TIME 88 9425
TEST_TIME 89 9427
TEST_TIME 90 9430
TEST_TIME 91 9444
TEST_TIME 92 9542
TEST_TIME 93 9532
TEST_TIME 94 9575
TEST_TIME 95 9510
TEST_TIME 96 9445
TEST_TIME 97 9440
TEST_TIME 98 9428
TEST_TIME 99 9391
TEST_TIME 100 9378
TEST_TIME 101 9413
TEST_TIME 102 9392
TEST_TIME 103 9400
TEST_TIME 104 9418
TEST_TIME 105 9441
TEST_TIME 106 9410
TEST_TIME 107 9426
TEST_TIME 108 9465
TEST_TIME 109 9474
TEST_TIME 110 9483
TEST_TIME 111 9420
TEST_TIME 112 9347
TEST_TIME 113 9338
TEST_TIME 114 9288
TEST_TIME 115 9246
TEST_TIME 116 9195
TEST_TIME 117 9196
TEST_TIME 118 9177
TEST_TIME 119 9148
TEST_TIME 120 9087

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-06-2021 07:38:41 to 19-06-2021 07:38:44.

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 1508014 1508014 0 0.00 0 25889 7 0 0
doc_list_biud 1508012 1508012 0 0.00 0 34598 34 0 0
sp_add_invoice_to_stock 4985 4870 115 2.30 20 233446 17727 0 115
sp_cancel_adding_invoice 5691 5691 0 0.00 5 59432 948 0 0
sp_cancel_client_order 1671 1671 0 0.00 4 10629 361 0 0
sp_cancel_customer_reserve 553760 553760 0 0.00 1 15894 73 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 7519 90 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5085 89 0 0
sp_cancel_supplier_invoice 6327 6318 9 0.14 2 26152 1099 0 9
sp_cancel_supplier_order 428 428 0 0.00 7 1461117 123506 0 0
sp_cancel_write_off 10370 10370 0 0.00 1 26193 133 0 0
sp_client_order 20017 20017 0 0.00 4 27101 361 0 0
sp_customer_reserve 451630 451630 0 0.00 0 192985 172 0 0
sp_fill_shopping_cart 30841 30841 0 0.00 1 11133 40 0 0
sp_fill_shopping_cart_clo_res 451625 451625 0 0.00 0 17807 17 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 25333 381 0 0
sp_kill_cost_storno 17130 17130 0 0.00 0 8075 71 0 0
sp_kill_qstorno_ret_qs2qd 1124636 1124636 0 0.00 0 26184 40 0 0
sp_kill_qty_storno 592695 592695 0 0.00 0 34598 86 0 0
sp_lock_selected_doc 20092 20092 0 0.00 0 8717 6 0 0
sp_make_cost_storno 27004 27004 0 0.00 0 16678 56 0 0
sp_multiply_rows_for_pdistr 27004 27004 0 0.00 0 8433 34 0 0
sp_multiply_rows_for_qdistr 20017 20017 0 0.00 0 14312 172 0 0
sp_pay_from_customer 6759 6759 0 0.00 4 7536 110 0 0
sp_pay_to_supplier 5788 5788 0 0.00 5 12581 124 0 0
sp_payment_common 12547 12547 0 0.00 1 6965 18 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 9903 335 0 0
sp_qd_handle_on_invoice_upd_sts 10554 10554 0 0.00 0 34597 425 0 0
sp_qd_handle_on_reserve_upd_sts 9594 9594 0 0.00 0 19404 52 0 0
sp_reserve_write_off 9594 9594 0 0.00 7 19418 187 0 0
sp_supplier_invoice 5383 5383 0 0.00 7 65101 3403 0 0
sp_supplier_order 5447 5447 0 0.00 4 33519 1326 0 0
srv_aggregate_perf_data 99 99 0 0.00 2534 371252 21397 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 990 209623 47123 0 0
srv_find_qd_qs_mism 1043552 1043552 0 0.00 0 25888 5 0 0
srv_make_invnt_saldo 414 414 0 0.00 81 106909 4179 0 0
srv_make_money_saldo 762 762 0 0.00 1 12680 563 0 0
srv_recalc_idx_stat 5 5 0 0.00 164546 408196 278317 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 0 11864 178 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 3916 114 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 2026 11 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3683 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1239 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 224 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1116 23 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 23250 1800 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2594 13 0 0
t$perf-norm:sp_client_order 20017 20017 0 0.00 0 10570 21 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 1648 10 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 6686 9 0 0
t$perf-norm:sp_pay_to_supplier 5788 5788 0 0.00 0 4837 13 0 0
t$perf-norm:sp_reserve_write_off 9594 9594 0 0.00 0 10887 13 0 0
t$perf-norm:sp_supplier_invoice 5383 5383 0 0.00 0 5127 21 0 0
t$perf-norm:sp_supplier_order 5447 5447 0 0.00 0 7086 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 350 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2837 47 0 0
t$perf-norm:srv_make_invnt_saldo 414 414 0 0.00 0 511 9 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 2000 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 9956 9726 230 2.31 0 12435 19 0 230
v_all_customers 36756 36756 0 0.00 0 124 1 0 0
v_all_suppliers 30454 30454 0 0.00 0 170 1 0 0
v_all_wares 40034 40034 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 3746 16 0 0
v_cancel_client_order 3342 3342 0 0.00 0 2727 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1984 6 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1356 5 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 5045 16 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 2029 16 0 18
v_cancel_supplier_order 856 856 0 0.00 0 3530 12 0 0
v_cancel_write_off 2008 2008 0 0.00 0 3131 17 0 0
v_max_id_clo_ord 5447 5447 0 0.00 0 5790 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 6574 23 0 0
v_max_id_ord_sup 5377 5377 0 0.00 0 7769 8 0 0
v_max_non_paid_invoice 1385 1385 0 0.00 0 178 2 0 0
v_max_non_paid_realizn 3404 3404 0 0.00 0 1200 3 0 0
v_min_id_clo_ord 5447 5447 0 0.00 0 4260 7 0 0
v_min_id_clo_res 956 956 0 0.00 0 3721 11 0 0
v_min_id_ord_sup 5377 5377 0 0.00 0 9083 7 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 6961 10 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 5316 12 0 0
v_our_firm 9988 9988 0 0.00 0 15 1 0 0
v_reserve_write_off 19188 19188 0 0.00 0 5015 7 0 0
x_lock_depdocs_on_canc_invoice 11599 11599 0 0.00 0 36593 122 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 7064 332 0 0
x_make_qstorno_1000_1200 5447 5447 0 0.00 2 33503 1240 0 0
x_make_qstorno_1200_2000 5383 5383 0 0.00 3 65092 3261 0 0
x_make_qstorno_2100_3300 451630 451630 0 0.00 0 192982 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2021 07:38:45 to 19-06-2021 07:38:49.

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:14 2 8192000000 8797287648 8808026112 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 18377 8192000000 9723669392 10024681472 807065456 8.968 100 84 168 0 834864240 7594784 20204000
05:09:40 30296 8192000000 9779254912 10088792064 851058640 9.411 100 92 184 0 886532736 13834704 21639392
05:11:17 23563 8192000000 9825465600 10145624064 882841152 9.728 100 84 168 1 925074608 23207904 19025552
05:12:49 10830 8192000000 9884452464 10219298816 921973584 10.116 100 83 166 0 997546464 57573664 17999216
05:14:07 10099 8192000000 9935747568 10280501248 961588656 10.505 100 80 160 0 1047857088 69858048 16410384
05:15:20 4019 8192000000 9954548848 10308780032 993877024 10.819 100 76 146 3 1063380240 54502800 15000416
05:16:25 2141 8192000000 9986298592 10349285376 1022119632 11.092 100 78 154 1 1104083968 66181728 15782608
05:17:30 1357 8192000000 10015196672 10384486400 1045918560 11.322 100 88 173 2 1123862432 60248160 17695712
05:18:33 2125 8192000000 10055075600 10424815616 1061411888 11.470 100 90 180 1 1167884096 87569680 18902528
05:19:38 4592 8192000000 9987538096 10374160384 1067751216 11.531 100 58 116 0 1105353232 25777168 11824848
05:20:44 3357 8192000000 10036127072 10420432896 1073813056 11.588 100 59 116 1 1143522032 57706352 12002624
05:21:51 11120 8192000000 9998304960 10401804288 1079525904 11.643 100 22 44 2 1109731792 25747584 4458304
05:23:03 5726 8192000000 9992402688 10383413248 1089792992 11.741 100 33 65 0 1113199728 16368672 7038064
05:24:10 16002 8192000000 10017975136 10407043072 1092466128 11.766 100 62 127 2 1122292448 16913376 12912944
05:25:26 6445 8192000000 10023798000 10429259776 1092811424 11.769 100 26 52 0 1140959648 42819632 5328592
05:26:34 14408 8192000000 10036854784 10446770176 1101963200 11.856 100 41 80 3 1143056112 32730032 8362880
05:27:53 13752 8192000000 9990031440 10399694848 1035582448 11.222 95 18 36 1 1063370496 23830592 3957456
05:29:09 5966 8192000000 9979211216 10360741888 1037080352 11.237 98 35 70 1 1066089776 20454752 8554672
05:30:19 57312 8192000000 9939684944 10345291776 981326064 10.697 94 21 42 1 1002869424 17172768 4370592
05:32:23 12081 8192000000 9917106144 10307424256 991798416 10.799 97 28 56 1 1024415664 26388208 6229040
05:33:36 9393 8192000000 9959487888 10355699712 1033532224 11.202 100 41 81 1 1061941088 19368928 9039936
05:34:48 7655 8192000000 9972474880 10373046272 1048684512 11.348 100 42 83 2 1094105488 36563216 8857760
05:35:56 45618 8192000000 9962370688 10380275712 1053969248 11.399 100 31 60 2 1083168112 22539024 6659840
05:37:46 32892 8192000000 9999393408 10398150656 1079604992 11.644 100 46 89 0 1104992912 16057040 9330880
05:39:24 24122 8192000000 10070260128 10471211008 1108424496 11.917 100 50 97 1 1162445216 44218272 9802448
05:40:52 20091 8192000000 10134765920 10544521216 1130127328 12.123 100 55 109 1 1238393120 97107488 11158304
05:42:20 24155 8192000000 10152442992 10565181440 1141896656 12.233 100 39 78 1 1255433248 104640240 8896352
05:43:48 8144 8192000000 10194376080 10610835456 1151414576 12.323 100 43 86 0 1312702864 151541920 9746368
05:45:06 4755 8192000000 10226635424 10637938688 1162959984 12.431 100 60 120 0 1334229440 157790096 13479360
05:46:16 5002 8192000000 10268242512 10694303744 1168665728 12.484 100 52 104 1 1376674176 195881568 12126880
05:47:32 7230 8192000000 10297744080 10721247232 1178313568 12.574 100 49 96 2 1407637712 217888384 11435760
05:48:45 2744 8192000000 10325267344 10745659392 1182968848 12.618 100 65 129 1 1425552784 227961232 14622704
05:49:51 989 8192000000 10387246224 10795933696 1184635056 12.633 100 87 172 1 1491093184 286607872 19850256
05:50:58 3674 8192000000 10389775568 10797969408 1166346816 12.463 100 71 142 3 1492724528 309230432 17147280
05:52:08 2637 8192000000 10368291152 10811011072 1159898256 12.402 99 70 138 0 1464834720 288308144 16628320
05:53:16 1042 8192000000 10335227472 10741456896 1158872912 12.393 99 91 179 2 1434059664 254360992 20825760
05:54:22 1805 8192000000 10388524096 10797105152 1179305040 12.584 100 83 165 1 1482207504 283723136 19179328
05:55:30 3116 8192000000 10418897024 10833391616 1189648896 12.680 100 75 149 0 1517059232 309980896 17429440
05:56:41 2667 8192000000 10419691440 10833534976 1158191120 12.386 99 78 153 1 1506055968 329642128 18222720
05:57:47 2838 8192000000 10433877984 10851258368 1162855312 12.430 99 76 150 0 1523631152 343214288 17561552
05:58:56 8223 8192000000 10473668096 10908909568 1166386576 12.463 100 45 90 0 1571845792 393696464 11762752
06:00:13 6227 8192000000 10549804096 10974425088 1182786576 12.616 100 53 106 1 1644544656 448078784 13679296
06:01:29 9507 8192000000 10552679328 11009241088 1173684080 12.531 100 38 76 1 1669984576 485980304 10320192
06:02:43 10534 8192000000 10558259680 11034902528 1165111152 12.451 100 33 66 0 1668344496 494385008 8848336
06:04:14 26190 8192000000 10564732672 11026989056 1125368736 12.078 100 26 52 1 1669522864 535812896 8341232
06:05:48 15707 8192000000 10223142480 10721415168 1128139888 12.104 99 33 66 0 1337616640 201691904 7784848
06:07:06 41882 8192000000 10103317728 10588663808 1114394736 11.974 98 13 26 2 1192000000 74734160 2871104
06:08:48 79898 8192000000 10091945920 10592776192 1094332368 11.784 97 4 5 2 1135278448 40324080 622000
06:11:09 111499 8192000000 10071790928 10566610944 1115896624 11.988 99 5 10 0 1193696320 77020736 778960
06:14:05 12782 8192000000 10088735392 10524340224 1116007296 11.989 98 57 109 2 1166701424 38816000 11878128
06:15:18 71891 8192000000 10119460352 10664931328 1135391120 12.172 100 11 22 0 1257567360 119644672 2531568
06:17:53 164036 8192000000 10101013440 10569682944 1126409968 12.088 99 10 20 1 1180084176 51801936 1872272
06:21:41 187940 8192000000 10084215456 10542448640 1129889392 12.120 99 19 38 0 1190137376 56468896 3779088
06:25:52 25420 8192000000 10143343120 10586226688 1149782224 12.307 100 34 68 1 1213138960 56411200 6945536
06:27:22 39905 8192000000 10101980112 10569936896 1135847488 12.176 99 23 45 0 1200814368 60037040 4929840
06:29:11 61898 8192000000 10067701888 10521837568 1121114768 12.038 98 14 28 0 1151716480 27422480 3179232
06:31:17 53129 8192000000 10101828304 10555887616 1148189600 12.293 100 26 52 1 1182372208 28319312 5863296
06:33:17 209623 8192000000 10072774576 10606342144 1083823728 11.684 94 15 30 0 1151068768 63817360 3427680
06:38:02 58821 8192000000 10044479184 10523754496 1053071808 11.390 93 12 24 1 1082389152 26570912 2746432
06:40:05 19022 8192000000 10108582384 10543407104 1108033168 11.914 98 58 116 1 1161643904 40951808 12658928
06:41:27 84301 8192000000 10076797360 10558263296 1053846128 11.398 93 5 10 0 1089688992 35136160 706704
06:44:03 27821 8192000000 10071821968 10518462464 1114133344 11.972 99 25 50 2 1151363472 31819296 5410832
06:45:38 57472 8192000000 10107451808 10550493184 1100477328 11.842 97 20 40 0 1147033968 42722512 3834128
06:47:40 106469 8192000000 10040871616 10493489152 1067556176 11.529 94 10 20 0 1161068896 91432160 2080560
06:50:26 27851 8192000000 10111896640 10528907264 1102887328 11.865 99 54 107 2 1167107744 53691856 10528560
06:51:57 118448 8192000000 10064117360 10594799616 1119596992 12.023 100 28 56 1 1227593344 101903584 6092768
06:55:06 74668 8192000000 10048387616 10499440640 1101322448 11.850 98 16 32 1 1136355008 31886544 3146016
06:57:21 34509 8192000000 10065128064 10531008512 1131769296 12.138 100 27 54 0 1176027280 38378480 5879504
06:58:56 68455 8192000000 10048515280 10515173376 1110468720 11.937 98 8 16 0 1148565008 36681504 1414784
07:01:09 36172 8192000000 10090482336 10535448576 1121884480 12.045 99 37 74 4 1184562208 55160976 7516752
07:02:51 59667 8192000000 10160027664 10654859264 1145312672 12.265 100 25 50 3 1219480848 68402560 5765616
07:04:53 26814 8192000000 10071853920 10503426048 1093030592 11.771 96 27 54 1 1135565024 36723264 5811168
07:06:23 47800 8192000000 10114304544 10572808192 1122974912 12.055 99 38 76 0 1216177280 85160016 8042352
07:08:11 45362 8192000000 10127365216 10594541568 1121138928 12.038 99 37 74 1 1239809168 111049312 7620928
07:10:50 82649 8192000000 10064857360 10575753216 1117956608 12.008 98 11 22 1 1171858144 51902960 1998576
07:13:27 89601 8192000000 10101457840 10596990976 1133820544 12.157 99 14 29 1 1192319424 55316640 3182240
07:17:02 70975 8192000000 10149219952 10631688192 1155476416 12.361 100 29 58 0 1236982592 75248704 6257472
07:19:29 82212 8192000000 10129004528 10605559808 1122495536 12.051 97 24 48 0 1169851056 41819328 5536192
07:21:58 80588 8192000000 10050506176 10494676992 1085793232 11.703 95 10 20 1 1128975664 41214848 1967584
07:24:21 63662 8192000000 10081221808 10581233664 1117922448 12.007 98 22 44 1 1173057792 50922048 4213296
07:26:25 30175 8192000000 10098074576 10570686464 1132187776 12.142 98 29 58 2 1197109376 59253952 5667648
07:27:56 81174 8192000000 10088240864 10577399808 1112384592 11.955 97 13 26 0 1164319360 49386576 2548192
07:30:42 49676 8192000000 10071801360 10565361664 1129095936 12.113 99 8 16 0 1162116560 31559024 1461600
07:32:31 62899 8192000000 10078939648 10556747776 1144950688 12.262 100 4 8 0 1183172912 37477344 744880
07:34:34 50262 8192000000 10102765488 10606391296 1115090160 11.981 97 11 22 0 1167907440 50237136 2580144
07:36:32 46899 8192000000 9281049824 9591525376 363632432 4.250 32 13 26 0 418298016 51999072 2666512

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-06-2021 07:38:51 to 19-06-2021 07:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2021 07:39:07 to 19-06-2021 07:39:09.

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          225618
MON$OLDEST_ACTIVE               229948
MON$OLDEST_SNAPSHOT             229948
MON$NEXT_TRANSACTION            229948
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                       2118400
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/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 Jun 19 07:37:21 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Jun 19 07:37:21 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Jun 19 07:37:27 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

19.06.21 07:39:10. 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
2021-06-19 07:39:13 No severe PSQL-related problems occured
19.06.21 07:39:14. End of report.