Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16931464 kB
MemAvailable 31545276 kB
Buffers 0 kB
Cached 14732284 kB
SwapCached 3808 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-10-22 10:26:53
DTS_END                         2020-10-22 10:26:53.2130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/34550
TRN_ID                          233587
ATT_ID                          634
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: 9038.22 20924 1084586
customer order: creation 170.78 392 20494
customer order: refuse 14.24 327 1709
order to supplier: creation 44.37 1351 5324
order to supplier: removal 3.41 104367 409
invoice (draft): creation 44.89 3198 5387
invoice (draft): removal 49.89 846 5987
invoice accept: apply 41.69 14518 5003
invoice accept: cancel 45.49 790 5459
customer reserve: creation 3885.18 141 466221
customer reserve: removal 4446.03 64 533524
realization accept: apply 81.17 165 9740
realization accept: cancel 82.30 109 9876
payment from customer: creation 59.28 102 7114
payment from customer: removal 5.68 80 682
payment to supplier: creation 49.50 118 5940
payment to supplier: removal 3.30 105 396
service: total inventory turnovers 4.02 2737 482
service: total monetary turnovers 6.10 425 732
service: aggregate perf. data 0.86 11863 103
service: refresh index statistics 0.03 276789 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-10-2020 10:28:15 to 22-10-2020 10:28:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2837
TEST_TIME 2 4023
TEST_TIME 3 4107
TEST_TIME 4 4219
TEST_TIME 5 4466
TEST_TIME 6 4301
TEST_TIME 7 4411
TEST_TIME 8 4767
TEST_TIME 9 5076
TEST_TIME 10 5369
TEST_TIME 11 5777
TEST_TIME 12 5838
TEST_TIME 13 6440
TEST_TIME 14 7428
TEST_TIME 15 7818
TEST_TIME 16 7791
TEST_TIME 17 8047
TEST_TIME 18 7913
TEST_TIME 19 8290
TEST_TIME 20 8384
TEST_TIME 21 8492
TEST_TIME 22 9676
TEST_TIME 23 10810
TEST_TIME 24 10479
TEST_TIME 25 10414
TEST_TIME 26 10458
TEST_TIME 27 10292
TEST_TIME 28 10181
TEST_TIME 29 10004
TEST_TIME 30 9863
TEST_TIME 31 9824
TEST_TIME 32 9704
TEST_TIME 33 9856
TEST_TIME 34 9868
TEST_TIME 35 9844
TEST_TIME 36 9826
TEST_TIME 37 9826
TEST_TIME 38 9791
TEST_TIME 39 9993
TEST_TIME 40 9907
TEST_TIME 41 10004
TEST_TIME 42 10003
TEST_TIME 43 10147
TEST_TIME 44 9978
TEST_TIME 45 9829
TEST_TIME 46 9624
TEST_TIME 47 9607
TEST_TIME 48 9560
TEST_TIME 49 9557
TEST_TIME 50 9587
TEST_TIME 51 9601
TEST_TIME 52 9499
TEST_TIME 53 9388
TEST_TIME 54 9382
TEST_TIME 55 9322
TEST_TIME 56 9553
TEST_TIME 57 9494
TEST_TIME 58 9519
TEST_TIME 59 9578
TEST_TIME 60 9570
TEST_TIME 61 9588
TEST_TIME 62 9612
TEST_TIME 63 9606
TEST_TIME 64 9666
TEST_TIME 65 9668
TEST_TIME 66 9662
TEST_TIME 67 9737
TEST_TIME 68 9683
TEST_TIME 69 9821
TEST_TIME 70 9802
TEST_TIME 71 9671
TEST_TIME 72 9586
TEST_TIME 73 9561
TEST_TIME 74 9494
TEST_TIME 75 9440
TEST_TIME 76 9393
TEST_TIME 77 9336
TEST_TIME 78 9273
TEST_TIME 79 9253
TEST_TIME 80 9226
TEST_TIME 81 9195
TEST_TIME 82 9187
TEST_TIME 83 9219
TEST_TIME 84 9234
TEST_TIME 85 9395
TEST_TIME 86 9332
TEST_TIME 87 9356
TEST_TIME 88 9397
TEST_TIME 89 9457
TEST_TIME 90 9435
TEST_TIME 91 9392
TEST_TIME 92 9312
TEST_TIME 93 9264
TEST_TIME 94 9200
TEST_TIME 95 9193
TEST_TIME 96 9165
TEST_TIME 97 9148
TEST_TIME 98 9162
TEST_TIME 99 9171
TEST_TIME 100 9216
TEST_TIME 101 9178
TEST_TIME 102 9228
TEST_TIME 103 9201
TEST_TIME 104 9186
TEST_TIME 105 9228
TEST_TIME 106 9220
TEST_TIME 107 9224
TEST_TIME 108 9268
TEST_TIME 109 9295
TEST_TIME 110 9277
TEST_TIME 111 9201
TEST_TIME 112 9184
TEST_TIME 113 9140
TEST_TIME 114 9095
TEST_TIME 115 9066
TEST_TIME 116 9047
TEST_TIME 117 9046
TEST_TIME 118 9059
TEST_TIME 119 9071
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-10-2020 10:28:19 to 22-10-2020 10:28:23.

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 1516337 1516337 0 0.00 0 24075 6 0 0
doc_list_biud 1516336 1516336 0 0.00 0 46090 28 0 0
sp_add_invoice_to_stock 5188 5003 185 3.56 41 162788 14518 0 185
sp_cancel_adding_invoice 5459 5459 0 0.00 5 91863 791 0 0
sp_cancel_client_order 1709 1709 0 0.00 5 8462 327 0 0
sp_cancel_customer_reserve 533524 533524 0 0.00 1 43819 65 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 3927 81 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 7835 105 0 0
sp_cancel_supplier_invoice 6001 5987 14 0.23 2 19073 846 0 14
sp_cancel_supplier_order 409 409 0 0.00 7 941574 104368 0 0
sp_cancel_write_off 9876 9876 0 0.00 1 16744 109 0 0
sp_client_order 20494 20494 0 0.00 4 25324 393 0 0
sp_customer_reserve 466221 466221 0 0.00 0 86142 142 0 0
sp_fill_shopping_cart 31180 31180 0 0.00 1 28235 42 0 0
sp_fill_shopping_cart_clo_res 466213 466213 0 0.00 0 28014 15 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 25948 350 0 0
sp_kill_cost_storno 16413 16413 0 0.00 0 8140 54 0 0
sp_kill_qstorno_ret_qs2qd 1083320 1083320 0 0.00 0 43813 35 0 0
sp_kill_qty_storno 571695 571695 0 0.00 0 46088 74 0 0
sp_lock_selected_doc 20432 20432 0 0.00 0 8073 7 0 0
sp_make_cost_storno 27785 27785 0 0.00 0 17071 51 0 0
sp_multiply_rows_for_pdistr 27785 27785 0 0.00 0 15130 30 0 0
sp_multiply_rows_for_qdistr 20494 20494 0 0.00 0 25304 218 0 0
sp_pay_from_customer 7114 7114 0 0.00 4 15142 102 0 0
sp_pay_to_supplier 5940 5940 0 0.00 5 13362 119 0 0
sp_payment_common 13054 13054 0 0.00 1 9080 20 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 7819 287 0 0
sp_qd_handle_on_invoice_upd_sts 10450 10450 0 0.00 0 46086 319 0 0
sp_qd_handle_on_reserve_upd_sts 9740 9740 0 0.00 0 10532 45 0 0
sp_reserve_write_off 9740 9740 0 0.00 7 19789 166 0 0
sp_supplier_invoice 5387 5387 0 0.00 7 149606 3198 0 0
sp_supplier_order 5324 5324 0 0.00 5 36030 1352 0 0
srv_aggregate_perf_data 103 103 0 0.00 2345 157275 11863 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2792 142220 48009 0 0
srv_find_qd_qs_mism 1036964 1036964 0 0.00 0 24072 5 0 0
srv_make_invnt_saldo 482 482 0 0.00 60 164625 2737 0 0
srv_make_money_saldo 732 732 0 0.00 1 21364 425 0 0
srv_recalc_idx_stat 4 4 0 0.00 87467 469058 276790 0 0
t$perf-abend:sp_add_invoice_to_stock 185 185 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5003 5003 0 0.00 0 14003 138 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 1557 49 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 1728 9 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 3408 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 679 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 669 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 2698 21 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 23485 1389 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 4596 14 0 0
t$perf-norm:sp_client_order 20494 20494 0 0.00 0 6565 14 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 7909 14 0 0
t$perf-norm:sp_pay_from_customer 7114 7114 0 0.00 0 4303 6 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 8085 10 0 0
t$perf-norm:sp_reserve_write_off 9740 9740 0 0.00 0 6474 10 0 0
t$perf-norm:sp_supplier_invoice 5387 5387 0 0.00 0 3526 12 0 0
t$perf-norm:sp_supplier_order 5324 5324 0 0.00 0 7707 13 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 734 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 63 2 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 4018 16 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 2652 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10350 9980 370 3.57 0 6474 16 0 370
v_all_customers 38102 38102 0 0.00 0 19 1 0 0
v_all_suppliers 30206 30206 0 0.00 0 24 1 0 0
v_all_wares 40988 40988 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3709 9 0 0
v_cancel_client_order 3418 3418 0 0.00 0 3761 7 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3759 11 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 6482 10 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2561 12 0 0
v_cancel_supplier_invoice 800 772 28 3.50 0 1132 14 0 28
v_cancel_supplier_order 818 818 0 0.00 0 382 4 0 0
v_cancel_write_off 2026 2026 0 0.00 0 5932 19 0 0
v_max_id_clo_ord 5310 5310 0 0.00 0 1107 1 0 0
v_max_id_clo_res 975 975 0 0.00 0 3396 15 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 6716 7 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 76 1 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 881 2 0 0
v_min_id_clo_ord 5310 5310 0 0.00 0 8438 13 0 0
v_min_id_clo_res 975 975 0 0.00 0 3477 12 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 4154 4 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 5914 15 0 0
v_min_non_paid_realizn 7114 7114 0 0.00 0 6754 12 0 0
v_our_firm 9964 9964 0 0.00 0 52 1 0 0
v_reserve_write_off 19480 19480 0 0.00 0 5784 6 0 0
x_lock_depdocs_on_canc_invoice 11060 11060 0 0.00 0 11154 100 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 22740 346 0 0
x_make_qstorno_1000_1200 5324 5324 0 0.00 2 32266 1277 0 0
x_make_qstorno_1200_2000 5387 5387 0 0.00 2 146911 3034 0 0
x_make_qstorno_2100_3300 466221 466221 0 0.00 0 86141 124 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2020 10:28:24 to 22-10-2020 10:28:28.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:57 2 8192000000 8797545920 8808484864 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:57 27830 8192000000 9723607872 10019504128 790466688 8.800 100 89 178 0 820737024 7878608 22391728
07:59:45 27543 8192000000 9775680240 10084683776 844460912 9.345 100 91 182 0 879703616 13638704 21604000
08:01:18 24458 8192000000 9826819712 10150854656 880259984 9.702 100 80 161 0 925914640 27902832 17751824
08:02:44 12744 8192000000 9895251312 10228101120 932056304 10.215 100 86 172 0 1004295248 53446032 18792912
08:04:01 7833 8192000000 9937929584 10287501312 973053184 10.616 100 76 152 1 1053089680 64300816 15735680
08:05:11 5677 8192000000 9956870144 10315366400 1004796288 10.925 100 69 138 0 1076147984 56921504 14430192
08:06:18 3970 8192000000 9988463552 10358005760 1037682592 11.242 100 71 140 2 1108385568 56225216 14477760
08:07:24 2489 8192000000 10048395920 10419556352 1052040304 11.380 100 90 181 1 1153645728 83431712 18173712
08:08:30 1450 8192000000 10020892144 10390679552 1060618336 11.462 100 92 184 2 1131830448 52212064 19000048
08:09:32 4556 8192000000 9992160816 10385104896 1062002144 11.476 100 42 84 2 1112463072 42085760 8375168
08:10:37 5071 8192000000 9987113216 10381295616 1070277280 11.555 100 31 63 0 1106704496 29834352 6592864
08:11:43 65849 8192000000 9987473616 10391748608 1081859472 11.665 100 21 40 0 1117675520 31332144 4483904
08:13:50 3320 8192000000 10019054992 10413977600 1089210288 11.735 100 65 130 1 1123534128 20479520 13844320
08:14:55 10285 8192000000 10003549968 10416885760 1092533136 11.767 100 34 65 2 1113981760 14652480 6796144
08:16:07 18000 8192000000 9985574272 10396073984 1085453296 11.699 99 22 44 2 1108496240 18018288 5024656
08:17:25 5131 8192000000 10014154624 10420551680 1074311216 11.593 98 32 64 0 1116304480 35071312 6921952
08:18:33 5690 8192000000 9968176832 10355470336 1030415504 11.172 97 21 41 0 1064537376 29706960 4414912
08:19:39 57248 8192000000 9928858800 10304671744 990380016 10.785 96 38 75 0 1021147664 22642368 8125280
08:21:36 5625 8192000000 9935008128 10317774848 1000362512 10.882 98 47 93 1 1028949648 17411344 11175792
08:22:44 12599 8192000000 9954386400 10345517056 1016180080 11.035 98 29 58 1 1046279824 23933312 6166432
08:23:57 8003 8192000000 9943831104 10334355456 1029391696 11.163 99 35 67 2 1049679136 12594848 7692592
08:25:05 38716 8192000000 9972121088 10379587584 1049500864 11.356 100 24 48 1 1081564128 27377184 4686080
08:26:49 20577 8192000000 9990706064 10388090880 1076616144 11.615 100 45 91 4 1105605568 19440000 9549424
08:28:15 8997 8192000000 10045568256 10440876032 1100241008 11.840 100 46 92 0 1147327008 37742640 9343360
08:29:32 39899 8192000000 10099124976 10505039872 1125091696 12.075 100 46 90 5 1199957104 65118944 9746464
08:31:16 22430 8192000000 10148927136 10557747200 1141662480 12.231 100 48 95 1 1256914896 104521664 10730752
08:32:48 16272 8192000000 10201415296 10611380224 1152345232 12.332 100 40 80 1 1306895856 145479952 9070672
08:34:08 16408 8192000000 10251326112 10657738752 1167027472 12.469 100 56 111 1 1352035840 172474576 12533792
08:35:37 2790 8192000000 10301432704 10707804160 1182745232 12.616 100 72 146 1 1406023072 206978672 16299168
08:36:45 7916 8192000000 10356284032 10763902976 1175192576 12.545 100 66 130 1 1452262752 262335888 14734288
08:38:11 15548 8192000000 10397976512 10821287936 1194146944 12.722 100 59 118 2 1492960384 284463648 14349792
08:39:34 3755 8192000000 10420857472 10856185856 1186977616 12.655 99 61 122 1 1512434528 310860432 14596480
08:40:47 8059 8192000000 10350966240 10786762752 1193937120 12.720 100 56 112 0 1439148128 232177664 13033344
08:41:59 14931 8192000000 10328708800 10753957888 1178330464 12.575 99 36 72 1 1419025088 232096912 8597712
08:43:16 17678 8192000000 10334498176 10766483456 1169087232 12.488 100 30 59 1 1442804016 266372576 7344208
08:44:37 11372 8192000000 10367263136 10795405312 1152074768 12.329 100 27 54 0 1468253264 308998736 7179760
08:45:54 13712 8192000000 10354480928 10787246080 1140456384 12.220 99 26 51 1 1445799760 298853856 6489520
08:47:15 17295 8192000000 10360567264 10808008704 1105032464 11.885 98 30 60 1 1459968176 346478752 8456960
08:48:37 27002 8192000000 10205506048 10624446464 1057017312 11.428 95 25 49 1 1306610656 243384304 6209040
08:50:12 44115 8192000000 10083393488 10508390400 1110353664 11.936 100 19 35 1 1170739776 56076256 4309856
08:52:01 111294 8192000000 10038659280 10536087552 1057026208 11.428 94 9 16 0 1118398272 59658832 1713232
08:54:53 27143 8192000000 10014069536 10444599296 1080495520 11.652 97 29 57 1 1095680304 9357648 5827136
08:56:29 54882 8192000000 10011012832 10442006528 1053055120 11.390 95 26 54 1 1084827664 26275456 5497088
08:58:25 18470 8192000000 10065512624 10480054272 1100831424 11.846 99 36 71 4 1156543568 48322352 7389792
08:59:44 97245 8192000000 10060090592 10517782528 1076960144 11.618 97 6 12 0 1120530880 42726128 844608
09:02:22 55255 8192000000 10053886128 10509619200 1113302672 11.964 99 25 50 0 1170212112 51568656 5340784
09:04:37 80824 8192000000 10059258272 10532761600 1109129152 11.924 99 12 24 0 1194563744 82669776 2764816
09:06:59 44253 8192000000 10079631792 10547298304 1131705344 12.137 100 14 28 0 1191223024 56791200 2726480
09:08:44 73329 8192000000 10081946672 10580860928 1120439600 12.031 98 14 29 1 1148558848 25344288 2774960
09:10:57 142220 8192000000 10106460576 10546085888 1146503680 12.277 100 42 84 3 1200868032 44261504 10102848
09:14:20 86552 8192000000 10138607728 10634555392 1157977168 12.384 100 38 76 0 1254347904 88118272 8252464
09:16:47 66963 8192000000 10095791248 10552442880 1131017744 12.131 98 19 38 0 1207254512 71877536 4359232
09:18:57 64995 8192000000 10173655072 10619285504 1150698272 12.316 99 15 30 0 1262840672 108519504 3622896
09:21:09 46944 8192000000 10176682160 10647883776 1135919600 12.177 98 17 34 0 1261221808 120990000 4312208
09:23:09 44479 8192000000 10088472912 10533695488 1135960912 12.178 98 22 44 2 1183528656 42733056 4834688
09:25:01 39677 8192000000 10118762704 10549104640 1142803376 12.242 99 27 55 0 1194546368 45894128 5848864
09:26:42 93461 8192000000 10099903488 10553782272 1133318016 12.153 98 25 48 1 1171817360 33672800 4826544
09:29:43 31473 8192000000 10114420432 10536919040 1123178912 12.057 98 28 54 0 1175798048 47219424 5399712
09:31:15 29150 8192000000 10082403408 10543583232 1113138592 11.962 98 18 33 2 1154670672 38396464 3135616
09:32:46 62992 8192000000 10087722080 10542370816 1103633648 11.872 97 19 38 0 1164169488 56529824 4006016
09:34:53 42683 8192000000 10094979008 10577485824 1101687632 11.854 97 17 34 1 1170332592 65124704 3520256
09:36:40 116162 8192000000 10051262032 10485989376 1104495280 11.880 98 16 30 1 1138828128 31357984 2974864
09:39:37 52765 8192000000 10023107008 10470281216 1100838288 11.846 98 15 30 0 1121002192 17165280 2998624
09:41:30 63233 8192000000 10028202832 10465640448 1093825440 11.779 98 9 18 2 1117769984 22200240 1744304
09:44:21 31942 8192000000 10050115776 10486652928 1112591664 11.957 100 16 32 2 1159881664 43623040 3666960
09:45:54 81966 8192000000 10085738480 10527744000 1096854016 11.808 98 22 43 2 1146402496 43788192 5760288
09:48:16 35472 8192000000 10107949824 10548170752 1085410832 11.699 97 34 67 2 1188149616 94988384 7750400
09:49:53 53537 8192000000 10140328544 10612232192 1113631280 11.967 99 25 50 3 1251655216 132351920 5672016
09:52:00 52291 8192000000 10105745792 10528632832 1124543488 12.070 100 23 45 1 1192526048 63517696 4464864
09:54:12 47496 8192000000 10065787536 10519728128 1071590784 11.567 96 25 50 1 1156539984 79694544 5254656
09:56:00 20704 8192000000 10054437392 10485432320 1093865232 11.779 98 48 96 2 1125925872 22448368 9612272
09:57:20 95520 8192000000 10045241728 10501746688 1112851520 11.959 99 16 32 1 1144098944 27922256 3325168
10:00:05 61031 8192000000 10073895216 10532995072 1123269168 12.058 100 6 12 0 1185805280 61418528 1117584
10:02:06 37372 8192000000 10080668096 10521432064 1132834064 12.148 100 24 47 0 1170316432 32673264 4809104
10:03:44 89600 8192000000 10126821568 10610499584 1144642080 12.259 100 14 27 1 1220835968 73377040 2816848
10:06:14 78306 8192000000 10117258080 10585919488 1149887296 12.308 100 11 22 2 1207912928 55521808 2503824
10:08:44 45221 8192000000 10084441920 10520555520 1139737360 12.213 99 34 68 0 1166964928 20153664 7073904
10:10:40 54317 8192000000 10134310416 10578976768 1127806512 12.101 98 21 42 0 1204165104 71699792 4658800
10:12:36 33812 8192000000 10075526768 10504101888 1116162368 11.991 97 12 24 1 1155590496 36930080 2498048
10:14:16 60700 8192000000 10085715456 10566615040 1081389376 11.661 95 15 30 1 1155003664 70780832 2833456
10:16:23 115320 8192000000 10079493344 10550497280 1119410160 12.021 99 19 38 2 1157535872 34190864 3934848
10:20:58 70605 8192000000 10097165760 10546126848 1110768720 11.940 97 11 22 0 1162268944 49063616 2436608
10:23:09 38449 8192000000 10119074016 10571857920 1121151792 12.038 98 22 44 2 1193193808 67466528 4575488
10:24:56 33260 8192000000 10117632304 10550300672 1113038400 11.961 98 30 60 4 1189875744 69917712 6919632
10:26:38 32304 8192000000 8913011936 9138761728 68945952 0.834 7 2 2 1 85102160 15776480 379728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2020 10:28:29 to 22-10-2020 10:28:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 185 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2020 10:28:45 to 22-10-2020 10:28:47.

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          233889
MON$OLDEST_ACTIVE               233890
MON$OLDEST_SNAPSHOT             233890
MON$NEXT_TRANSACTION            233890
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                       2097792
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             776
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2232 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2232 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2232 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.2232 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Oct 22 10:27:12 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

22.10.20 10:28:48. 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-10-22 10:28:51 No severe PSQL-related problems occured
22.10.20 10:28:52. End of report.