Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 04: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 17204724 kB
MemAvailable 31549468 kB
Buffers 20 kB
Cached 14451200 kB
SwapCached 3816 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-23 04:26:54
DTS_END                         2020-10-23 04:26:54.7700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/46474
TRN_ID                          233610
ATT_ID                          584
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: 8396.23 23681 1007548
customer order: creation 167.71 452 20125
customer order: refuse 14.17 302 1700
order to supplier: creation 44.76 1545 5371
order to supplier: removal 3.16 113274 379
invoice (draft): creation 46.03 3648 5523
invoice (draft): removal 46.64 983 5597
invoice accept: apply 39.96 18656 4795
invoice accept: cancel 42.11 913 5053
customer reserve: creation 3645.50 185 437460
customer reserve: removal 4067.46 70 488095
realization accept: apply 81.54 185 9785
realization accept: cancel 73.08 116 8769
payment from customer: creation 57.97 103 6956
payment from customer: removal 5.45 66 654
payment to supplier: creation 46.08 106 5530
payment to supplier: removal 3.44 60 413
service: total inventory turnovers 4.08 3044 489
service: total monetary turnovers 6.17 440 740
service: aggregate perf. data 0.92 15625 110
service: refresh index statistics 0.03 313847 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-10-2020 04:28:17 to 23-10-2020 04:28:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1800
TEST_TIME 2 3487
TEST_TIME 3 5158
TEST_TIME 4 5081
TEST_TIME 5 5385
TEST_TIME 6 5328
TEST_TIME 7 5833
TEST_TIME 8 5995
TEST_TIME 9 5986
TEST_TIME 10 6169
TEST_TIME 11 6282
TEST_TIME 12 6446
TEST_TIME 13 6373
TEST_TIME 14 6988
TEST_TIME 15 7488
TEST_TIME 16 7456
TEST_TIME 17 8017
TEST_TIME 18 8131
TEST_TIME 19 8178
TEST_TIME 20 8127
TEST_TIME 21 9080
TEST_TIME 22 9622
TEST_TIME 23 9960
TEST_TIME 24 10288
TEST_TIME 25 10316
TEST_TIME 26 10898
TEST_TIME 27 10561
TEST_TIME 28 10689
TEST_TIME 29 10405
TEST_TIME 30 10178
TEST_TIME 31 9921
TEST_TIME 32 9811
TEST_TIME 33 9716
TEST_TIME 34 9631
TEST_TIME 35 9671
TEST_TIME 36 9469
TEST_TIME 37 9392
TEST_TIME 38 9277
TEST_TIME 39 9142
TEST_TIME 40 9041
TEST_TIME 41 9132
TEST_TIME 42 9145
TEST_TIME 43 9069
TEST_TIME 44 8948
TEST_TIME 45 8850
TEST_TIME 46 8790
TEST_TIME 47 8704
TEST_TIME 48 8620
TEST_TIME 49 8701
TEST_TIME 50 8760
TEST_TIME 51 8667
TEST_TIME 52 8689
TEST_TIME 53 8702
TEST_TIME 54 8600
TEST_TIME 55 8683
TEST_TIME 56 8664
TEST_TIME 57 8681
TEST_TIME 58 8695
TEST_TIME 59 8683
TEST_TIME 60 8723
TEST_TIME 61 8803
TEST_TIME 62 8663
TEST_TIME 63 8590
TEST_TIME 64 8527
TEST_TIME 65 8458
TEST_TIME 66 8409
TEST_TIME 67 8486
TEST_TIME 68 8546
TEST_TIME 69 8575
TEST_TIME 70 8598
TEST_TIME 71 8594
TEST_TIME 72 8546
TEST_TIME 73 8660
TEST_TIME 74 8688
TEST_TIME 75 8698
TEST_TIME 76 8679
TEST_TIME 77 8739
TEST_TIME 78 8776
TEST_TIME 79 8732
TEST_TIME 80 8640
TEST_TIME 81 8613
TEST_TIME 82 8556
TEST_TIME 83 8496
TEST_TIME 84 8502
TEST_TIME 85 8512
TEST_TIME 86 8506
TEST_TIME 87 8528
TEST_TIME 88 8519
TEST_TIME 89 8555
TEST_TIME 90 8521
TEST_TIME 91 8568
TEST_TIME 92 8628
TEST_TIME 93 8591
TEST_TIME 94 8607
TEST_TIME 95 8678
TEST_TIME 96 8658
TEST_TIME 97 8631
TEST_TIME 98 8578
TEST_TIME 99 8547
TEST_TIME 100 8516
TEST_TIME 101 8509
TEST_TIME 102 8485
TEST_TIME 103 8459
TEST_TIME 104 8468
TEST_TIME 105 8507
TEST_TIME 106 8518
TEST_TIME 107 8489
TEST_TIME 108 8515
TEST_TIME 109 8480
TEST_TIME 110 8557
TEST_TIME 111 8611
TEST_TIME 112 8574
TEST_TIME 113 8568
TEST_TIME 114 8506
TEST_TIME 115 8470
TEST_TIME 116 8447
TEST_TIME 117 8455
TEST_TIME 118 8424
TEST_TIME 119 8417
TEST_TIME 120 8397

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2020 04:28:21 to 23-10-2020 04:28: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 1413840 1413840 0 0.00 0 30479 7 0 0
doc_list_biud 1413836 1413836 0 0.00 0 72155 31 0 0
sp_add_invoice_to_stock 4922 4795 127 2.58 29 198820 18656 0 127
sp_cancel_adding_invoice 5053 5053 0 0.00 5 68095 913 0 0
sp_cancel_client_order 1700 1700 0 0.00 4 10757 303 0 0
sp_cancel_customer_reserve 488095 488095 0 0.00 1 72156 70 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 3698 66 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 2001 61 0 0
sp_cancel_supplier_invoice 5607 5597 10 0.17 2 45329 984 0 10
sp_cancel_supplier_order 379 379 0 0.00 6 913123 113275 0 0
sp_cancel_write_off 8769 8769 0 0.00 1 7950 116 0 0
sp_client_order 20125 20125 0 0.00 4 61774 453 0 0
sp_customer_reserve 437460 437460 0 0.00 0 80342 185 0 0
sp_fill_shopping_cart 31005 31005 0 0.00 1 34201 49 0 0
sp_fill_shopping_cart_clo_res 437459 437459 0 0.00 0 42263 22 0 0
sp_get_clo_for_invoice 4794 4794 0 0.00 0 43581 427 0 0
sp_kill_cost_storno 14889 14889 0 0.00 0 22757 59 0 0
sp_kill_qstorno_ret_qs2qd 990935 990935 0 0.00 0 72139 38 0 0
sp_kill_qty_storno 524169 524169 0 0.00 0 72155 82 0 0
sp_lock_selected_doc 20197 20197 0 0.00 0 7352 5 0 0
sp_make_cost_storno 27065 27065 0 0.00 0 10748 49 0 0
sp_multiply_rows_for_pdistr 27062 27062 0 0.00 0 7514 28 0 0
sp_multiply_rows_for_qdistr 20125 20125 0 0.00 0 19214 249 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 7753 103 0 0
sp_pay_to_supplier 5530 5530 0 0.00 5 6711 107 0 0
sp_payment_common 12486 12486 0 0.00 1 5593 19 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 10741 278 0 0
sp_qd_handle_on_invoice_upd_sts 9847 9847 0 0.00 0 46337 378 0 0
sp_qd_handle_on_reserve_upd_sts 9782 9782 0 0.00 0 25958 65 0 0
sp_reserve_write_off 9785 9785 0 0.00 6 30546 186 0 0
sp_supplier_invoice 5523 5523 0 0.00 7 83117 3649 0 0
sp_supplier_order 5371 5371 0 0.00 5 68230 1546 0 0
srv_aggregate_perf_data 110 110 0 0.00 2796 344154 15626 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2410 214117 50631 0 0
srv_find_qd_qs_mism 962233 962233 0 0.00 0 15834 6 0 0
srv_make_invnt_saldo 489 489 0 0.00 58 97362 3044 0 0
srv_make_money_saldo 740 740 0 0.00 1 10535 441 0 0
srv_recalc_idx_stat 4 4 0 0.00 71894 608839 313848 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4795 4795 0 0.00 0 21067 157 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 2809 90 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 11325 24 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 6768 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 1880 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 6108 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 3568 26 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 23673 1568 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 1554 7 0 0
t$perf-norm:sp_client_order 20126 20126 0 0.00 0 7148 16 0 0
t$perf-norm:sp_customer_reserve 911 911 0 0.00 0 480 3 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 18063 12 0 0
t$perf-norm:sp_pay_to_supplier 5530 5530 0 0.00 0 7264 9 0 0
t$perf-norm:sp_reserve_write_off 9785 9785 0 0.00 0 6386 10 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 5122 16 0 0
t$perf-norm:sp_supplier_order 5371 5371 0 0.00 0 3426 13 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 721 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 71 2 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 914 8 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 1519 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9842 9588 254 2.58 0 7764 18 0 254
v_all_customers 36986 36986 0 0.00 0 28 1 0 0
v_all_suppliers 29654 29654 0 0.00 0 70 1 0 0
v_all_wares 40248 40248 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2677 13 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2249 5 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 3689 11 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 1812 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 656 5 0 0
v_cancel_supplier_invoice 824 804 20 2.42 0 7836 24 0 20
v_cancel_supplier_order 758 758 0 0.00 0 2582 7 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1531 14 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 471 1 0 0
v_max_id_clo_res 911 911 0 0.00 0 5189 28 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 4863 7 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 128 1 0 0
v_max_non_paid_realizn 3564 3564 0 0.00 0 678 3 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 10014 8 0 0
v_min_id_clo_res 911 911 0 0.00 0 3350 9 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 6332 12 0 0
v_min_non_paid_invoice 5530 5530 0 0.00 0 4406 15 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 5584 12 0 0
v_our_firm 10046 10046 0 0.00 0 12 1 0 0
v_reserve_write_off 19564 19564 0 0.00 0 5343 5 0 0
x_lock_depdocs_on_canc_invoice 10248 10248 0 0.00 0 12857 121 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 10673 361 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 2 67928 1469 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 3 83077 3459 0 0
x_make_qstorno_2100_3300 437460 437460 0 0.00 0 80340 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 04:28:26 to 23-10-2020 04:28: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
01:56:58 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
01:57:59 21795 8192000000 9710661024 10007605248 790619424 8.801 100 89 178 1 820266256 7642640 22004192
01:59:36 22987 8192000000 9754102368 10058428416 824992128 9.149 100 84 168 1 859779600 14791632 19995840
02:01:04 20331 8192000000 9813458064 10134982656 873614080 9.636 100 83 165 2 919238240 26402272 19221888
02:02:30 14620 8192000000 9892400032 10227974144 923681184 10.132 100 78 156 3 998531600 57316592 17533824
02:03:47 6383 8192000000 9914921488 10262982656 967316656 10.561 100 80 157 2 1033766400 50555280 15894464
02:04:56 5515 8192000000 9962211632 10326917120 1001427104 10.892 100 65 130 1 1082568736 68153216 12988416
02:06:03 2851 8192000000 10001440096 10363035648 1027398576 11.143 100 92 182 1 1111809808 66386400 18024832
02:07:07 1623 8192000000 10028449968 10396135424 1058916496 11.446 100 93 186 2 1140258608 62178400 19163712
02:08:11 6131 8192000000 10019465824 10399670272 1065872816 11.513 100 82 163 0 1136750736 54404576 16473344
02:09:17 4066 8192000000 9991846576 10387898368 1074465504 11.595 100 37 75 2 1113728944 30884864 8378576
02:10:22 58586 8192000000 10000566176 10398109696 1084134448 11.687 100 30 60 2 1119534224 29022272 6377504
02:12:21 4929 8192000000 10011169648 10418937856 1085523952 11.700 100 28 56 2 1128612128 37433344 5654832
02:13:28 6186 8192000000 10000791088 10401538048 1090046960 11.743 100 38 75 2 1111448240 13313552 8087728
02:14:38 6984 8192000000 10020681936 10426249216 1097056192 11.810 100 41 82 3 1125567808 19799504 8712112
02:15:47 38665 8192000000 10015298336 10429526016 1099820240 11.836 100 36 73 2 1128724304 21800000 7104064
02:17:35 5821 8192000000 9981829088 10377711616 1054760112 11.406 98 47 92 3 1091435504 26254176 10421216
02:18:41 6744 8192000000 9981743808 10364170240 1039294400 11.258 98 42 83 3 1066712512 18706096 8712016
02:19:51 67523 8192000000 9898072176 10287177728 949118576 10.382 93 18 36 0 977137936 24176864 3842496
02:21:59 5366 8192000000 9913027328 10287702016 993342144 10.814 98 25 48 2 1004799088 6094752 5362192
02:23:07 6192 8192000000 9947717344 10328686592 1039350368 11.258 100 45 90 0 1059192960 9479792 10362800
02:24:18 5776 8192000000 9966175168 10351009792 1048570752 11.347 100 43 87 1 1069840864 11857360 9412752
02:25:29 33455 8192000000 9978114048 10387849216 1052874432 11.388 100 31 62 1 1082467232 22907856 6684944
02:27:08 33448 8192000000 9999778496 10389983232 1081291200 11.660 100 48 99 1 1102313856 10979248 10043408
02:28:45 7372 8192000000 10056073728 10446811136 1100044224 11.838 100 61 122 0 1162290368 48798720 13447424
02:29:54 25135 8192000000 10100707536 10501001216 1119578544 12.023 100 57 113 0 1205820544 74329456 11912544
02:31:22 36387 8192000000 10136160672 10568003584 1125530224 12.079 100 36 70 5 1255943648 122421952 7991472
02:33:06 6981 8192000000 10181632368 10585346048 1143825472 12.252 100 49 98 1 1285396160 130631536 10939152
02:34:20 8373 8192000000 10190920704 10591289344 1150056800 12.310 100 70 140 4 1294895568 129775472 15063296
02:35:32 19925 8192000000 10231754592 10633879552 1153826208 12.345 100 62 124 0 1327721856 160565856 13329792
02:36:56 5919 8192000000 10260997664 10668089344 1163667728 12.438 100 70 136 3 1367490048 189284800 14537520
02:38:09 11895 8192000000 10304351008 10718420992 1173101296 12.526 100 63 124 1 1428486096 241292128 14092672
02:39:27 6013 8192000000 10315023200 10718523392 1155770896 12.364 98 70 138 2 1408856912 236386528 16699488
02:40:39 3085 8192000000 10366527616 10793697280 1170854240 12.505 100 80 160 0 1469682448 280000960 18827248
02:41:47 5174 8192000000 10331974624 10759712768 1171359968 12.510 100 66 130 0 1431667024 245143040 15164016
02:42:59 2407 8192000000 10375160912 10773782528 1159595088 12.399 100 79 158 0 1472774880 295591888 17587904
02:44:06 7063 8192000000 10342700560 10762072064 1146594128 12.278 99 43 87 1 1437909760 280714432 10601200
02:45:29 9527 8192000000 10368530240 10781020160 1156009872 12.366 100 49 97 1 1458923008 290970000 11943136
02:46:42 4011 8192000000 10401521984 10808082432 1144021536 12.253 100 84 169 1 1501462560 336261200 21179824
02:47:49 24349 8192000000 10323812784 10752024576 1127247008 12.095 99 32 64 1 1434409936 297894960 9267968
02:49:22 24003 8192000000 10201612416 10641612800 1125218976 12.076 100 19 38 0 1316814000 186513312 5081712
02:50:49 12800 8192000000 10177417904 10607525888 1112449152 11.956 99 18 34 2 1297073808 180309264 4315392
02:52:02 89610 8192000000 10073371072 10489950208 1060671520 11.463 95 16 32 0 1211284560 146365840 4247200
02:54:37 104961 8192000000 10057586368 10512744448 1115528128 11.985 100 18 36 0 1172419312 53405104 3486080
02:57:25 36219 8192000000 10089815152 10521161728 1105939184 11.894 99 43 87 0 1167406640 52743712 8723744
02:59:08 91227 8192000000 10055147136 10501509120 1079006112 11.638 96 11 22 0 1143382272 62491328 1884832
03:02:18 31317 8192000000 10041577520 10486177792 1101580880 11.853 99 11 22 0 1136346480 32397216 2368384
03:04:04 74468 8192000000 10101167936 10542665728 1108572560 11.919 99 23 46 1 1186180880 72354816 5253504
03:06:19 45005 8192000000 10106729920 10564370432 1116845152 11.997 100 20 40 0 1225099488 103752704 4501632
03:08:18 47824 8192000000 10046844160 10511970304 1106570160 11.900 99 14 28 1 1134918768 25505088 2843520
03:10:25 53937 8192000000 10046357856 10495741952 1119810128 12.025 100 10 20 0 1155711360 34009360 1891872
03:12:19 56761 8192000000 10039200784 10519248896 1127732720 12.100 100 7 14 0 1165389728 36294544 1362464
03:14:16 99607 8192000000 10079237328 10573094912 1121278768 12.039 98 22 44 1 1187078224 60996912 4802544
03:16:56 39982 8192000000 10089626128 10521493504 1131426464 12.135 99 33 67 1 1191587696 52979696 7181536
03:18:46 122183 8192000000 10130412080 10568011776 1131512384 12.136 99 15 30 2 1170302672 36276496 2513792
03:22:10 130592 8192000000 10148025616 10611515392 1130792096 12.129 98 12 24 0 1199128288 65939072 2397120
03:25:24 30417 8192000000 10132522752 10553217024 1136373664 12.181 99 42 84 0 1205178928 60509072 8296192
03:26:55 90223 8192000000 10065991968 10541711360 1135533216 12.173 99 7 14 0 1197714064 60749776 1431072
03:29:25 47267 8192000000 10077000256 10521001984 1128029296 12.103 98 16 32 2 1153505440 22238624 3237520
03:31:15 66790 8192000000 10155870912 10604679168 1130343216 12.125 98 18 36 0 1199708064 65404064 3960784
03:34:33 71771 8192000000 10066721264 10503573504 1078035728 11.629 94 9 18 0 1126995472 47319136 1640608
03:36:45 32561 8192000000 10091116656 10519003136 1111334656 11.945 98 27 53 0 1165344112 48175696 5833760
03:38:40 30345 8192000000 10126673904 10549006336 1083507168 11.681 96 57 114 2 1164896496 68845920 12543408
03:40:26 71671 8192000000 10061769792 10506002432 1104277008 11.878 98 18 36 0 1172418736 64561136 3580592
03:42:38 52433 8192000000 10084121184 10532110336 1098616800 11.825 98 41 82 1 1152609936 45904240 8088896
03:44:33 56212 8192000000 10033012848 10471882752 1071850576 11.570 96 22 45 0 1104331888 27649344 4831968
03:46:40 79877 8192000000 10048687456 10503979008 1101169408 11.849 99 34 64 2 1165663536 57688032 6806096
03:49:30 41499 8192000000 10082135488 10531016704 1096361600 11.803 98 12 24 1 1167300672 67942000 2997072
03:51:13 53551 8192000000 10074851056 10564231168 1101436400 11.851 99 12 24 0 1161410960 57514176 2460384
03:53:10 45245 8192000000 10055527216 10509631488 1069925440 11.551 96 24 48 1 1136261904 60946304 5390160
03:54:56 57543 8192000000 10061989088 10528739328 1109201728 11.925 99 19 38 2 1175382832 62305248 3875856
03:56:54 55952 8192000000 10134685808 10588536832 1130038512 12.122 99 21 40 1 1214163952 79776384 4349056
03:59:07 42118 8192000000 10100704608 10528993280 1137025728 12.188 99 28 56 1 1187573552 44785456 5762368
04:01:05 73356 8192000000 10055748112 10490523648 1075772128 11.607 95 11 22 1 1126895088 48940160 2182800
04:03:18 127955 8192000000 10075893296 10530643968 1119106384 12.019 99 32 64 0 1207474176 81882080 6485712
04:06:36 76602 8192000000 10054634048 10523254784 1123106832 12.056 99 6 12 0 1160991600 36819536 1065232
04:08:53 87316 8192000000 10103759904 10550648832 1132713552 12.147 99 28 56 1 1212256688 73279488 6263648
04:11:34 74858 8192000000 10094348096 10558164992 1123086320 12.056 98 19 38 2 1185163520 57691312 4385888
04:13:49 52858 8192000000 10149865504 10605154304 1153678784 12.344 100 18 36 0 1231542160 73651616 4211760
04:15:46 102711 8192000000 10127184576 10580426752 1124167296 12.066 98 19 38 3 1214269136 85885120 4216720
04:18:30 15984 8192000000 10106273296 10524860416 1144092032 12.254 100 45 90 2 1180331392 27016880 9222480
04:19:50 214117 8192000000 10081805472 10553892864 1085635904 11.701 95 18 36 0 1132337264 43038848 3662512
04:24:38 33244 8192000000 10069954368 10505457664 1108875088 11.922 98 23 46 1 1167116224 52716672 5524464
04:26:13 26419 8192000000 10091551920 10536824832 1123440368 12.059 99 31 62 2 1178294032 47750352 7103312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-10-2020 04:28:32 to 23-10-2020 04:28:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 127 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

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

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          229440
MON$OLDEST_ACTIVE               233728
MON$OLDEST_SNAPSHOT             233728
MON$NEXT_TRANSACTION            233728
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                       2066336
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             778
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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Oct 23 04:26:58 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Oct 23 04:26:58 2020
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Oct 23 04:26:59 2020
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

23.10.20 04:28:50. 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-23 04:28:52 No severe PSQL-related problems occured
23.10.20 04:28:54. End of report.