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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16876392 kB
MemAvailable 31604184 kB
Buffers 20 kB
Cached 14762608 kB
SwapCached 0 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 2021-03-10 04:26:57
DTS_END                         2021-03-10 04:26:57.3150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58914
TRN_ID                          229607
ATT_ID                          682
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: 8673.64 24333 1040837
customer order: creation 165.62 401 19874
customer order: refuse 14.13 393 1695
order to supplier: creation 43.87 1438 5264
order to supplier: removal 3.49 115704 419
invoice (draft): creation 44.33 3821 5320
invoice (draft): removal 47.59 1038 5711
invoice accept: apply 40.58 18117 4870
invoice accept: cancel 43.07 939 5168
customer reserve: creation 3768.05 174 452166
customer reserve: removal 4222.01 76 506641
realization accept: apply 79.20 207 9504
realization accept: cancel 78.70 128 9444
payment from customer: creation 57.47 130 6896
payment from customer: removal 5.59 102 671
payment to supplier: creation 46.80 149 5616
payment to supplier: removal 3.37 121 404
service: total inventory turnovers 3.41 4453 409
service: total monetary turnovers 5.45 656 654
service: aggregate perf. data 0.89 14864 107
service: refresh index statistics 0.03 323756 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-03-2021 04:28:56 to 10-03-2021 04:28:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2925
TEST_TIME 2 3163
TEST_TIME 3 2205
TEST_TIME 4 3154
TEST_TIME 5 3197
TEST_TIME 6 3714
TEST_TIME 7 4242
TEST_TIME 8 4111
TEST_TIME 9 4539
TEST_TIME 10 4668
TEST_TIME 11 4901
TEST_TIME 12 4948
TEST_TIME 13 5394
TEST_TIME 14 5482
TEST_TIME 15 6178
TEST_TIME 16 6305
TEST_TIME 17 6765
TEST_TIME 18 6881
TEST_TIME 19 7063
TEST_TIME 20 7546
TEST_TIME 21 7538
TEST_TIME 22 7776
TEST_TIME 23 7876
TEST_TIME 24 7906
TEST_TIME 25 8057
TEST_TIME 26 8387
TEST_TIME 27 8490
TEST_TIME 28 8722
TEST_TIME 29 9336
TEST_TIME 30 10278
TEST_TIME 31 10313
TEST_TIME 32 10162
TEST_TIME 33 10232
TEST_TIME 34 10048
TEST_TIME 35 10043
TEST_TIME 36 9993
TEST_TIME 37 9840
TEST_TIME 38 9706
TEST_TIME 39 9672
TEST_TIME 40 9580
TEST_TIME 41 9475
TEST_TIME 42 9578
TEST_TIME 43 9561
TEST_TIME 44 9469
TEST_TIME 45 9453
TEST_TIME 46 9388
TEST_TIME 47 9378
TEST_TIME 48 9541
TEST_TIME 49 9478
TEST_TIME 50 9619
TEST_TIME 51 9585
TEST_TIME 52 9588
TEST_TIME 53 9415
TEST_TIME 54 9273
TEST_TIME 55 9159
TEST_TIME 56 9187
TEST_TIME 57 9188
TEST_TIME 58 9139
TEST_TIME 59 9173
TEST_TIME 60 9112
TEST_TIME 61 9141
TEST_TIME 62 9066
TEST_TIME 63 9144
TEST_TIME 64 9033
TEST_TIME 65 9033
TEST_TIME 66 9004
TEST_TIME 67 9009
TEST_TIME 68 9025
TEST_TIME 69 9011
TEST_TIME 70 9064
TEST_TIME 71 9044
TEST_TIME 72 9063
TEST_TIME 73 9104
TEST_TIME 74 9055
TEST_TIME 75 9126
TEST_TIME 76 9247
TEST_TIME 77 9250
TEST_TIME 78 9298
TEST_TIME 79 9234
TEST_TIME 80 9156
TEST_TIME 81 9074
TEST_TIME 82 9048
TEST_TIME 83 8974
TEST_TIME 84 8883
TEST_TIME 85 8808
TEST_TIME 86 8779
TEST_TIME 87 8765
TEST_TIME 88 8828
TEST_TIME 89 8855
TEST_TIME 90 8845
TEST_TIME 91 8850
TEST_TIME 92 8868
TEST_TIME 93 8868
TEST_TIME 94 8879
TEST_TIME 95 8954
TEST_TIME 96 8991
TEST_TIME 97 8960
TEST_TIME 98 8971
TEST_TIME 99 8982
TEST_TIME 100 8935
TEST_TIME 101 8863
TEST_TIME 102 8887
TEST_TIME 103 8835
TEST_TIME 104 8799
TEST_TIME 105 8767
TEST_TIME 106 8765
TEST_TIME 107 8750
TEST_TIME 108 8732
TEST_TIME 109 8704
TEST_TIME 110 8762
TEST_TIME 111 8764
TEST_TIME 112 8797
TEST_TIME 113 8797
TEST_TIME 114 8814
TEST_TIME 115 8863
TEST_TIME 116 8858
TEST_TIME 117 8790
TEST_TIME 118 8766
TEST_TIME 119 8728
TEST_TIME 120 8679

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-03-2021 04:29:00 to 10-03-2021 04:29:03.

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 1459308 1459308 0 0.00 0 20866 7 0 0
doc_list_biud 1459308 1459308 0 0.00 0 49459 33 0 0
sp_add_invoice_to_stock 5028 4870 158 3.14 35 193587 18118 0 158
sp_cancel_adding_invoice 5168 5168 0 0.00 4 67566 939 0 0
sp_cancel_client_order 1695 1695 0 0.00 5 36365 393 0 0
sp_cancel_customer_reserve 506641 506641 0 0.00 1 38847 76 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 6883 102 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 15350 121 0 0
sp_cancel_supplier_invoice 5720 5711 9 0.15 1 49461 1038 0 9
sp_cancel_supplier_order 419 419 0 0.00 10 1252868 115704 0 0
sp_cancel_write_off 9444 9444 0 0.00 1 14252 128 0 0
sp_client_order 19874 19874 0 0.00 4 32310 401 0 0
sp_customer_reserve 452166 452166 0 0.00 0 47909 175 0 0
sp_fill_shopping_cart 30454 30454 0 0.00 1 24557 48 0 0
sp_fill_shopping_cart_clo_res 452162 452162 0 0.00 0 27466 20 0 0
sp_get_clo_for_invoice 4864 4864 0 0.00 0 54920 484 0 0
sp_kill_cost_storno 15687 15687 0 0.00 0 15345 66 0 0
sp_kill_qstorno_ret_qs2qd 1028856 1028856 0 0.00 0 49459 41 0 0
sp_kill_qty_storno 543446 543446 0 0.00 0 49459 89 0 0
sp_lock_selected_doc 19926 19926 0 0.00 0 11368 7 0 0
sp_make_cost_storno 26880 26880 0 0.00 0 17126 64 0 0
sp_multiply_rows_for_pdistr 26880 26880 0 0.00 0 14518 37 0 0
sp_multiply_rows_for_qdistr 19874 19874 0 0.00 0 22811 204 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 21892 131 0 0
sp_pay_to_supplier 5616 5616 0 0.00 5 16810 150 0 0
sp_payment_common 12512 12512 0 0.00 1 16923 28 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 36355 364 0 0
sp_qd_handle_on_invoice_upd_sts 10032 10032 0 0.00 0 36088 427 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 36115 60 0 0
sp_reserve_write_off 9504 9504 0 0.00 7 36322 207 0 0
sp_supplier_invoice 5320 5320 0 0.00 7 79604 3821 0 0
sp_supplier_order 5264 5264 0 0.00 5 42437 1439 0 0
srv_aggregate_perf_data 107 107 0 0.00 2326 202668 14865 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1952 137222 45766 0 0
srv_find_qd_qs_mism 994586 994586 0 0.00 0 20863 5 0 0
srv_make_invnt_saldo 409 409 0 0.00 39 169186 4453 0 0
srv_make_money_saldo 654 654 0 0.00 1 14483 657 0 0
srv_recalc_idx_stat 4 4 0 0.00 182922 439327 323756 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 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 13740 160 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 4456 94 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 4324 15 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 2774 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 2344 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 3269 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1202 13 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 41028 1734 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 839 5 0 0
t$perf-norm:sp_client_order 19874 19874 0 0.00 0 6826 17 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 338 3 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 5872 12 0 0
t$perf-norm:sp_pay_to_supplier 5616 5616 0 0.00 0 8508 8 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 8455 9 0 0
t$perf-norm:sp_supplier_invoice 5320 5320 0 0.00 0 2670 13 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 8137 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 158 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 135 4 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 555 10 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 741 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10042 9726 316 3.14 0 8970 20 0 316
v_all_customers 36434 36434 0 0.00 0 168 1 0 0
v_all_suppliers 29104 29104 0 0.00 0 15 1 0 0
v_all_wares 39748 39748 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 291 6 0 0
v_cancel_client_order 3390 3390 0 0.00 0 3109 6 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 5026 17 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 1911 9 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5863 15 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 3600 22 0 18
v_cancel_supplier_order 838 838 0 0.00 0 3198 10 0 0
v_cancel_write_off 1924 1924 0 0.00 0 985 12 0 0
v_max_id_clo_ord 5263 5263 0 0.00 0 2344 2 0 0
v_max_id_clo_res 955 955 0 0.00 0 2408 14 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 4836 4 0 0
v_max_non_paid_invoice 1644 1644 0 0.00 0 290 2 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 5237 4 0 0
v_min_id_clo_ord 5263 5263 0 0.00 0 17258 18 0 0
v_min_id_clo_res 955 955 0 0.00 0 3682 16 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 7260 20 0 0
v_min_non_paid_invoice 5616 5616 0 0.00 0 11638 17 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 16918 24 0 0
v_our_firm 10042 10042 0 0.00 0 14 1 0 0
v_reserve_write_off 19008 19008 0 0.00 0 8432 7 0 0
x_lock_depdocs_on_canc_invoice 10475 10475 0 0.00 0 13034 114 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3074 287 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 2 42173 1345 0 0
x_make_qstorno_1200_2000 5320 5320 0 0.00 3 75469 3644 0 0
x_make_qstorno_2100_3300 452166 452166 0 0.00 0 46824 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2021 04:29:04 to 10-03-2021 04:29:08.

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:57:01 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 30869 8192000000 9737050656 10033074176 804281408 8.940 100 94 189 0 834700688 8090288 22328992
01:59:42 16380 8192000000 9762331120 10068144128 839283056 9.293 100 88 176 1 873904592 14072960 20548576
02:01:02 26880 8192000000 9816151808 10138509312 875457600 9.654 100 85 170 1 922414480 27040896 19915984
02:02:31 16067 8192000000 9875999440 10210295808 916510832 10.062 100 76 150 2 979576320 46595152 16470336
02:03:50 10605 8192000000 9926142896 10270687232 957841152 10.468 100 79 155 0 1037751568 62937056 16973360
02:05:03 5528 8192000000 9944831360 10296803328 990267504 10.784 100 74 148 0 1054200608 49154752 14778352
02:06:12 14007 8192000000 9990892672 10357891072 1023012624 11.101 100 83 166 0 1113765040 74192704 16559712
02:07:28 3316 8192000000 10013593680 10385244160 1046538032 11.327 100 74 148 0 1132644192 71249440 14856720
02:08:34 2575 8192000000 10047138064 10417152000 1061912656 11.475 100 89 177 1 1166033376 86268000 17852720
02:09:40 5638 8192000000 10051876960 10425139200 1071844432 11.570 100 80 162 3 1159605712 71904160 15857120
02:10:46 4413 8192000000 10019231904 10405376000 1075510624 11.605 100 56 112 1 1133081072 46198992 11371456
02:11:53 4995 8192000000 10004670256 10396082176 1079652992 11.644 100 40 77 2 1119624224 31897008 8074224
02:12:59 14259 8192000000 9982617136 10384281600 1087974624 11.723 100 39 75 1 1105340608 8507504 8858480
02:14:17 17418 8192000000 10003997216 10418847744 1090368752 11.746 100 46 91 0 1108388032 8047296 9971984
02:15:35 4668 8192000000 10011922672 10410139648 1094112928 11.782 100 43 86 2 1148190384 45036512 9040944
02:16:41 3242 8192000000 10015874256 10414120960 1096041648 11.800 100 43 85 3 1129715184 23891792 9781744
02:17:45 5311 8192000000 10004848912 10399494144 1083972416 11.685 100 54 107 2 1108663872 12435760 12255696
02:18:53 23767 8192000000 9981448480 10368376832 1031085856 11.179 95 50 99 1 1054575408 12009968 11479584
02:20:18 3771 8192000000 9950137376 10337435648 1017891872 11.052 97 46 94 1 1041186832 12961616 10333344
02:21:22 5449 8192000000 9930406320 10309390336 986904704 10.751 96 51 103 0 1007249024 9284336 11059984
02:22:30 12433 8192000000 9940296128 10325508096 1000106896 10.880 98 38 76 0 1029936288 21173648 8655744
02:23:45 25646 8192000000 9951155024 10348699648 1036634384 11.232 100 20 39 2 1069017536 28206848 4176304
02:25:11 7495 8192000000 9973756736 10379173888 1057614368 11.434 100 39 76 5 1080798864 13414736 9769760
02:26:19 4001 8192000000 9978002528 10369077248 1062085392 11.476 100 31 59 1 1091131936 21894832 7151712
02:27:27 38902 8192000000 10001748688 10400321536 1085615408 11.701 100 44 88 0 1111921904 17046928 9259568
02:29:10 78375 8192000000 10077387552 10495766528 1102138976 11.858 100 42 83 0 1174872704 63772448 8961280
02:31:37 4966 8192000000 10080011248 10487128064 1121466336 12.041 100 39 78 1 1191603728 61414064 8723328
02:32:45 17028 8192000000 10136177104 10543874048 1136570224 12.183 100 54 105 0 1244339952 96841344 10928384
02:34:07 10867 8192000000 10152778640 10564194304 1143234624 12.246 100 51 101 0 1259565328 105017776 11312928
02:35:26 12934 8192000000 10173077360 10592079872 1153824096 12.345 100 55 108 0 1278500384 112679648 11996640
02:36:42 11053 8192000000 10228905568 10642157568 1163715680 12.438 100 53 106 0 1328968560 153317216 11935664
02:38:05 4340 8192000000 10268908944 10678329344 1172201344 12.517 100 65 129 2 1373539328 186624848 14713136
02:39:14 6937 8192000000 10312007504 10730655744 1168476336 12.483 100 65 129 2 1422855344 239903696 14475312
02:40:30 4086 8192000000 10321941664 10727006208 1173667648 12.531 100 81 162 2 1421788512 230463152 17657712
02:41:39 4030 8192000000 10291876512 10702725120 1178012448 12.572 99 61 120 3 1391387456 199375424 13999584
02:42:47 9643 8192000000 10337014752 10774515712 1191562816 12.698 100 52 104 0 1435400448 231802144 12035488
02:44:03 2500 8192000000 10360712832 10769772544 1190039952 12.684 100 85 167 1 1456737840 247941184 18756704
02:45:09 1951 8192000000 10368024192 10776584192 1154925472 12.356 100 74 147 0 1464048448 291687200 17435776
02:46:20 4527 8192000000 10338551328 10749759488 1132199040 12.142 99 57 113 1 1437734032 291125600 14409392
02:47:30 4881 8192000000 10342602944 10770853888 1149670512 12.306 100 59 115 1 1441294224 277558176 14065536
02:48:42 9063 8192000000 10357451552 10772758528 1152716960 12.335 99 51 100 2 1439602144 275094368 11790816
02:50:01 7976 8192000000 10372615584 10812743680 1156563088 12.371 99 39 78 1 1462174784 295959872 9651824
02:51:13 6846 8192000000 10405688832 10850467840 1146079408 12.273 100 46 91 0 1496568704 339489648 10999648
02:52:29 13246 8192000000 10452314592 10902507520 1156668336 12.372 100 42 84 0 1544296496 376922512 10705648
02:53:49 12107 8192000000 10411256560 10875768832 1140484176 12.220 100 27 54 0 1509294320 360385552 8424592
02:55:07 15074 8192000000 10348251680 10805055488 1110681552 11.939 99 32 64 0 1451842576 332998048 8162976
02:56:33 33156 8192000000 10095199616 10616758272 1094888320 11.789 98 9 18 1 1213184208 116154256 2141632
02:58:17 38264 8192000000 10083737920 10502959104 1063257888 11.488 94 30 60 1 1117130560 46943584 6929088
02:59:57 77105 8192000000 10053477008 10538889216 1108246576 11.916 99 21 42 3 1195510544 82254256 5009712
03:02:29 60871 8192000000 10054856640 10476838912 1107452720 11.908 99 30 58 2 1137174192 23701264 6020208
03:04:31 102379 8192000000 10073857888 10524442624 1121206576 12.038 99 21 42 1 1187408384 62116464 4085344
03:07:27 44411 8192000000 10098691488 10561581056 1137469008 12.192 100 23 46 1 1216803056 74817568 4516480
03:09:24 107125 8192000000 10087681648 10531635200 1127844544 12.101 99 6 12 1 1165076000 35698640 1532816
03:12:39 59673 8192000000 10130066400 10593284096 1125692064 12.081 98 12 24 1 1203274064 75158880 2423120
03:14:40 124444 8192000000 10094429792 10549800960 1122240640 12.048 98 6 12 1 1195720848 72147072 1333136
03:17:50 72533 8192000000 10078918512 10566938624 1133237584 12.152 98 15 30 1 1181853552 45687872 2928096
03:21:08 99803 8192000000 10116917872 10562125824 1129810304 12.120 98 35 71 0 1202336528 64569728 7956496
03:23:58 50074 8192000000 10130509152 10559885312 1127504896 12.098 98 51 99 3 1199155600 61237296 10413408
03:25:51 34048 8192000000 10110890992 10552844288 1137698096 12.194 98 18 36 1 1196584400 54084704 4801600
03:27:35 65992 8192000000 10108439968 10555842560 1129104128 12.113 98 10 20 1 1182332352 51328688 1899536
03:29:41 63631 8192000000 10080034112 10547539968 1138720192 12.203 99 9 18 0 1164538256 24148080 1669984
03:33:09 56608 8192000000 10090641296 10557014016 1144400128 12.257 99 15 30 2 1192375200 45156880 2818192
03:35:06 65729 8192000000 10056863616 10537771008 1109006656 11.923 97 15 30 0 1163084736 50899664 3178416
03:37:13 26773 8192000000 10110457040 10538024960 1113986288 11.970 99 58 115 0 1169335584 43068784 12280512
03:38:41 46880 8192000000 10113147920 10559348736 1114009424 11.970 99 28 56 0 1189297488 69704784 5583280
03:40:47 88018 8192000000 10148919536 10610266112 1074694400 11.597 95 14 28 0 1171489360 93772544 3022416
03:43:49 73229 8192000000 10058492256 10518224896 1067727584 11.530 95 12 24 0 1114051776 44164960 2159232
03:46:02 47232 8192000000 10056288112 10481659904 1089841856 11.741 98 58 116 1 1138192592 36146240 12204496
03:49:04 92349 8192000000 10060628976 10507681792 1103073264 11.867 99 12 24 0 1149881760 43634096 3174400
03:51:42 78197 8192000000 10127827472 10601897984 1122823440 12.054 100 28 57 3 1227955040 98973616 6157984
03:54:00 79637 8192000000 10054523792 10553606144 1086220368 11.707 97 13 26 0 1167876224 78346016 3309840
03:56:50 57780 8192000000 10115384848 10567548928 1115676768 11.986 99 19 38 1 1174196256 54757232 3762256
03:58:52 18297 8192000000 10081511488 10507075584 1106057648 11.895 98 36 70 3 1146136064 32510608 7567808
04:00:12 45218 8192000000 10120311136 10587652096 1144214752 12.255 100 33 66 0 1226016032 74975552 6825728
04:01:57 66108 8192000000 10081390256 10579460096 1124642912 12.071 98 14 28 0 1193848416 66161648 3043856
04:04:11 58326 8192000000 10109249520 10572926976 1139549472 12.211 99 19 38 0 1172072080 28680208 3842400
04:06:14 137222 8192000000 10126849680 10620055552 1145563440 12.268 99 20 40 1 1238213152 87510320 5139392
04:10:03 52921 8192000000 10091474992 10551283712 1141608048 12.231 99 6 12 1 1183236976 40135184 1493744
04:11:56 35624 8192000000 10090761184 10542710784 1136534656 12.183 99 24 48 1 1188608960 47186080 4888224
04:13:35 20076 8192000000 10106948000 10552971264 1150122304 12.311 100 30 60 1 1188422448 32184416 6115728
04:14:59 44622 8192000000 10145153712 10583777280 1164279984 12.443 100 25 50 1 1223894496 54031856 5582656
04:17:13 91767 8192000000 10123648160 10629349376 1146845664 12.280 99 17 34 0 1209707712 59718432 3143616
04:19:45 49497 8192000000 10130556064 10564354048 1119116112 12.019 97 24 48 1 1177791152 53753600 4921440
04:21:35 82439 8192000000 10074094496 10495746048 1084199616 11.687 94 29 58 0 1112883424 22981952 5701856
04:23:58 81179 8192000000 10054932160 10507517952 1138090528 12.198 100 14 28 0 1174197136 33390224 2716384
04:26:47 26856 8192000000 9451477856 9814974464 568182400 6.485 51 2 3 0 593013904 24516480 315024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-03-2021 04:29:10 to 10-03-2021 04:29:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 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:01, from 10-03-2021 04:29:26 to 10-03-2021 04:29:27.

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          229861
MON$OLDEST_ACTIVE               229862
MON$OLDEST_SNAPSHOT             229862
MON$NEXT_TRANSACTION            229862
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                       2104064
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:318: 
:319: mscs-test-161.budzdorov.ru    Wed Mar 10 04:27:28 2021
:320:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:321: 
:322: 
:323: mscs-test-161.budzdorov.ru    Wed Mar 10 04:27:28 2021
:324:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:325: 
:326: 
:327: mscs-test-161.budzdorov.ru    Wed Mar 10 04:27:28 2021
:328:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:329: 
:330: 
:331: mscs-test-161.budzdorov.ru    Wed Mar 10 04:27:28 2021
:332:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:333: 
:334: 
:335: mscs-test-161.budzdorov.ru    Wed Mar 10 04:27:28 2021
:336:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:337: 
--- end of diff output ---

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

10.03.21 04:29:28. 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-03-10 04:29:31 No severe PSQL-related problems occured
10.03.21 04:29:32. End of report.