Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2021 13:27
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 e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16517044 kB
MemAvailable 31197760 kB
Buffers 0 kB
Cached 15184448 kB
SwapCached 4780 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-02-13 13:26:54
DTS_END                         2021-02-13 13:26:54.7580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34902
TRN_ID                          231063
ATT_ID                          582
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: 8975.83 22859 1077100
customer order: creation 163.88 431 19666
customer order: refuse 13.82 311 1658
order to supplier: creation 42.48 1338 5098
order to supplier: removal 3.20 134284 384
invoice (draft): creation 43.63 3693 5236
invoice (draft): removal 52.31 1037 6277
invoice accept: apply 39.38 19148 4726
invoice accept: cancel 46.80 849 5616
customer reserve: creation 3714.11 183 445693
customer reserve: removal 4562.20 73 547464
realization accept: apply 79.91 197 9589
realization accept: cancel 91.41 120 10969
payment from customer: creation 56.17 113 6740
payment from customer: removal 5.48 95 658
payment to supplier: creation 47.35 138 5682
payment to supplier: removal 3.28 112 393
service: total inventory turnovers 3.78 3371 454
service: total monetary turnovers 5.76 660 691
service: aggregate perf. data 0.85 12404 102
service: refresh index statistics 0.03 278616 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2021 13:28:21 to 13-02-2021 13:28:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2031
TEST_TIME 2 3714
TEST_TIME 3 3880
TEST_TIME 4 3910
TEST_TIME 5 4268
TEST_TIME 6 4609
TEST_TIME 7 4888
TEST_TIME 8 5318
TEST_TIME 9 5406
TEST_TIME 10 5482
TEST_TIME 11 5499
TEST_TIME 12 5801
TEST_TIME 13 5869
TEST_TIME 14 6208
TEST_TIME 15 6373
TEST_TIME 16 6376
TEST_TIME 17 6388
TEST_TIME 18 6860
TEST_TIME 19 7002
TEST_TIME 20 7254
TEST_TIME 21 7920
TEST_TIME 22 7744
TEST_TIME 23 7970
TEST_TIME 24 8421
TEST_TIME 25 8898
TEST_TIME 26 8993
TEST_TIME 27 9866
TEST_TIME 28 11176
TEST_TIME 29 11027
TEST_TIME 30 10931
TEST_TIME 31 10974
TEST_TIME 32 10901
TEST_TIME 33 10777
TEST_TIME 34 10632
TEST_TIME 35 10345
TEST_TIME 36 10067
TEST_TIME 37 10152
TEST_TIME 38 10018
TEST_TIME 39 9892
TEST_TIME 40 10041
TEST_TIME 41 10081
TEST_TIME 42 9934
TEST_TIME 43 9894
TEST_TIME 44 9780
TEST_TIME 45 9891
TEST_TIME 46 9713
TEST_TIME 47 9796
TEST_TIME 48 9663
TEST_TIME 49 9746
TEST_TIME 50 9753
TEST_TIME 51 9738
TEST_TIME 52 9696
TEST_TIME 53 9736
TEST_TIME 54 9760
TEST_TIME 55 9732
TEST_TIME 56 9865
TEST_TIME 57 9837
TEST_TIME 58 9809
TEST_TIME 59 9858
TEST_TIME 60 9849
TEST_TIME 61 9712
TEST_TIME 62 9610
TEST_TIME 63 9541
TEST_TIME 64 9526
TEST_TIME 65 9449
TEST_TIME 66 9453
TEST_TIME 67 9413
TEST_TIME 68 9349
TEST_TIME 69 9420
TEST_TIME 70 9462
TEST_TIME 71 9426
TEST_TIME 72 9413
TEST_TIME 73 9444
TEST_TIME 74 9426
TEST_TIME 75 9510
TEST_TIME 76 9543
TEST_TIME 77 9584
TEST_TIME 78 9488
TEST_TIME 79 9425
TEST_TIME 80 9415
TEST_TIME 81 9337
TEST_TIME 82 9283
TEST_TIME 83 9277
TEST_TIME 84 9257
TEST_TIME 85 9220
TEST_TIME 86 9207
TEST_TIME 87 9207
TEST_TIME 88 9205
TEST_TIME 89 9296
TEST_TIME 90 9345
TEST_TIME 91 9425
TEST_TIME 92 9454
TEST_TIME 93 9422
TEST_TIME 94 9463
TEST_TIME 95 9436
TEST_TIME 96 9495
TEST_TIME 97 9401
TEST_TIME 98 9343
TEST_TIME 99 9303
TEST_TIME 100 9253
TEST_TIME 101 9218
TEST_TIME 102 9213
TEST_TIME 103 9175
TEST_TIME 104 9155
TEST_TIME 105 9131
TEST_TIME 106 9137
TEST_TIME 107 9115
TEST_TIME 108 9130
TEST_TIME 109 9108
TEST_TIME 110 9131
TEST_TIME 111 9194
TEST_TIME 112 9174
TEST_TIME 113 9189
TEST_TIME 114 9172
TEST_TIME 115 9099
TEST_TIME 116 9051
TEST_TIME 117 9055
TEST_TIME 118 9027
TEST_TIME 119 8997
TEST_TIME 120 8979

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-02-2021 13:28:24 to 13-02-2021 13:28:28.

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 1487120 1487120 0 0.00 0 26302 7 0 0
doc_list_biud 1487119 1487119 0 0.00 0 29625 34 0 0
sp_add_invoice_to_stock 4900 4726 174 3.55 38 188947 19148 0 174
sp_cancel_adding_invoice 5616 5616 0 0.00 6 60074 849 0 0
sp_cancel_client_order 1658 1658 0 0.00 4 14151 311 0 0
sp_cancel_customer_reserve 547464 547464 0 0.00 1 28710 74 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 14369 95 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 11539 112 0 0
sp_cancel_supplier_invoice 6299 6277 22 0.34 3 18807 1037 0 22
sp_cancel_supplier_order 384 384 0 0.00 15 1281781 134284 0 0
sp_cancel_write_off 10969 10969 0 0.00 1 12404 121 0 0
sp_client_order 19666 19666 0 0.00 4 76250 432 0 0
sp_customer_reserve 445693 445693 0 0.00 0 80985 184 0 0
sp_fill_shopping_cart 29990 29990 0 0.00 1 20390 45 0 0
sp_fill_shopping_cart_clo_res 445690 445690 0 0.00 0 22383 19 0 0
sp_get_clo_for_invoice 4721 4721 0 0.00 0 53874 461 0 0
sp_kill_cost_storno 17636 17636 0 0.00 0 14263 60 0 0
sp_kill_qstorno_ret_qs2qd 1112558 1112558 0 0.00 0 28698 40 0 0
sp_kill_qty_storno 586678 586678 0 0.00 0 29625 86 0 0
sp_lock_selected_doc 19718 19718 0 0.00 0 9922 5 0 0
sp_make_cost_storno 26732 26732 0 0.00 0 25836 64 0 0
sp_multiply_rows_for_pdistr 26732 26732 0 0.00 0 26259 32 0 0
sp_multiply_rows_for_qdistr 19666 19666 0 0.00 0 76237 254 0 0
sp_pay_from_customer 6740 6740 0 0.00 4 19445 114 0 0
sp_pay_to_supplier 5682 5682 0 0.00 5 22400 139 0 0
sp_payment_common 12422 12422 0 0.00 1 20021 21 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 14145 288 0 0
sp_qd_handle_on_invoice_upd_sts 10337 10337 0 0.00 0 29624 415 0 0
sp_qd_handle_on_reserve_upd_sts 9589 9589 0 0.00 0 27487 74 0 0
sp_reserve_write_off 9589 9589 0 0.00 7 27510 197 0 0
sp_supplier_invoice 5236 5236 0 0.00 7 81884 3693 0 0
sp_supplier_order 5098 5098 0 0.00 5 37985 1339 0 0
srv_aggregate_perf_data 102 102 0 0.00 2792 123388 12405 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1679 130009 49377 0 0
srv_find_qd_qs_mism 1030168 1030168 0 0.00 0 15793 6 0 0
srv_make_invnt_saldo 454 454 0 0.00 66 107876 3372 0 0
srv_make_money_saldo 691 691 0 0.00 1 17198 661 0 0
srv_recalc_idx_stat 4 4 0 0.00 130553 427472 278617 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 22 22 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4726 4726 0 0.00 0 10759 194 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 2101 73 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 8816 19 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 3880 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 2491 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 2218 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 320 9 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 21266 1558 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 334 4 0 0
t$perf-norm:sp_client_order 19666 19666 0 0.00 0 8542 18 0 0
t$perf-norm:sp_customer_reserve 913 913 0 0.00 0 179 2 0 0
t$perf-norm:sp_pay_from_customer 6740 6740 0 0.00 0 4357 9 0 0
t$perf-norm:sp_pay_to_supplier 5682 5682 0 0.00 0 11622 11 0 0
t$perf-norm:sp_reserve_write_off 9589 9589 0 0.00 0 9241 11 0 0
t$perf-norm:sp_supplier_invoice 5237 5237 0 0.00 0 4860 18 0 0
t$perf-norm:sp_supplier_order 5098 5098 0 0.00 0 5351 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 128 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 212 5 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 1950 12 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 707 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9790 9442 348 3.55 0 6048 16 0 348
v_all_customers 36052 36052 0 0.00 0 58 1 0 0
v_all_suppliers 29050 29050 0 0.00 0 127 1 0 0
v_all_wares 39332 39332 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 914 6 0 0
v_cancel_client_order 3316 3316 0 0.00 0 1810 3 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 5962 10 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 1673 5 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1661 8 0 0
v_cancel_supplier_invoice 826 782 44 5.32 0 1685 17 0 44
v_cancel_supplier_order 768 768 0 0.00 0 3695 14 0 0
v_cancel_write_off 1870 1870 0 0.00 0 3338 20 0 0
v_max_id_clo_ord 5098 5098 0 0.00 0 2476 2 0 0
v_max_id_clo_res 913 913 0 0.00 0 1601 12 0 0
v_max_id_ord_sup 5225 5225 0 0.00 0 5744 5 0 0
v_max_non_paid_invoice 1480 1480 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 3519 3519 0 0.00 0 10525 6 0 0
v_min_id_clo_ord 5098 5098 0 0.00 0 3236 5 0 0
v_min_id_clo_res 913 913 0 0.00 0 8174 23 0 0
v_min_id_ord_sup 5225 5225 0 0.00 0 3058 6 0 0
v_min_non_paid_invoice 5682 5682 0 0.00 0 20018 16 0 0
v_min_non_paid_realizn 6740 6740 0 0.00 0 10493 12 0 0
v_our_firm 9722 9722 0 0.00 0 21 1 0 0
v_reserve_write_off 19178 19178 0 0.00 0 5441 5 0 0
x_lock_depdocs_on_canc_invoice 11502 11502 0 0.00 0 23277 114 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 9928 355 0 0
x_make_qstorno_1000_1200 5098 5098 0 0.00 2 37973 1261 0 0
x_make_qstorno_1200_2000 5236 5236 0 0.00 4 75861 3521 0 0
x_make_qstorno_2100_3300 445693 445693 0 0.00 0 80984 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2021 13:28:29 to 13-02-2021 13:28:33.

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
10:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:59 30234 8192000000 9708430704 9998077952 779866384 8.692 100 93 186 0 810913632 7686864 23360384
10:59:37 15828 8192000000 9743780240 10046717952 820084688 9.099 100 84 167 0 852669456 12026688 20558080
11:01:02 13500 8192000000 9794941824 10112999424 864070704 9.541 100 80 160 2 902152656 19590336 18491616
11:02:19 11992 8192000000 9857307984 10191343616 918249120 10.079 100 81 160 3 971672432 35801584 17621728
11:03:35 11861 8192000000 9922858304 10265255936 960352240 10.492 100 81 160 1 1039454256 62021712 17080304
11:04:49 5913 8192000000 9951423856 10305290240 996941872 10.849 100 84 167 0 1072653568 58509648 17202048
11:05:57 3505 8192000000 10001056400 10365792256 1021115216 11.083 100 83 161 2 1115757376 77768352 16873808
11:07:02 2393 8192000000 10021231760 10387542016 1049223392 11.353 100 86 173 1 1130640656 62985008 18432256
11:08:07 1329 8192000000 10022810496 10394112000 1064799696 11.502 100 92 184 3 1137751968 53952944 18999328
11:09:09 6599 8192000000 10001210144 10392674304 1068870288 11.541 100 48 95 1 1124870976 45404464 10596224
11:10:16 7280 8192000000 10004811104 10407526400 1079624864 11.644 100 36 72 0 1117864784 30350272 7889648
11:11:32 4520 8192000000 9997580544 10384072704 1085236496 11.697 100 49 100 3 1108341744 11842384 11262864
11:12:37 59092 8192000000 9996309776 10409365504 1090922352 11.751 100 31 62 1 1114425104 16937424 6565328
11:14:37 9221 8192000000 10012188976 10423029760 1085425776 11.699 99 38 76 1 1127166528 33756304 7984448
11:15:47 5757 8192000000 10006433760 10416349184 1084389472 11.689 99 42 86 1 1125467568 31533232 9544864
11:16:53 6663 8192000000 10005413920 10409852928 1089103120 11.734 100 25 50 1 1112070736 17992032 4975584
11:18:00 50578 8192000000 9973252576 10362376192 1029091792 11.160 96 36 73 1 1056637520 20323168 7222560
11:19:51 7169 8192000000 9948117632 10338885632 1000876816 10.887 96 34 67 1 1030604320 21910432 7817072
11:20:59 10903 8192000000 9943394224 10335641600 998958704 10.868 97 33 66 0 1028750352 21885040 7906608
11:22:12 15090 8192000000 9942860944 10338951168 1022838512 11.099 98 30 59 3 1049573920 19014016 7721392
11:23:27 30593 8192000000 9949999248 10353451008 1043356144 11.297 100 17 33 2 1071404912 24651072 3397696
11:24:59 6261 8192000000 9962185440 10363117568 1045981920 11.322 99 46 88 2 1065137936 9327760 9828256
11:26:08 5802 8192000000 9985939120 10377904128 1075391888 11.604 100 35 67 1 1097277120 14745536 7139696
11:27:21 100440 8192000000 10017378672 10424946688 1099314432 11.831 100 32 64 0 1129869712 24015824 6539456
11:30:07 14944 8192000000 10087600976 10490060800 1111279152 11.945 100 48 96 2 1198450320 76562096 10609072
11:31:35 5256 8192000000 10109002288 10515349504 1124156784 12.066 100 52 101 2 1222056592 87429984 10469824
11:32:52 7101 8192000000 10147861376 10560991232 1139990656 12.215 100 48 97 2 1267840752 117189760 10660336
11:34:06 12740 8192000000 10168626384 10582835200 1155611856 12.362 100 56 108 3 1278501056 109928096 12961104
11:35:21 6699 8192000000 10209507392 10616483840 1164321376 12.444 100 64 124 1 1309127664 132165040 12641248
11:36:31 4374 8192000000 10249765136 10660528128 1179983184 12.590 100 59 117 2 1347822288 155002960 12836144
11:37:38 4729 8192000000 10272063840 10683650048 1174683312 12.541 99 62 123 0 1371696272 182244928 14768032
11:38:48 5576 8192000000 10339487840 10749128704 1182070544 12.610 100 85 169 1 1440783296 239346960 19365792
11:39:58 3357 8192000000 10350026976 10757902336 1172422752 12.519 100 75 150 0 1447496720 257600816 17473152
11:41:04 3547 8192000000 10376399744 10803032064 1164301360 12.444 100 52 102 0 1489272448 312878112 12092976
11:42:11 3605 8192000000 10368660144 10769813504 1133385856 12.153 99 74 148 1 1454706416 304294416 17026144
11:43:20 1678 8192000000 10434962640 10839830528 1163487360 12.436 100 89 177 1 1534921856 350791264 20643232
11:44:27 5006 8192000000 10493604912 10897780736 1149127840 12.301 100 79 158 0 1590339664 422843424 18368400
11:45:38 3357 8192000000 10464153440 10872791040 1151472720 12.323 100 66 131 0 1563962784 396433488 16056576
11:46:47 8257 8192000000 10499706976 10917777408 1151208432 12.321 100 52 105 1 1594116000 429168256 13739312
11:47:58 9633 8192000000 10461769936 10897543168 1167884640 12.477 100 39 78 1 1571724992 393801728 10038624
11:49:15 2163 8192000000 10504890016 10951446528 1156013984 12.366 100 66 132 1 1599888576 427329616 16544976
11:50:21 11994 8192000000 10523218304 10954838016 1169865168 12.496 100 52 105 0 1619048128 435384096 13798864
11:51:48 26034 8192000000 10458411488 10948759552 1149415824 12.304 100 38 76 1 1574363792 415350768 9597200
11:53:31 100973 8192000000 10054017040 10545758208 1073410128 11.585 95 13 26 1 1282116096 205166688 3539280
11:56:14 6496 8192000000 10079133744 10493702144 1128505168 12.107 100 65 129 3 1165031632 23337536 13188928
11:57:30 95511 8192000000 10108065488 10558820352 1098157424 11.820 96 15 30 2 1145414912 44171552 3085936
12:00:09 28492 8192000000 10130891552 10555068416 1143712144 12.250 100 46 86 2 1216703312 64816144 8175024
12:01:40 130008 8192000000 10109538656 10548203520 1108700640 11.920 97 29 58 1 1220511808 105712944 6098224
12:05:02 41564 8192000000 10165822112 10640031744 1140549184 12.221 100 16 32 1 1259157232 114689616 3918432
12:06:44 55003 8192000000 10081634048 10632331264 1119458944 12.022 99 9 18 0 1190698416 68980192 2259280
12:08:40 62879 8192000000 10131722864 10601877504 1136948528 12.187 99 26 52 0 1203421936 60787824 5685584
12:10:45 102244 8192000000 10147172432 10679959552 1151083744 12.320 100 23 46 0 1272661616 116614640 4963232
12:13:31 59902 8192000000 10114675696 10655756288 1145403872 12.266 100 5 10 0 1214207568 67941744 861952
12:15:43 79030 8192000000 10108064544 10557988864 1158745184 12.392 100 9 18 1 1242358768 81944928 1668656
12:18:02 38296 8192000000 10132355408 10572509184 1149132320 12.301 99 24 49 0 1204109776 49769616 5207840
12:19:48 38042 8192000000 10090827968 10568458240 1145938432 12.271 99 11 22 1 1218204176 70083840 2181904
12:21:27 63751 8192000000 10105162128 10616651776 1156899152 12.374 100 23 46 1 1243464832 81609312 4956368
12:23:31 19418 8192000000 10119049520 10540847104 1143938080 12.253 100 49 97 4 1202731888 49187920 9605888
12:24:56 33511 8192000000 10094944128 10549149696 1107972496 11.913 97 22 44 0 1160834432 48628656 4233280
12:26:32 115981 8192000000 10058525072 10510200832 1116190736 11.991 98 23 46 1 1164918304 44223376 4504192
12:29:37 61209 8192000000 10043831728 10526371840 1111199296 11.944 98 6 12 1 1150869472 38666912 1003264
12:31:38 59113 8192000000 10060061600 10527391744 1112662816 11.958 98 12 24 0 1167246928 52208896 2375216
12:33:39 84054 8192000000 10138146896 10583105536 1100632128 11.844 97 25 50 1 1173306112 67178064 5495920
12:36:33 34759 8192000000 10051346400 10507743232 1066131456 11.515 95 19 39 0 1112029600 42357296 3540848
12:38:14 52566 8192000000 10087584816 10514190336 1082227200 11.669 97 31 62 0 1152131360 63637712 6266448
12:40:13 24817 8192000000 10082395488 10507747328 1112574992 11.957 100 35 70 0 1168254096 48913872 6765232
12:41:43 115556 8192000000 10063888608 10510209024 1074691984 11.597 96 10 20 0 1123046320 46091472 2262864
12:44:59 113141 8192000000 10065397024 10533482496 1108136016 11.915 99 25 50 2 1153258976 39988528 5134432
12:47:57 71881 8192000000 10067232224 10525745152 1097781520 11.817 98 6 12 0 1133936480 34807888 1347072
12:50:36 84693 8192000000 10090819648 10536259584 1069872656 11.551 95 25 50 1 1122998576 47910720 5215200
12:53:14 83976 8192000000 10149271456 10611781632 1089239040 11.735 97 14 28 0 1201397840 109162944 2995856
12:55:44 53735 8192000000 10075612368 10502262784 1091309520 11.755 98 22 44 0 1198066416 101950128 4806768
12:57:40 58115 8192000000 10117382192 10614009856 1127618384 12.099 100 12 24 0 1192055072 62056448 2380240
12:59:38 22950 8192000000 10108701008 10541060096 1120047712 12.027 98 41 82 3 1173297984 45448576 7801696
13:01:02 68867 8192000000 10124917456 10555342848 1130738048 12.128 99 22 41 2 1180669776 45660496 4271232
13:03:12 107354 8192000000 10084347872 10537971712 1127464096 12.097 98 17 34 0 1166630352 35806144 3360112
13:06:06 84613 8192000000 10109145312 10594627584 1152619728 12.334 100 9 18 1 1223745232 69241376 1884128
13:08:30 89377 8192000000 10100975168 10545729536 1142982112 12.244 99 16 32 0 1177601344 31038144 3581088
13:11:10 62481 8192000000 10111574896 10582913024 1141087040 12.226 98 23 46 0 1211912384 65635968 5189376
13:13:13 70934 8192000000 10141682560 10615939072 1117273744 12.001 96 31 61 1 1211471296 87576336 6621216
13:15:28 72719 8192000000 10117240400 10601127936 1118956608 12.017 97 31 62 2 1211190208 85642048 6591552
13:17:45 66221 8192000000 10095711296 10525884416 1102339248 11.860 96 34 68 0 1141229664 32083056 6807360
13:20:02 101667 8192000000 10114113552 10578407424 1128249248 12.105 99 31 62 2 1199460400 65250064 5961088
13:24:12 36001 8192000000 10048696032 10536837120 1088789488 11.731 96 2 4 0 1120533728 30868416 875824
13:25:52 68897 8192000000 8912950672 9115394048 72736320 0.880 7 4 7 0 86863264 13271408 855536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-02-2021 13:28:35 to 13-02-2021 13:28:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 22 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-02-2021 13:28:50 to 13-02-2021 13:28:52.

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          231394
MON$OLDEST_ACTIVE               231395
MON$OLDEST_SNAPSHOT             231395
MON$NEXT_TRANSACTION            231395
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                       2100992
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.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:392: 
:393: mscs-test-161.budzdorov.ru    Sat Feb 13 13:27:18 2021
:394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:395: 
--- end of diff output ---

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

13.02.21 13:28:54. 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-02-13 13:28:56 No severe PSQL-related problems occured
13.02.21 13:28:57. End of report.