Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.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 16994384 kB
MemAvailable 31573364 kB
Buffers 20 kB
Cached 14641800 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-14 04:26:56
DTS_END                         2021-03-14 04:26:56.7390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36216
TRN_ID                          226906
ATT_ID                          636
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: 8735.26 21874 1048231
customer order: creation 163.93 423 19672
customer order: refuse 13.28 360 1594
order to supplier: creation 42.82 1473 5138
order to supplier: removal 3.14 112514 377
invoice (draft): creation 43.80 3706 5256
invoice (draft): removal 49.73 919 5968
invoice accept: apply 39.77 16668 4772
invoice accept: cancel 44.38 888 5326
customer reserve: creation 3670.49 161 440459
customer reserve: removal 4387.73 63 526527
realization accept: apply 79.53 172 9544
realization accept: cancel 74.68 119 8962
payment from customer: creation 56.24 97 6749
payment from customer: removal 5.36 103 643
payment to supplier: creation 46.67 134 5600
payment to supplier: removal 3.37 61 404
service: total inventory turnovers 3.51 3553 421
service: total monetary turnovers 5.88 637 706
service: aggregate perf. data 0.91 14847 109
service: refresh index statistics 0.03 280578 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2021 04:28:52 to 14-03-2021 04:28:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3516
TEST_TIME 2 3661
TEST_TIME 3 4576
TEST_TIME 4 5245
TEST_TIME 5 5585
TEST_TIME 6 5693
TEST_TIME 7 5837
TEST_TIME 8 5892
TEST_TIME 9 6301
TEST_TIME 10 6633
TEST_TIME 11 6771
TEST_TIME 12 6797
TEST_TIME 13 6938
TEST_TIME 14 7307
TEST_TIME 15 7434
TEST_TIME 16 7291
TEST_TIME 17 7949
TEST_TIME 18 8566
TEST_TIME 19 8796
TEST_TIME 20 9149
TEST_TIME 21 9236
TEST_TIME 22 9341
TEST_TIME 23 9984
TEST_TIME 24 9971
TEST_TIME 25 10147
TEST_TIME 26 11153
TEST_TIME 27 10804
TEST_TIME 28 10669
TEST_TIME 29 10740
TEST_TIME 30 10476
TEST_TIME 31 10440
TEST_TIME 32 10247
TEST_TIME 33 10240
TEST_TIME 34 10037
TEST_TIME 35 10085
TEST_TIME 36 9876
TEST_TIME 37 9705
TEST_TIME 38 9490
TEST_TIME 39 9500
TEST_TIME 40 9525
TEST_TIME 41 9391
TEST_TIME 42 9293
TEST_TIME 43 9412
TEST_TIME 44 9306
TEST_TIME 45 9228
TEST_TIME 46 9244
TEST_TIME 47 9242
TEST_TIME 48 9205
TEST_TIME 49 9172
TEST_TIME 50 9120
TEST_TIME 51 9238
TEST_TIME 52 9352
TEST_TIME 53 9283
TEST_TIME 54 9234
TEST_TIME 55 9307
TEST_TIME 56 9272
TEST_TIME 57 9308
TEST_TIME 58 9181
TEST_TIME 59 9290
TEST_TIME 60 9195
TEST_TIME 61 9150
TEST_TIME 62 9078
TEST_TIME 63 9024
TEST_TIME 64 9045
TEST_TIME 65 8995
TEST_TIME 66 8988
TEST_TIME 67 9072
TEST_TIME 68 8995
TEST_TIME 69 9022
TEST_TIME 70 9060
TEST_TIME 71 9169
TEST_TIME 72 9197
TEST_TIME 73 9178
TEST_TIME 74 9116
TEST_TIME 75 9210
TEST_TIME 76 9158
TEST_TIME 77 9231
TEST_TIME 78 9149
TEST_TIME 79 9043
TEST_TIME 80 8960
TEST_TIME 81 8923
TEST_TIME 82 8852
TEST_TIME 83 8794
TEST_TIME 84 8747
TEST_TIME 85 8767
TEST_TIME 86 8759
TEST_TIME 87 8734
TEST_TIME 88 8695
TEST_TIME 89 8731
TEST_TIME 90 8705
TEST_TIME 91 8817
TEST_TIME 92 8798
TEST_TIME 93 8818
TEST_TIME 94 8796
TEST_TIME 95 8905
TEST_TIME 96 8926
TEST_TIME 97 8964
TEST_TIME 98 8964
TEST_TIME 99 8877
TEST_TIME 100 8830
TEST_TIME 101 8826
TEST_TIME 102 8778
TEST_TIME 103 8743
TEST_TIME 104 8739
TEST_TIME 105 8760
TEST_TIME 106 8737
TEST_TIME 107 8737
TEST_TIME 108 8734
TEST_TIME 109 8744
TEST_TIME 110 8738
TEST_TIME 111 8729
TEST_TIME 112 8758
TEST_TIME 113 8757
TEST_TIME 114 8886
TEST_TIME 115 8906
TEST_TIME 116 8920
TEST_TIME 117 8859
TEST_TIME 118 8795
TEST_TIME 119 8780
TEST_TIME 120 8744

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-03-2021 04:28:55 to 14-03-2021 04:28:59.

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 1454829 1454829 0 0.00 0 17909 6 0 0
doc_list_biud 1454828 1454828 0 0.00 0 66116 29 0 0
sp_add_invoice_to_stock 4876 4772 104 2.13 27 132120 16668 0 104
sp_cancel_adding_invoice 5326 5326 0 0.00 5 73071 888 0 0
sp_cancel_client_order 1594 1594 0 0.00 4 10349 360 0 0
sp_cancel_customer_reserve 526527 526527 0 0.00 1 22051 64 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 8036 103 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 1610 61 0 0
sp_cancel_supplier_invoice 5978 5968 10 0.16 2 66121 920 0 10
sp_cancel_supplier_order 377 377 0 0.00 9 1233429 112514 0 0
sp_cancel_write_off 8962 8962 0 0.00 1 21956 119 0 0
sp_client_order 19672 19672 0 0.00 4 62368 423 0 0
sp_customer_reserve 440459 440459 0 0.00 0 50017 162 0 0
sp_fill_shopping_cart 30054 30054 0 0.00 1 20919 48 0 0
sp_fill_shopping_cart_clo_res 440452 440452 0 0.00 0 15358 17 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 44437 433 0 0
sp_kill_cost_storno 15335 15335 0 0.00 0 9723 55 0 0
sp_kill_qstorno_ret_qs2qd 1068361 1068361 0 0.00 0 66114 35 0 0
sp_kill_qty_storno 563054 563054 0 0.00 0 66115 75 0 0
sp_lock_selected_doc 19749 19749 0 0.00 0 10314 8 0 0
sp_make_cost_storno 26649 26649 0 0.00 0 17580 52 0 0
sp_multiply_rows_for_pdistr 26649 26649 0 0.00 0 8945 30 0 0
sp_multiply_rows_for_qdistr 19672 19672 0 0.00 0 62355 227 0 0
sp_pay_from_customer 6749 6749 0 0.00 4 14570 98 0 0
sp_pay_to_supplier 5600 5600 0 0.00 4 13442 135 0 0
sp_payment_common 12349 12349 0 0.00 1 6924 20 0 0
sp_qd_handle_on_cancel_clo 1594 1594 0 0.00 0 8515 310 0 0
sp_qd_handle_on_invoice_upd_sts 10082 10082 0 0.00 0 37762 373 0 0
sp_qd_handle_on_reserve_upd_sts 9544 9544 0 0.00 0 14556 57 0 0
sp_reserve_write_off 9544 9544 0 0.00 7 19903 172 0 0
sp_supplier_invoice 5256 5256 0 0.00 7 94560 3707 0 0
sp_supplier_order 5138 5138 0 0.00 5 51267 1473 0 0
srv_aggregate_perf_data 109 109 0 0.00 2790 183257 14848 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1289 214031 49308 0 0
srv_find_qd_qs_mism 1002253 1002253 0 0.00 0 17908 5 0 0
srv_make_invnt_saldo 421 421 0 0.00 64 103919 3553 0 0
srv_make_money_saldo 706 706 0 0.00 1 16002 638 0 0
srv_recalc_idx_stat 4 4 0 0.00 150934 337443 280579 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 4772 4772 0 0.00 0 11382 161 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 4336 93 0 0
t$perf-norm:sp_cancel_client_order 1594 1594 0 0.00 0 6790 20 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 2398 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 5811 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 445 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 930 17 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 27880 1471 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 770 5 0 0
t$perf-norm:sp_client_order 19672 19672 0 0.00 0 7973 18 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 1930 6 0 0
t$perf-norm:sp_pay_from_customer 6749 6749 0 0.00 0 12287 13 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 12359 11 0 0
t$perf-norm:sp_reserve_write_off 9544 9544 0 0.00 0 6507 9 0 0
t$perf-norm:sp_supplier_invoice 5256 5256 0 0.00 0 11018 16 0 0
t$perf-norm:sp_supplier_order 5138 5138 0 0.00 0 8015 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1169 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 4844 82 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 3439 14 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 680 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9720 9512 208 2.13 0 8296 15 0 208
v_all_customers 35750 35750 0 0.00 0 61 1 0 0
v_all_suppliers 28788 28788 0 0.00 0 67 1 0 0
v_all_wares 39344 39344 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 497 6 0 0
v_cancel_client_order 3188 3188 0 0.00 0 6850 6 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 6930 12 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1034 4 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1549 6 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 312 9 0 20
v_cancel_supplier_order 754 754 0 0.00 0 4220 9 0 0
v_cancel_write_off 2018 2018 0 0.00 0 3896 14 0 0
v_max_id_clo_ord 5138 5138 0 0.00 0 3076 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 8772 35 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 7288 8 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 378 2 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 477 2 0 0
v_min_id_clo_ord 5138 5138 0 0.00 0 11031 13 0 0
v_min_id_clo_res 942 942 0 0.00 0 8678 21 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 5278 10 0 0
v_min_non_paid_invoice 5600 5600 0 0.00 0 6920 16 0 0
v_min_non_paid_realizn 6749 6749 0 0.00 0 6352 12 0 0
v_our_firm 9764 9764 0 0.00 0 75 1 0 0
v_reserve_write_off 19088 19088 0 0.00 0 6928 4 0 0
x_lock_depdocs_on_canc_invoice 10883 10883 0 0.00 0 20307 114 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 2604 321 0 0
x_make_qstorno_1000_1200 5138 5138 0 0.00 2 41011 1386 0 0
x_make_qstorno_1200_2000 5256 5256 0 0.00 3 94542 3525 0 0
x_make_qstorno_2100_3300 440459 440459 0 0.00 0 50015 142 0 0

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

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:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 42441 8192000000 9731015520 10029232128 800747584 8.904 100 90 180 0 830779232 8133152 21898496
01:59:48 20009 8192000000 9771792640 10078879744 843690672 9.337 100 92 185 0 883380144 17851072 21838400
02:01:15 12202 8192000000 9819667616 10135367680 879200064 9.692 100 90 180 0 927612272 27702464 20709744
02:02:34 11547 8192000000 9869797632 10204467200 922781824 10.124 100 77 153 0 977199840 37669392 16748624
02:03:48 7104 8192000000 9933672352 10281701376 966630272 10.554 100 76 150 4 1048595824 65780880 16184672
02:04:57 5119 8192000000 9971511520 10325671936 999427776 10.873 100 74 148 1 1081631536 66805040 15398720
02:06:04 3172 8192000000 10004002160 10370330624 1035707952 11.223 100 76 152 0 1115525184 64053120 15764112
02:07:09 2652 8192000000 10043706112 10412261376 1055402016 11.412 100 91 182 0 1156124816 82174704 18548096
02:08:12 2115 8192000000 10050893840 10421841920 1067379136 11.527 100 92 184 2 1155015792 68835040 18801616
02:09:16 14765 8192000000 10013462944 10393714688 1071041360 11.562 100 64 129 3 1128737344 44458848 13237136
02:10:32 9266 8192000000 9988968400 10378661888 1078026256 11.629 100 31 62 1 1104786384 20258800 6501328
02:11:42 61317 8192000000 9998878272 10402074624 1086486960 11.709 100 32 64 1 1119841904 26365808 6989136
02:13:46 6545 8192000000 10016049728 10422894592 1095606240 11.796 100 36 73 0 1140413536 37230768 7576528
02:14:54 6889 8192000000 10010197600 10404016128 1096156496 11.801 100 21 42 1 1123424560 22870032 4398032
02:16:02 9380 8192000000 10022638144 10436042752 1096674384 11.806 100 20 40 0 1137402928 36572960 4155584
02:17:12 2627 8192000000 10016423664 10405924864 1087262432 11.717 99 67 133 1 1112380896 9915904 15202560
02:18:15 6043 8192000000 10014369776 10409967616 1059730976 11.454 98 41 82 4 1103615120 35416800 8467344
02:19:22 6207 8192000000 9954855552 10341179392 1021207984 11.084 96 34 68 2 1044297536 15893424 7196128
02:20:30 10874 8192000000 9920711600 10298720256 993466528 10.815 98 33 66 4 1033934928 33174336 7294064
02:21:41 24286 8192000000 9924222512 10309238784 951736352 10.408 93 18 36 2 983595696 27380432 4478912
02:23:07 6171 8192000000 9944557952 10333020160 1017227504 11.045 99 47 92 1 1052162720 24317808 10617408
02:24:14 5285 8192000000 9969060000 10360340480 1051758224 11.378 100 63 122 4 1082890352 17062048 14070080
02:25:21 9798 8192000000 9971317840 10357100544 1061291728 11.469 100 44 92 4 1085600832 14537568 9771536
02:26:31 21236 8192000000 9973917328 10368626688 1066509728 11.519 100 30 60 3 1093114144 20525264 6079152
02:28:11 6844 8192000000 10036901632 10432143360 1093971200 11.780 100 63 126 1 1143328896 35887824 13469872
02:29:32 18825 8192000000 10094783072 10494844928 1114621840 11.976 100 56 112 1 1200597232 73913920 12061472
02:31:03 11501 8192000000 10111491504 10520580096 1126231696 12.086 100 60 118 0 1227218608 87985168 13001744
02:32:19 13319 8192000000 10133266896 10543874048 1137579968 12.193 100 37 74 0 1240623280 95245408 7797904
02:33:38 13245 8192000000 10165684256 10577641472 1145830784 12.270 100 45 90 0 1280472752 124402144 10239824
02:34:55 8583 8192000000 10216598384 10646990848 1156115680 12.367 100 50 100 0 1329051072 161104704 11830688
02:36:10 8996 8192000000 10252222784 10676965376 1170789264 12.504 100 53 106 1 1349791920 167187968 11814688
02:37:30 4017 8192000000 10256018496 10675060736 1182202720 12.611 100 59 118 0 1364887984 169573632 13111632
02:38:39 1386 8192000000 10304234224 10705817600 1176516112 12.558 99 87 172 2 1401767904 205576784 19675008
02:39:44 4948 8192000000 10363336176 10774278144 1179596960 12.586 99 74 148 0 1465055456 269040272 16418224
02:40:54 1289 8192000000 10402752288 10817654784 1189196320 12.676 100 80 159 0 1507645024 300762672 17686032
02:41:58 3325 8192000000 10378586496 10789576704 1154749008 12.354 99 73 145 1 1471407504 300055968 16602528
02:43:07 3522 8192000000 10398801888 10804506624 1155300768 12.359 99 84 162 4 1495417856 320983408 19133680
02:44:13 2784 8192000000 10421181520 10826194944 1148152576 12.292 100 79 159 0 1509616592 342766400 18697616
02:45:22 5588 8192000000 10356179088 10782580736 1135173216 12.170 100 68 137 0 1455054960 303171104 16710640
02:46:35 14045 8192000000 10273164768 10694770688 1119376368 12.021 99 33 64 0 1371549024 244322096 7850560
02:47:53 7011 8192000000 10298268144 10719571968 1140905520 12.224 100 49 92 2 1396941952 244686000 11350432
02:49:02 14953 8192000000 10297925264 10780106752 1141023312 12.225 99 27 54 1 1436353024 287859440 7470272
02:50:19 32782 8192000000 10293394144 10759004160 1143765904 12.251 100 22 44 1 1381986208 232647728 5572576
02:51:54 21971 8192000000 10192218576 10694598656 1133052432 12.150 100 23 46 1 1363315280 224572768 5690080
02:53:24 65817 8192000000 10100423760 10541436928 1103192832 11.868 98 33 66 0 1195466192 84654128 7619232
02:55:35 35267 8192000000 10058908672 10491285504 1108457968 11.918 99 16 30 1 1158517680 47231184 2828528
02:57:12 66329 8192000000 10058550016 10504314880 1088272656 11.726 98 13 27 0 1158624496 67706256 2645584
02:59:22 159887 8192000000 10043664752 10495614976 1070472160 11.557 96 21 42 1 1131488384 56451584 4564640
03:04:52 36460 8192000000 10096865376 10553937920 1118867664 12.016 99 39 78 1 1208938496 81642560 8428272
03:06:30 45550 8192000000 10076404032 10541019136 1131476672 12.135 100 11 22 0 1187084320 53046288 2561360
03:08:17 70963 8192000000 10108440048 10564034560 1113862960 11.969 98 15 29 1 1207121280 90006272 3252048
03:10:36 42942 8192000000 10053678048 10509361152 1119569088 12.023 99 21 42 0 1173095024 48399328 5126608
03:12:20 76329 8192000000 10051837456 10513928192 1136050128 12.178 100 18 36 2 1167881600 27980640 3850832
03:14:36 18276 8192000000 10064436096 10489962496 1132376208 12.144 99 50 97 4 1155842576 12360208 11106160
03:15:56 92287 8192000000 10104260592 10575642624 1128723536 12.109 98 21 42 2 1183515616 49713792 5078288
03:18:29 75486 8192000000 10069027808 10505756672 1133787856 12.157 99 16 32 0 1174385440 37549616 3047968
03:20:55 78672 8192000000 10094563808 10512461824 1099292960 11.831 95 17 34 0 1145325088 42778480 3253648
03:23:15 62804 8192000000 10124415232 10570625024 1151450432 12.323 100 20 40 0 1211672832 55512928 4709472
03:25:24 83638 8192000000 10098231472 10628116480 1134478064 12.164 98 19 38 2 1203567200 64810208 4278928
03:27:48 65322 8192000000 10076077744 10565152768 1125154864 12.076 98 13 26 0 1164932576 37123856 2653856
03:29:53 87028 8192000000 10072500624 10544496640 1051491056 11.375 92 11 22 0 1129305440 75557888 2256496
03:32:28 75534 8192000000 10148921104 10618048512 1124539472 12.070 99 27 54 1 1238225776 106878544 6807760
03:35:00 52938 8192000000 10185927296 10627788800 1123191664 12.057 98 21 42 2 1243067120 115082496 4792960
03:36:53 56917 8192000000 10077234928 10554994688 1106178640 11.896 97 13 25 1 1179343744 70323488 2841616
03:38:50 61089 8192000000 10074804912 10534629376 1098067168 11.819 97 15 29 1 1138748624 37431408 3250048
03:40:51 62245 8192000000 10055588432 10563837952 1106800496 11.902 98 35 70 0 1191171456 76837312 7533648
03:43:02 214030 8192000000 10001004768 10431217664 1015555600 11.029 90 13 26 0 1031969584 13718448 2695536
03:47:57 69161 8192000000 10016426800 10454835200 1058112032 11.438 95 9 17 0 1079276608 19756736 1407840
03:50:08 63845 8192000000 10059907584 10527506432 1087293264 11.717 98 8 16 1 1143656096 54575008 1787824
03:52:15 55679 8192000000 10041041152 10492420096 1095545824 11.795 99 10 20 0 1127594272 30078208 1970240
03:54:11 29698 8192000000 10097312160 10529988608 1077359680 11.622 96 33 66 0 1146140768 61854832 6926256
03:55:41 51772 8192000000 10109279584 10534572032 1103467440 11.871 98 21 42 1 1166146288 58179936 4498912
03:57:36 79267 8192000000 10096264464 10571853824 1105622560 11.891 98 16 32 1 1164087232 55128528 3336144
04:00:06 51702 8192000000 10145262112 10574028800 1126473920 12.088 99 24 48 1 1203800560 71963840 5362800
04:02:20 64520 8192000000 10123187520 10603859968 1114190336 11.972 98 19 38 3 1162683792 44309232 4184224
04:04:25 93221 8192000000 10088186480 10539950080 1119235536 12.020 98 19 38 0 1172211232 49035808 3939888
04:07:33 56322 8192000000 10102654336 10594013184 1142460448 12.239 100 10 20 1 1209550912 64564288 2526176
04:09:30 61533 8192000000 10104887568 10554966016 1125697296 12.081 99 10 20 1 1168639792 41055504 1886992
04:11:33 86579 8192000000 10071188752 10522644480 1114607792 11.976 98 16 32 1 1182850992 64949648 3293552
04:14:37 62309 8192000000 10099219648 10559086592 1136188928 12.180 99 17 34 1 1187386880 47797776 3400176
04:17:27 64523 8192000000 10155089360 10634129408 1122534560 12.051 97 15 30 0 1210146544 84228992 3382992
04:20:26 59634 8192000000 10102150896 10624585728 1136930480 12.187 99 14 28 1 1251514336 111725696 2858160
04:22:36 92630 8192000000 10085143648 10549567488 1102618752 11.862 97 13 26 1 1170959680 66048336 2292592
04:25:25 31218 8192000000 10048772048 10525990912 1118129936 12.009 99 6 13 0 1147409808 28276528 1003344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2021 04:29:21 to 14-03-2021 04:29:23.

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          225687
MON$OLDEST_ACTIVE               227029
MON$OLDEST_SNAPSHOT             227029
MON$NEXT_TRANSACTION            227029
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                       2092544
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 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.2387 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:20 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:21 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:21 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:21 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:21 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Mar 14 04:27:21 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

14.03.21 04:29:24. 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-14 04:29:26 No severe PSQL-related problems occured
14.03.21 04:29:28. End of report.