Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.09.2021 04:38
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 15858044 kB
MemAvailable 30879300 kB
Buffers 20 kB
Cached 15808896 kB
SwapCached 340 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-09-08 04:37:07
DTS_END                         2021-09-08 04:37:07.0620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41686
TRN_ID                          236565
ATT_ID                          696
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: 9116.93 27561 1094032
customer order: creation 166.54 392 19985
customer order: refuse 14.96 332 1795
order to supplier: creation 44.73 1443 5368
order to supplier: removal 3.48 125815 418
invoice (draft): creation 44.95 3592 5394
invoice (draft): removal 53.80 1014 6456
invoice accept: apply 40.28 18869 4833
invoice accept: cancel 48.49 897 5819
customer reserve: creation 3706.75 185 444810
customer reserve: removal 4697.42 73 563690
realization accept: apply 80.64 189 9677
realization accept: cancel 88.71 122 10645
payment from customer: creation 58.16 125 6979
payment from customer: removal 5.83 104 699
payment to supplier: creation 47.93 149 5751
payment to supplier: removal 3.38 105 405
service: total inventory turnovers 3.87 3494 464
service: total monetary turnovers 5.98 691 717
service: aggregate perf. data 1.03 13104 123
service: refresh index statistics 0.03 380521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-09-2021 04:38:48 to 08-09-2021 04:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1803
TEST_TIME 2 2199
TEST_TIME 3 3214
TEST_TIME 4 3644
TEST_TIME 5 3904
TEST_TIME 6 4188
TEST_TIME 7 4620
TEST_TIME 8 4861
TEST_TIME 9 5139
TEST_TIME 10 5302
TEST_TIME 11 5372
TEST_TIME 12 5516
TEST_TIME 13 6113
TEST_TIME 14 6212
TEST_TIME 15 6634
TEST_TIME 16 6759
TEST_TIME 17 6812
TEST_TIME 18 7101
TEST_TIME 19 7167
TEST_TIME 20 7227
TEST_TIME 21 7808
TEST_TIME 22 8678
TEST_TIME 23 8735
TEST_TIME 24 8660
TEST_TIME 25 9153
TEST_TIME 26 9763
TEST_TIME 27 10137
TEST_TIME 28 11388
TEST_TIME 29 11154
TEST_TIME 30 10974
TEST_TIME 31 10678
TEST_TIME 32 10583
TEST_TIME 33 10602
TEST_TIME 34 10417
TEST_TIME 35 10378
TEST_TIME 36 10222
TEST_TIME 37 10162
TEST_TIME 38 10204
TEST_TIME 39 10163
TEST_TIME 40 10169
TEST_TIME 41 10165
TEST_TIME 42 10010
TEST_TIME 43 9958
TEST_TIME 44 9836
TEST_TIME 45 9632
TEST_TIME 46 9636
TEST_TIME 47 9596
TEST_TIME 48 9491
TEST_TIME 49 9481
TEST_TIME 50 9429
TEST_TIME 51 9417
TEST_TIME 52 9519
TEST_TIME 53 9482
TEST_TIME 54 9568
TEST_TIME 55 9579
TEST_TIME 56 9705
TEST_TIME 57 9629
TEST_TIME 58 9833
TEST_TIME 59 9793
TEST_TIME 60 9671
TEST_TIME 61 9621
TEST_TIME 62 9524
TEST_TIME 63 9569
TEST_TIME 64 9476
TEST_TIME 65 9433
TEST_TIME 66 9428
TEST_TIME 67 9440
TEST_TIME 68 9390
TEST_TIME 69 9392
TEST_TIME 70 9513
TEST_TIME 71 9506
TEST_TIME 72 9540
TEST_TIME 73 9571
TEST_TIME 74 9596
TEST_TIME 75 9684
TEST_TIME 76 9660
TEST_TIME 77 9617
TEST_TIME 78 9525
TEST_TIME 79 9496
TEST_TIME 80 9429
TEST_TIME 81 9397
TEST_TIME 82 9407
TEST_TIME 83 9332
TEST_TIME 84 9313
TEST_TIME 85 9344
TEST_TIME 86 9319
TEST_TIME 87 9292
TEST_TIME 88 9278
TEST_TIME 89 9309
TEST_TIME 90 9329
TEST_TIME 91 9358
TEST_TIME 92 9455
TEST_TIME 93 9489
TEST_TIME 94 9536
TEST_TIME 95 9557
TEST_TIME 96 9463
TEST_TIME 97 9450
TEST_TIME 98 9370
TEST_TIME 99 9344
TEST_TIME 100 9308
TEST_TIME 101 9269
TEST_TIME 102 9248
TEST_TIME 103 9233
TEST_TIME 104 9264
TEST_TIME 105 9245
TEST_TIME 106 9251
TEST_TIME 107 9208
TEST_TIME 108 9296
TEST_TIME 109 9329
TEST_TIME 110 9300
TEST_TIME 111 9324
TEST_TIME 112 9357
TEST_TIME 113 9329
TEST_TIME 114 9266
TEST_TIME 115 9273
TEST_TIME 116 9230
TEST_TIME 117 9233
TEST_TIME 118 9232
TEST_TIME 119 9192
TEST_TIME 120 9118

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-09-2021 04:38:51 to 08-09-2021 04:38:55.

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 1506354 1506354 0 0.00 0 15891 7 0 0
doc_list_biud 1506354 1506354 0 0.00 0 92437 34 0 0
sp_add_invoice_to_stock 4909 4833 76 1.54 26 160822 18869 0 76
sp_cancel_adding_invoice 5819 5819 0 0.00 5 105234 898 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 7552 333 0 0
sp_cancel_customer_reserve 563690 563690 0 0.00 1 43906 73 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 7027 104 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4677 106 0 0
sp_cancel_supplier_invoice 6460 6456 4 0.06 2 92468 1014 0 4
sp_cancel_supplier_order 418 418 0 0.00 8 1276120 125815 0 0
sp_cancel_write_off 10645 10645 0 0.00 1 17313 122 0 0
sp_client_order 19985 19985 0 0.00 4 55665 393 0 0
sp_customer_reserve 444810 444810 0 0.00 0 83227 185 0 0
sp_fill_shopping_cart 30735 30735 0 0.00 1 10004 43 0 0
sp_fill_shopping_cart_clo_res 444808 444808 0 0.00 0 19854 18 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 31897 410 0 0
sp_kill_cost_storno 17568 17568 0 0.00 0 5065 58 0 0
sp_kill_qstorno_ret_qs2qd 1144899 1144899 0 0.00 0 92434 39 0 0
sp_kill_qty_storno 603329 603329 0 0.00 0 92435 85 0 0
sp_lock_selected_doc 20256 20256 0 0.00 0 6566 7 0 0
sp_make_cost_storno 27236 27236 0 0.00 0 14852 54 0 0
sp_multiply_rows_for_pdistr 27236 27236 0 0.00 0 10958 34 0 0
sp_multiply_rows_for_qdistr 19985 19985 0 0.00 0 13427 200 0 0
sp_pay_from_customer 6979 6979 0 0.00 4 10970 125 0 0
sp_pay_to_supplier 5751 5751 0 0.00 5 14858 150 0 0
sp_payment_common 12730 12730 0 0.00 1 7798 23 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 7543 293 0 0
sp_qd_handle_on_invoice_upd_sts 10648 10648 0 0.00 0 57251 400 0 0
sp_qd_handle_on_reserve_upd_sts 9677 9677 0 0.00 0 17953 50 0 0
sp_reserve_write_off 9677 9677 0 0.00 7 17967 190 0 0
sp_supplier_invoice 5394 5394 0 0.00 6 85035 3592 0 0
sp_supplier_order 5368 5368 0 0.00 6 86812 1443 0 0
srv_aggregate_perf_data 123 123 0 0.00 2690 103813 13105 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1126 122517 47867 0 0
srv_find_qd_qs_mism 1047930 1047930 0 0.00 0 15890 5 0 0
srv_make_invnt_saldo 464 464 0 0.00 70 97249 3494 0 0
srv_make_money_saldo 717 717 0 0.00 1 26003 691 0 0
srv_recalc_idx_stat 4 4 0 0.00 329771 438346 380521 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4833 4833 0 0.00 0 7630 173 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 7466 99 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 6418 16 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 3002 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 4337 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 937 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1443 25 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 16768 1559 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 933 6 0 0
t$perf-norm:sp_client_order 19985 19985 0 0.00 0 8734 20 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 2922 11 0 0
t$perf-norm:sp_pay_from_customer 6979 6979 0 0.00 0 5517 12 0 0
t$perf-norm:sp_pay_to_supplier 5751 5751 0 0.00 0 5141 11 0 0
t$perf-norm:sp_reserve_write_off 9677 9677 0 0.00 0 5280 11 0 0
t$perf-norm:sp_supplier_invoice 5394 5394 0 0.00 0 23261 29 0 0
t$perf-norm:sp_supplier_order 5368 5368 0 0.00 0 2287 15 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 4714 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1207 20 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 6978 26 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 20353 36 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9810 9658 152 1.54 0 6693 24 0 152
v_all_customers 36408 36408 0 0.00 0 109 1 0 0
v_all_suppliers 29850 29850 0 0.00 0 65 1 0 0
v_all_wares 39970 39970 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 960 9 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1762 6 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 7009 21 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 3521 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 4669 23 0 0
v_cancel_supplier_invoice 832 824 8 0.96 0 5737 47 0 8
v_cancel_supplier_order 836 836 0 0.00 0 5133 18 0 0
v_cancel_write_off 1990 1990 0 0.00 0 4144 21 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 1935 2 0 0
v_max_id_clo_res 954 954 0 0.00 0 2715 18 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 3452 4 0 0
v_max_non_paid_invoice 1576 1576 0 0.00 0 1278 3 0 0
v_max_non_paid_realizn 3658 3658 0 0.00 0 5824 6 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 898 4 0 0
v_min_id_clo_res 954 954 0 0.00 0 2677 8 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 5953 10 0 0
v_min_non_paid_invoice 5751 5751 0 0.00 0 6094 14 0 0
v_min_non_paid_realizn 6979 6979 0 0.00 0 7794 14 0 0
v_our_firm 10204 10204 0 0.00 0 109 1 0 0
v_reserve_write_off 19354 19354 0 0.00 0 8332 11 0 0
x_lock_depdocs_on_canc_invoice 11863 11863 0 0.00 0 13724 114 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8653 384 0 0
x_make_qstorno_1000_1200 5368 5368 0 0.00 2 86803 1358 0 0
x_make_qstorno_1200_2000 5394 5394 0 0.00 3 84984 3432 0 0
x_make_qstorno_2100_3300 444810 444810 0 0.00 0 83225 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-09-2021 04:38:56 to 08-09-2021 04:39:00.

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:10 10 8192000000 8801117088 8813268992 5768432 0.070 3 1 2 0 5869600 65392 35776
02:08:12 28605 8192000000 9729393136 10029322240 808776464 8.985 100 91 182 0 838978384 7923248 22278672
02:09:45 20086 8192000000 9764105360 10071252992 835833872 9.258 100 84 168 0 868626928 12587840 20205216
02:11:10 17097 8192000000 9808755632 10127695872 870338608 9.603 100 76 152 0 914601584 26982336 17280640
02:12:33 13566 8192000000 9861728784 10198609920 920992064 10.106 100 82 164 0 977601904 38736512 17873328
02:13:55 7474 8192000000 9920822208 10265059328 960887744 10.498 100 83 165 0 1032775760 54538240 17349776
02:15:05 4229 8192000000 9969690528 10321960960 991679424 10.798 100 80 159 1 1082162288 74496368 15986496
02:16:12 2210 8192000000 10009215392 10369953792 1025425440 11.124 100 90 181 1 1119690576 76155760 18109376
02:17:18 1611 8192000000 10018855280 10384105472 1045992912 11.322 100 88 177 0 1128231328 64470208 17768208
02:18:21 1537 8192000000 10038403712 10412011520 1064309168 11.498 100 89 178 0 1161763008 78535408 18918432
02:19:22 7987 8192000000 9996786976 10385297408 1066198096 11.516 100 38 73 0 1110803568 37136320 7469152
02:20:31 4708 8192000000 10001443776 10390519808 1077858192 11.627 100 47 94 0 1127354400 39927376 9568832
02:21:37 4545 8192000000 10000265104 10391719936 1080213264 11.650 100 34 67 1 1115489232 27899296 7376672
02:22:43 17031 8192000000 9986188320 10392182784 1084424800 11.690 100 33 66 0 1127505472 34881488 8199184
02:24:01 11964 8192000000 10010986816 10404016128 1086894352 11.713 100 48 96 0 1110438944 13010304 10534288
02:25:15 6324 8192000000 10012976800 10422099968 1095319808 11.793 100 46 91 1 1153006816 47867648 9819360
02:26:25 8559 8192000000 10029137552 10427125760 1093647360 11.777 100 42 84 2 1117150800 14492992 9010448
02:27:36 68695 8192000000 9990301952 10393075712 1014579648 11.020 94 35 68 3 1043896592 21157920 8159024
02:29:49 5377 8192000000 9935206384 10325458944 1018049328 11.053 97 43 86 1 1038262608 11530192 8683088
02:30:57 8134 8192000000 9944243056 10325979136 1009060000 10.966 99 33 64 1 1035304544 19353008 6891536
02:32:06 13288 8192000000 9954714448 10350850048 1040851344 11.273 100 34 70 1 1066258848 18409216 6998288
02:33:20 31204 8192000000 9954213648 10363981824 1011466048 10.990 96 20 40 0 1031855312 15903424 4485840
02:34:51 6684 8192000000 9988239920 10389114880 1068617760 11.539 100 39 78 0 1091289600 13901248 8770592
02:36:03 8022 8192000000 10003450176 10402775040 1077179744 11.621 100 25 48 1 1105469792 23170464 5119584
02:37:12 102931 8192000000 10020899824 10422755328 1090767616 11.750 100 29 59 0 1111652576 15095536 5789424
02:39:59 8768 8192000000 10077935424 10485768192 1115800448 11.987 100 39 78 0 1188291440 64320800 8170192
02:41:11 58577 8192000000 10132241392 10538471424 1121364000 12.040 99 44 88 0 1235609968 105136512 9109456
02:43:13 12877 8192000000 10162715968 10577674240 1142557472 12.240 100 44 86 0 1274975696 123244400 9173824
02:44:30 14015 8192000000 10231305952 10646867968 1159552032 12.399 100 66 132 0 1329517344 155565520 14399792
02:45:48 17175 8192000000 10225377072 10659508224 1166992144 12.469 100 44 88 1 1342930240 165769424 10168672
02:47:11 7868 8192000000 10282650160 10698645504 1179133680 12.582 100 65 129 1 1393761520 200878544 13749296
02:48:22 2038 8192000000 10338128528 10742157312 1172262688 12.518 99 83 165 2 1427793504 236621184 18909632
02:49:30 1475 8192000000 10366605472 10767568896 1165595248 12.456 100 83 165 1 1467087664 281974144 19518272
02:50:35 3721 8192000000 10366125712 10770771968 1157251840 12.378 100 79 159 2 1470238928 295090064 17897024
02:51:45 2669 8192000000 10401056336 10801594368 1159496560 12.399 100 87 174 1 1501220544 321260960 20463024
02:52:52 3696 8192000000 10418551872 10820952064 1145709760 12.269 98 83 166 1 1512561840 348255856 18596224
02:54:00 2995 8192000000 10430136880 10828812288 1136650944 12.184 99 83 166 2 1519378000 362201824 20525232
02:55:08 1119 8192000000 10498191296 10899091456 1144880160 12.261 100 82 161 0 1593627568 428879072 19868336
02:56:16 3715 8192000000 10502816448 10912608256 1150968064 12.319 100 64 128 0 1599343104 432137584 16237456
02:57:27 3703 8192000000 10537531216 10946433024 1149260704 12.303 100 91 180 2 1631615712 460355056 21999952
02:58:35 9648 8192000000 10593599888 11060355072 1154358800 12.350 100 50 99 0 1685075568 517163248 13553520
03:00:02 15320 8192000000 10452403136 10905378816 1155468432 12.361 100 45 84 1 1560968128 394033776 11465920
03:01:37 9909 8192000000 10501988320 10936713216 1157075184 12.376 100 40 78 0 1599915456 432473664 10366608
03:02:51 11398 8192000000 10469664256 10976845824 1153739200 12.345 100 29 58 1 1580240176 418575776 7925200
03:04:04 29443 8192000000 10388490528 10886447104 1125669504 12.081 99 29 57 2 1464823424 331821360 7332560
03:05:40 46174 8192000000 10052103728 10483552256 1076525856 11.614 96 10 20 0 1115766400 36975152 2265392
03:07:29 78302 8192000000 10106190592 10532626432 1134526992 12.164 100 35 68 2 1181055744 39118752 7410000
03:09:51 62182 8192000000 10083402160 10553073664 1132513840 12.145 99 33 67 0 1200840288 61124304 7202144
03:12:33 53600 8192000000 10116685504 10559389696 1145237744 12.265 99 23 46 0 1217496400 67210096 5048560
03:14:30 18354 8192000000 10156492720 10596798464 1147210096 12.283 99 29 59 0 1249064608 94975664 6878848
03:15:50 109656 8192000000 10074066976 10569490432 1114447504 11.975 97 19 38 0 1212274016 93443472 4383040
03:18:58 48656 8192000000 10063796528 10504232960 1130952864 12.130 99 28 55 0 1163810640 27183472 5674304
03:20:50 48264 8192000000 10062393232 10519195648 1096744512 11.807 96 10 20 0 1118608480 19678464 2185504
03:22:40 66982 8192000000 10088184496 10562662400 1115927200 11.988 98 23 45 2 1181483216 60438512 5117504
03:24:57 80870 8192000000 10109202880 10558394368 1129632400 12.118 99 10 20 0 1197887664 66171648 2083616
03:27:19 43318 8192000000 10102566144 10552418304 1135711776 12.175 99 22 44 1 1205915344 64725136 5478432
03:29:26 69632 8192000000 10102967920 10577035264 1113794176 11.968 96 17 34 0 1181961104 64562720 3604208
03:31:36 57755 8192000000 10130377648 10580783104 1097490496 11.814 96 31 61 0 1174365968 70283392 6592080
03:33:49 36311 8192000000 10083647600 10534563840 1113677520 11.967 98 23 46 0 1170266864 51429200 5160144
03:35:38 77935 8192000000 10064772320 10505977856 1099829936 11.836 97 23 46 0 1174884848 70452016 4602896
03:37:57 112131 8192000000 10049376800 10489565184 1043040384 11.294 93 23 46 0 1077299264 29487136 4771744
03:40:50 55072 8192000000 10036709168 10484363264 1088070864 11.724 97 15 30 1 1121056464 29935376 3050224
03:44:14 48865 8192000000 10077473312 10542559232 1087442208 11.718 96 19 38 0 1149847424 57627200 4778016
03:46:04 98755 8192000000 10074394544 10572718080 1088519568 11.729 97 19 38 0 1198716064 105409888 4786608
03:48:53 40660 8192000000 10145482768 10600402944 1134046976 12.159 100 41 82 1 1214987792 72489248 8451568
03:50:34 53066 8192000000 10072583312 10561056768 1122981808 12.055 99 19 38 1 1186938080 59838640 4117632
03:52:27 61475 8192000000 10072775520 10554593280 1128179664 12.104 99 19 38 1 1217941632 85760880 4001088
03:54:29 122517 8192000000 10074743216 10518568960 1090504368 11.747 96 44 88 1 1139947872 40217328 9226176
03:57:33 83600 8192000000 10079727680 10557435904 1115732576 11.987 98 9 18 0 1195864672 78015568 2116528
03:59:57 14127 8192000000 10069615168 10572795904 1134864176 12.167 100 13 26 2 1181378256 43377008 3137072
04:01:11 55219 8192000000 10098257504 10572771328 1124531520 12.070 98 32 64 1 1189785952 58248960 7005472
04:03:10 59469 8192000000 10102689184 10557333504 1115973200 11.989 98 21 42 0 1170098288 49650176 4474912
04:05:12 71059 8192000000 10126483600 10585788416 1111414224 11.946 97 25 50 1 1203891808 87243984 5233600
04:07:23 47831 8192000000 10178853872 10673561600 1147744320 12.288 100 28 56 1 1256938624 103460400 5733904
04:09:12 67237 8192000000 10082914736 10570158080 1102292448 11.859 96 10 20 0 1185060640 81157824 1610368
04:11:21 32694 8192000000 10091618464 10525577216 1136087968 12.179 99 34 68 0 1173981872 31658496 6235408
04:12:56 63691 8192000000 10079100688 10520997888 1128418208 12.106 98 14 28 0 1169828784 38861248 2549328
04:15:11 57796 8192000000 10069956496 10541871104 1145811536 12.270 100 10 20 0 1171232848 23219856 2201456
04:17:20 49132 8192000000 10083615648 10538209280 1147787888 12.289 99 13 25 1 1173886576 23496208 2602480
04:19:10 69579 8192000000 10138042096 10609696768 1148741040 12.298 99 21 42 0 1207572720 54233760 4597920
04:21:20 65729 8192000000 10103232768 10567557120 1144356704 12.256 99 19 38 0 1205864320 57876912 3630704
04:23:43 62999 8192000000 10159199728 10607456256 1159061056 12.394 100 20 40 1 1240661504 77170016 4430432
04:25:52 108386 8192000000 10084876256 10541109248 1074680960 11.597 93 14 28 0 1115654688 37920928 3052800
04:28:41 79372 8192000000 10073135232 10547167232 1080632416 11.653 95 14 28 0 1134714592 51361008 2721168
04:31:04 104642 8192000000 10105360448 10588901376 1119514064 12.022 99 26 52 0 1206856144 82054944 5287136
04:34:02 46737 8192000000 10076002128 10577186816 1132711056 12.147 100 4 8 0 1185412224 51918320 782848
04:35:56 62595 8192000000 10060704848 10531422208 1125189376 12.076 99 15 30 0 1169776864 41370544 3216944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-09-2021 04:39:02 to 08-09-2021 04:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-09-2021 04:39:17 to 08-09-2021 04:39:19.

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          235395
MON$OLDEST_ACTIVE               236928
MON$OLDEST_SNAPSHOT             236928
MON$NEXT_TRANSACTION            236928
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                       2061152
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2584 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2584 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2584 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2584 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Wed Sep  8 04:37:11 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Sep  8 04:37:11 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Sep  8 04:37:16 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

08.09.21 04:39:20. 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-09-08 04:39:22 No severe PSQL-related problems occured
08.09.21 04:39:24. End of report.