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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15950140 kB
MemAvailable 30749776 kB
Buffers 20 kB
Cached 15731136 kB
SwapCached 4712 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-02 04:37:08
DTS_END                         2021-10-02 04:37:08.3920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47782
TRN_ID                          233374
ATT_ID                          632
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: 8844.15 24747 1061298
customer order: creation 163.48 420 19618
customer order: refuse 13.82 312 1658
order to supplier: creation 43.79 1373 5255
order to supplier: removal 3.35 108659 402
invoice (draft): creation 43.97 3713 5276
invoice (draft): removal 50.78 886 6093
invoice accept: apply 39.91 20320 4789
invoice accept: cancel 45.73 824 5488
customer reserve: creation 3661.03 204 439324
customer reserve: removal 4499.83 64 539980
realization accept: apply 79.91 176 9589
realization accept: cancel 76.11 120 9133
payment from customer: creation 55.59 110 6671
payment from customer: removal 5.34 77 641
payment to supplier: creation 47.33 124 5680
payment to supplier: removal 3.29 99 395
service: total inventory turnovers 3.79 3431 455
service: total monetary turnovers 6.24 471 749
service: aggregate perf. data 0.81 15433 97
service: refresh index statistics 0.04 338131 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2021 04:39:44 to 02-10-2021 04:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4687
TEST_TIME 2 5342
TEST_TIME 3 4818
TEST_TIME 4 4730
TEST_TIME 5 5216
TEST_TIME 6 5307
TEST_TIME 7 5290
TEST_TIME 8 5529
TEST_TIME 9 5638
TEST_TIME 10 5704
TEST_TIME 11 5939
TEST_TIME 12 6106
TEST_TIME 13 6215
TEST_TIME 14 6786
TEST_TIME 15 7128
TEST_TIME 16 7252
TEST_TIME 17 8104
TEST_TIME 18 8246
TEST_TIME 19 8251
TEST_TIME 20 8407
TEST_TIME 21 8593
TEST_TIME 22 8890
TEST_TIME 23 9473
TEST_TIME 24 9750
TEST_TIME 25 10499
TEST_TIME 26 10824
TEST_TIME 27 10456
TEST_TIME 28 10198
TEST_TIME 29 10165
TEST_TIME 30 10265
TEST_TIME 31 10121
TEST_TIME 32 10043
TEST_TIME 33 9857
TEST_TIME 34 9659
TEST_TIME 35 9414
TEST_TIME 36 9318
TEST_TIME 37 9332
TEST_TIME 38 9283
TEST_TIME 39 9205
TEST_TIME 40 9166
TEST_TIME 41 9259
TEST_TIME 42 9364
TEST_TIME 43 9303
TEST_TIME 44 9478
TEST_TIME 45 9360
TEST_TIME 46 9301
TEST_TIME 47 9414
TEST_TIME 48 9387
TEST_TIME 49 9335
TEST_TIME 50 9344
TEST_TIME 51 9413
TEST_TIME 52 9582
TEST_TIME 53 9583
TEST_TIME 54 9409
TEST_TIME 55 9343
TEST_TIME 56 9467
TEST_TIME 57 9419
TEST_TIME 58 9337
TEST_TIME 59 9257
TEST_TIME 60 9169
TEST_TIME 61 9116
TEST_TIME 62 9150
TEST_TIME 63 9156
TEST_TIME 64 9103
TEST_TIME 65 9188
TEST_TIME 66 9098
TEST_TIME 67 9037
TEST_TIME 68 9245
TEST_TIME 69 9239
TEST_TIME 70 9190
TEST_TIME 71 9286
TEST_TIME 72 9217
TEST_TIME 73 9319
TEST_TIME 74 9297
TEST_TIME 75 9284
TEST_TIME 76 9258
TEST_TIME 77 9160
TEST_TIME 78 9105
TEST_TIME 79 9143
TEST_TIME 80 9115
TEST_TIME 81 9056
TEST_TIME 82 9028
TEST_TIME 83 8994
TEST_TIME 84 8939
TEST_TIME 85 8909
TEST_TIME 86 8939
TEST_TIME 87 8915
TEST_TIME 88 8945
TEST_TIME 89 8916
TEST_TIME 90 8882
TEST_TIME 91 8966
TEST_TIME 92 8929
TEST_TIME 93 8948
TEST_TIME 94 9028
TEST_TIME 95 9009
TEST_TIME 96 9004
TEST_TIME 97 8974
TEST_TIME 98 8944
TEST_TIME 99 8915
TEST_TIME 100 8873
TEST_TIME 101 8873
TEST_TIME 102 8835
TEST_TIME 103 8822
TEST_TIME 104 8849
TEST_TIME 105 8847
TEST_TIME 106 8813
TEST_TIME 107 8819
TEST_TIME 108 8957
TEST_TIME 109 8954
TEST_TIME 110 8916
TEST_TIME 111 8966
TEST_TIME 112 8975
TEST_TIME 113 8963
TEST_TIME 114 8974
TEST_TIME 115 8997
TEST_TIME 116 8976
TEST_TIME 117 8905
TEST_TIME 118 8914
TEST_TIME 119 8908
TEST_TIME 120 8846

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2021 04:39:48 to 02-10-2021 04:39:51.

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 1468621 1468621 0 0.00 0 14498 7 0 0
doc_list_biud 1468618 1468618 0 0.00 0 81910 30 0 0
sp_add_invoice_to_stock 4900 4789 111 2.26 23 245608 20321 0 111
sp_cancel_adding_invoice 5488 5488 0 0.00 5 51027 825 0 0
sp_cancel_client_order 1658 1658 0 0.00 5 14222 313 0 0
sp_cancel_customer_reserve 539980 539980 0 0.00 1 81914 64 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 3760 78 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 6952 99 0 0
sp_cancel_supplier_invoice 6109 6093 16 0.26 2 21849 887 0 16
sp_cancel_supplier_order 402 402 0 0.00 12 1082304 108659 0 0
sp_cancel_write_off 9133 9133 0 0.00 1 17225 120 0 0
sp_client_order 19618 19618 0 0.00 4 27002 421 0 0
sp_customer_reserve 439324 439324 0 0.00 0 111892 204 0 0
sp_fill_shopping_cart 30145 30145 0 0.00 1 21549 50 0 0
sp_fill_shopping_cart_clo_res 439313 439313 0 0.00 0 21528 17 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 23472 354 0 0
sp_kill_cost_storno 15657 15657 0 0.00 0 14294 59 0 0
sp_kill_qstorno_ret_qs2qd 1095588 1095588 0 0.00 0 77928 35 0 0
sp_kill_qty_storno 577119 577119 0 0.00 0 81910 75 0 0
sp_lock_selected_doc 19843 19843 0 0.00 0 7718 5 0 0
sp_make_cost_storno 26718 26718 0 0.00 0 14194 57 0 0
sp_multiply_rows_for_pdistr 26715 26715 0 0.00 0 9744 28 0 0
sp_multiply_rows_for_qdistr 19618 19618 0 0.00 0 20769 220 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 14171 110 0 0
sp_pay_to_supplier 5680 5680 0 0.00 5 10940 124 0 0
sp_payment_common 12350 12350 0 0.00 1 5831 17 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 14214 288 0 0
sp_qd_handle_on_invoice_upd_sts 10265 10265 0 0.00 0 20067 346 0 0
sp_qd_handle_on_reserve_upd_sts 9588 9588 0 0.00 0 13850 57 0 0
sp_reserve_write_off 9589 9589 0 0.00 7 14216 176 0 0
sp_supplier_invoice 5276 5276 0 0.00 7 98024 3713 0 0
sp_supplier_order 5255 5255 0 0.00 6 38786 1374 0 0
srv_aggregate_perf_data 97 97 0 0.00 2529 171206 15434 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1421 128554 46161 0 0
srv_find_qd_qs_mism 1016183 1016183 0 0.00 0 13697 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 56 94441 3432 0 0
srv_make_money_saldo 749 749 0 0.00 1 11338 471 0 0
srv_recalc_idx_stat 5 5 0 0.00 163175 612218 338132 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4789 4789 0 0.00 0 16826 165 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 3935 109 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 886 7 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 6336 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 1190 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 805 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 345 11 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 26871 1385 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 3234 8 0 0
t$perf-norm:sp_client_order 19618 19618 0 0.00 0 16321 15 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 2001 6 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 5960 10 0 0
t$perf-norm:sp_pay_to_supplier 5680 5680 0 0.00 0 15784 15 0 0
t$perf-norm:sp_reserve_write_off 9589 9589 0 0.00 0 18247 14 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 15877 21 0 0
t$perf-norm:sp_supplier_order 5255 5255 0 0.00 0 6348 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 844 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 51 1 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 479 5 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 7278 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 21 6 0 0
v_add_invoice_to_stock 9774 9552 222 2.27 0 7400 19 0 222
v_all_customers 35570 35570 0 0.00 0 18 1 0 0
v_all_suppliers 29370 29370 0 0.00 0 83 1 0 0
v_all_wares 39236 39236 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 10795 19 0 0
v_cancel_client_order 3316 3316 0 0.00 0 1393 3 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 464 3 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 8678 16 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1602 8 0 0
v_cancel_supplier_invoice 838 806 32 3.81 0 3273 17 0 32
v_cancel_supplier_order 804 804 0 0.00 0 1840 10 0 0
v_cancel_write_off 1872 1872 0 0.00 0 2385 17 0 0
v_max_id_clo_ord 5253 5253 0 0.00 0 1150 1 0 0
v_max_id_clo_res 954 954 0 0.00 0 6659 25 0 0
v_max_id_ord_sup 5274 5274 0 0.00 0 8555 10 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 232 2 0 0
v_max_non_paid_realizn 3625 3625 0 0.00 0 449 2 0 0
v_min_id_clo_ord 5253 5253 0 0.00 0 4852 8 0 0
v_min_id_clo_res 954 954 0 0.00 0 1272 6 0 0
v_min_id_ord_sup 5274 5274 0 0.00 0 6757 19 0 0
v_min_non_paid_invoice 5680 5680 0 0.00 0 3702 10 0 0
v_min_non_paid_realizn 6670 6670 0 0.00 0 5827 10 0 0
v_our_firm 9756 9756 0 0.00 0 84 1 0 0
v_reserve_write_off 19176 19176 0 0.00 0 2120 5 0 0
x_lock_depdocs_on_canc_invoice 11178 11178 0 0.00 0 18380 108 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 10793 348 0 0
x_make_qstorno_1000_1200 5255 5255 0 0.00 1 38757 1294 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 3 97935 3515 0 0
x_make_qstorno_2100_3300 439324 439324 0 0.00 0 111890 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2021 04:39:52 to 02-10-2021 04:39:56.

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
02:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 12543 8192000000 9705834576 9999429632 781726400 8.711 100 87 174 0 810781392 7198688 21856304
02:09:37 21552 8192000000 9755852400 10058264576 826782336 9.167 100 88 176 0 859407696 11361840 21263520
02:11:02 23860 8192000000 9795628160 10107740160 855496320 9.455 100 87 174 0 899551120 23676112 20378688
02:12:29 10246 8192000000 9843559024 10174382080 902904624 9.927 100 77 154 1 954962000 35533056 16524320
02:13:41 8125 8192000000 9912108464 10254553088 951310816 10.404 100 80 160 1 1019916256 52177328 16428112
02:14:53 4870 8192000000 9959806560 10312851456 993131360 10.812 100 85 168 1 1076895120 66939040 16824720
02:16:01 3842 8192000000 10007009520 10368516096 1021400896 11.086 100 87 175 1 1114510752 75499888 17609968
02:17:07 1802 8192000000 10030236512 10398773248 1038437168 11.250 100 89 176 4 1139888864 83532656 17919040
02:18:10 1489 8192000000 10037248848 10407387136 1063126272 11.486 100 92 183 2 1145834400 64069952 18638176
02:19:12 8747 8192000000 10021207040 10411245568 1069854832 11.551 100 50 101 2 1148122080 68344768 9922480
02:20:22 5816 8192000000 10001348064 10393899008 1080299664 11.650 100 40 79 0 1111671744 23115248 8256832
02:21:28 4501 8192000000 9988873760 10383224832 1085092720 11.696 100 42 85 0 1108488640 13833280 9562640
02:22:34 68544 8192000000 9989519904 10407051264 1090609632 11.748 100 35 70 2 1117266128 18890960 7765536
02:24:50 6633 8192000000 10028764320 10445926400 1099085152 11.829 100 29 58 0 1154456624 49419792 5951680
02:25:58 13280 8192000000 10010704064 10409328640 1079305056 11.641 98 31 63 3 1100992192 14801088 6886048
02:27:17 12539 8192000000 9965068640 10361282560 1046339056 11.326 98 32 62 3 1064214768 11368880 6506832
02:28:33 20084 8192000000 9961106080 10366156800 1019789936 11.070 95 13 26 1 1049518448 27161232 2567280
02:29:53 7914 8192000000 9939856112 10329354240 1007981504 10.956 97 42 85 2 1033395120 16649488 8764128
02:31:02 8494 8192000000 9937363376 10318479360 999183456 10.871 98 39 79 1 1026952544 18358288 9410800
02:32:11 74923 8192000000 9931685936 10332626944 1016702880 11.040 98 31 62 1 1045826480 22426416 6697184
02:34:35 7343 8192000000 9972143648 10366898176 1059689232 11.454 100 45 90 0 1082899056 12261440 10948384
02:35:45 7272 8192000000 9997125952 10396368896 1069819856 11.550 100 51 105 3 1093545728 11985584 11740288
02:36:55 36029 8192000000 10001947552 10395451392 1077497680 11.624 100 33 66 1 1107820496 22660992 7661824
02:38:33 59660 8192000000 10040455600 10452008960 1108017920 11.914 100 44 87 0 1166915024 49494320 9402784
02:40:38 14977 8192000000 10062037312 10467753984 1124572880 12.070 100 50 93 1 1175826864 42186960 9067024
02:42:04 1419 8192000000 10138893120 10536218624 1139814304 12.214 100 87 173 4 1242815280 84441008 18559968
02:43:08 13722 8192000000 10141633376 10563309568 1145294480 12.265 100 59 117 2 1265781120 107270752 13215888
02:44:29 3827 8192000000 10181552464 10586734592 1156088208 12.367 100 57 114 2 1294520496 125514720 12917568
02:45:41 9504 8192000000 10210670304 10617016320 1154144832 12.348 99 52 103 1 1301494944 135553184 11796928
02:46:55 9331 8192000000 10205097424 10619162624 1167241184 12.471 100 51 101 0 1315447936 136864400 11342352
02:48:10 7772 8192000000 10271793104 10694807552 1171817936 12.514 100 63 126 1 1370038928 184416912 13804080
02:49:24 6160 8192000000 10341143376 10748428288 1189000144 12.674 100 76 152 1 1445222752 239972464 16250144
02:50:32 11281 8192000000 10351437664 10779574272 1166672704 12.466 99 62 124 1 1452071904 271245536 14153664
02:51:48 3174 8192000000 10364998576 10772484096 1173859280 12.533 100 82 163 0 1463055200 270442336 18753584
02:52:54 2110 8192000000 10386434480 10797613056 1157808320 12.383 99 83 164 0 1477155344 299662944 19684080
02:54:01 3023 8192000000 10359605440 10778832896 1119764832 12.025 98 69 138 0 1451468832 315180928 16523072
02:55:08 5775 8192000000 10278401376 10687254528 1119509632 12.022 99 43 86 2 1378446880 248262656 10674592
02:56:17 15525 8192000000 10309633392 10749890560 1145249248 12.265 100 38 76 0 1425309824 270621984 9438592
02:57:38 11932 8192000000 10325081280 10787139584 1134560208 12.164 99 35 70 0 1439394624 296023440 8810976
02:58:52 7571 8192000000 10316342880 10767450112 1124570928 12.070 99 35 70 0 1411534480 277721536 9242016
03:00:08 21825 8192000000 10277894848 10738753536 1133225680 12.152 99 27 54 1 1408809296 268709248 6874368
03:01:30 43490 8192000000 10239831744 10698375168 1118336592 12.011 99 14 28 0 1346549440 224518640 3694208
03:03:18 52897 8192000000 10057702992 10527985664 1084527616 11.691 97 5 10 0 1146029088 60368720 1132752
03:05:12 48444 8192000000 10092468672 10541191168 1111654112 11.948 100 46 90 0 1185978608 65749312 8575184
03:07:06 100160 8192000000 10059793184 10514558976 1095629760 11.796 98 12 24 0 1161879408 63681648 2568000
03:09:47 51974 8192000000 10061191568 10496335872 1110352464 11.936 99 16 32 0 1158631856 45541840 2737552
03:11:46 89184 8192000000 10089440496 10531659776 1128388336 12.106 100 23 45 0 1189261600 55772688 5100576
03:14:55 8531 8192000000 10060900832 10520109056 1124048736 12.065 100 18 36 1 1167030336 39664384 3317216
03:16:05 22464 8192000000 10093276688 10532044800 1132098096 12.141 100 19 38 0 1188712864 52528560 4086208
03:17:43 31144 8192000000 10142102880 10591875072 1154736032 12.354 100 24 48 1 1248333904 88350400 5247472
03:19:15 82567 8192000000 10090535920 10547302400 1097670720 11.816 96 14 28 0 1155506400 54191600 3644080
03:21:38 46638 8192000000 10077932432 10507165696 1130778400 12.129 99 37 74 2 1154014368 14958944 8277024
03:23:25 97235 8192000000 10086220768 10576822272 1142945088 12.243 99 22 42 1 1220563968 73544384 4074496
03:26:03 21034 8192000000 10105920320 10529984512 1159984128 12.403 100 33 66 0 1208391616 42022080 6385408
03:27:25 44410 8192000000 10170819824 10634080256 1167050304 12.469 100 17 34 1 1291566848 120313472 4203072
03:29:25 101251 8192000000 10120847584 10654842880 1113831008 11.969 96 8 16 0 1169929024 54092768 2005248
03:32:35 50137 8192000000 10158300512 10598133760 1151638688 12.325 100 37 74 0 1256885408 96876272 8370448
03:34:51 63969 8192000000 10092794256 10567606272 1142265232 12.237 98 8 16 0 1188637456 44826592 1545632
03:36:56 81331 8192000000 10184704880 10641289216 1154994928 12.356 99 20 41 0 1261763488 102439616 4328944
03:39:17 37607 8192000000 10155200496 10647334912 1147483008 12.286 99 26 52 0 1269599856 115630768 6486080
03:40:55 44098 8192000000 10134021424 10585268224 1089608000 11.739 94 15 30 0 1179876768 86771840 3496928
03:42:52 128535 8192000000 10167152896 10618621952 1107238080 11.906 97 21 42 0 1234567408 122281584 5047744
03:46:02 76637 8192000000 10081155264 10507669504 1087567312 11.720 96 19 38 1 1134978160 43768272 3642576
03:48:32 67590 8192000000 10078975264 10522431488 1109399792 11.927 98 28 55 1 1165549888 50636608 5513488
03:50:46 62035 8192000000 10046481248 10479304704 1068415696 11.537 95 23 46 0 1114968736 42205216 4347824
03:52:50 45783 8192000000 10066756512 10486833152 1089773408 11.741 97 29 58 0 1130250000 34771952 5704640
03:54:38 82325 8192000000 10131675824 10571042816 1108572704 11.919 98 47 93 0 1205782608 87486896 9723008
03:57:01 46454 8192000000 10036862752 10492837888 1077575984 11.624 96 12 24 0 1104685088 24472880 2636224
03:58:56 36612 8192000000 10081306432 10526093312 1119277040 12.020 100 12 24 0 1173821456 51845552 2698864
04:00:33 65881 8192000000 10078289520 10532028416 1116597616 11.995 99 17 34 0 1167220480 46593424 4029440
04:02:40 51180 8192000000 10028721488 10498899968 1074729152 11.597 96 13 26 0 1137903536 60242976 2931408
04:04:35 60152 8192000000 10037417088 10498564096 1099014816 11.828 98 16 32 0 1139839664 36806256 4018592
04:06:36 36519 8192000000 10092178352 10524020736 1101869200 11.855 98 27 54 0 1166715152 59450752 5395200
04:08:17 39207 8192000000 10094458208 10573447168 1122486624 12.050 100 16 33 0 1200218640 74431088 3300928
04:10:01 67014 8192000000 10085672160 10572324864 1103212480 11.868 98 15 30 0 1214285904 107521024 3552400
04:12:08 22138 8192000000 10065105056 10495307776 1106944880 11.903 99 38 76 0 1136980288 22333616 7701792
04:13:34 25931 8192000000 10096845376 10528718848 1128890576 12.111 100 61 122 0 1182687520 42059728 11737216
04:15:08 88541 8192000000 10113654912 10574200832 1137998960 12.197 100 47 94 0 1237337888 89929296 9409632
04:17:37 65296 8192000000 10062657824 10536251392 1112820528 11.959 97 17 34 0 1190188816 73875600 3492688
04:19:43 81019 8192000000 10112211088 10599145472 1117706800 12.005 97 13 26 0 1171645056 51458368 2479888
04:22:06 81460 8192000000 10158869536 10607222784 1154117984 12.348 99 20 40 1 1219605168 60980192 4506992
04:24:28 82000 8192000000 10107110976 10664652800 1163627376 12.437 100 13 26 2 1281852272 115031616 3193280
04:26:58 82839 8192000000 10100362032 10579546112 1117441104 12.003 96 29 58 0 1172615920 49671264 5503552
04:29:30 74900 8192000000 10109323104 10570014720 1120221696 12.029 96 21 41 1 1161256080 36857488 4176896
04:31:45 24167 8192000000 10105267088 10543579136 1138198608 12.199 99 50 98 0 1181020848 31774576 11047664
04:33:54 112600 8192000000 10101706496 10539257856 1124204912 12.067 98 43 85 1 1204690720 71087280 9398528
04:36:49 30370 8192000000 9499784944 9871183872 624880976 7.087 55 1 2 0 645331920 20415536 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-10-2021 04:39:58 to 02-10-2021 04:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2021 04:40:14 to 02-10-2021 04:40:16.

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          233582
MON$OLDEST_ACTIVE               233583
MON$OLDEST_SNAPSHOT             233583
MON$NEXT_TRANSACTION            233583
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                       2040160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2621 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2621 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2621 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2621 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:735: 
:736: mscs-test-161.budzdorov.ru    Sat Oct  2 04:37:44 2021
:737:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:738: 
:739: 
:740: mscs-test-161.budzdorov.ru    Sat Oct  2 04:37:44 2021
:741:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:742: 
--- end of diff output ---

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

02.10.21 04:40:17. 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-10-02 04:40:20 No severe PSQL-related problems occured
02.10.21 04:40:21. End of report.