Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.11.2021 16:43
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 16099664 kB
MemAvailable 30759076 kB
Buffers 0 kB
Cached 15579328 kB
SwapCached 2452 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-11-09 16:42:36
DTS_END                         2021-11-09 16:42:36.5730
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(57:17)
IP                              ::1/49826
TRN_ID                          234233
ATT_ID                          619
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: 9081.42 22224 1089770
customer order: creation 165.24 410 19829
customer order: refuse 14.67 362 1760
order to supplier: creation 44.54 1364 5345
order to supplier: removal 3.55 100678 426
invoice (draft): creation 44.89 3603 5387
invoice (draft): removal 54.02 864 6482
invoice accept: apply 40.01 18467 4801
invoice accept: cancel 48.00 755 5760
customer reserve: creation 3672.14 182 440657
customer reserve: removal 4713.09 58 565571
realization accept: apply 79.80 183 9576
realization accept: cancel 77.45 114 9294
payment from customer: creation 57.05 109 6846
payment from customer: removal 5.66 94 679
payment to supplier: creation 46.20 134 5544
payment to supplier: removal 3.23 57 387
service: total inventory turnovers 4.75 1316 570
service: total monetary turnovers 6.34 33 761
service: aggregate perf. data 0.76 24963 91
service: refresh index statistics 0.03 290734 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2021 16:43:28 to 09-11-2021 16:43:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5962
TEST_TIME 2 4625
TEST_TIME 3 4732
TEST_TIME 4 4614
TEST_TIME 5 4988
TEST_TIME 6 6125
TEST_TIME 7 5986
TEST_TIME 8 6009
TEST_TIME 9 6021
TEST_TIME 10 6014
TEST_TIME 11 5921
TEST_TIME 12 6299
TEST_TIME 13 6458
TEST_TIME 14 6856
TEST_TIME 15 7014
TEST_TIME 16 7222
TEST_TIME 17 7327
TEST_TIME 18 7388
TEST_TIME 19 7924
TEST_TIME 20 8118
TEST_TIME 21 8468
TEST_TIME 22 8409
TEST_TIME 23 8710
TEST_TIME 24 8926
TEST_TIME 25 8900
TEST_TIME 26 8968
TEST_TIME 27 9057
TEST_TIME 28 9439
TEST_TIME 29 10101
TEST_TIME 30 10413
TEST_TIME 31 10201
TEST_TIME 32 10161
TEST_TIME 33 10322
TEST_TIME 34 10192
TEST_TIME 35 10147
TEST_TIME 36 10024
TEST_TIME 37 9966
TEST_TIME 38 9785
TEST_TIME 39 9651
TEST_TIME 40 9569
TEST_TIME 41 9641
TEST_TIME 42 9547
TEST_TIME 43 9441
TEST_TIME 44 9319
TEST_TIME 45 9317
TEST_TIME 46 9399
TEST_TIME 47 9540
TEST_TIME 48 9375
TEST_TIME 49 9375
TEST_TIME 50 9265
TEST_TIME 51 9321
TEST_TIME 52 9339
TEST_TIME 53 9290
TEST_TIME 54 9161
TEST_TIME 55 9163
TEST_TIME 56 9138
TEST_TIME 57 9233
TEST_TIME 58 9172
TEST_TIME 59 9110
TEST_TIME 60 9063
TEST_TIME 61 9016
TEST_TIME 62 9021
TEST_TIME 63 9019
TEST_TIME 64 9091
TEST_TIME 65 9108
TEST_TIME 66 9141
TEST_TIME 67 9136
TEST_TIME 68 9088
TEST_TIME 69 9206
TEST_TIME 70 9238
TEST_TIME 71 9253
TEST_TIME 72 9325
TEST_TIME 73 9343
TEST_TIME 74 9472
TEST_TIME 75 9438
TEST_TIME 76 9437
TEST_TIME 77 9386
TEST_TIME 78 9300
TEST_TIME 79 9314
TEST_TIME 80 9270
TEST_TIME 81 9255
TEST_TIME 82 9196
TEST_TIME 83 9175
TEST_TIME 84 9121
TEST_TIME 85 9180
TEST_TIME 86 9112
TEST_TIME 87 9075
TEST_TIME 88 9076
TEST_TIME 89 9072
TEST_TIME 90 9086
TEST_TIME 91 9084
TEST_TIME 92 9158
TEST_TIME 93 9154
TEST_TIME 94 9148
TEST_TIME 95 9170
TEST_TIME 96 9216
TEST_TIME 97 9251
TEST_TIME 98 9255
TEST_TIME 99 9275
TEST_TIME 100 9253
TEST_TIME 101 9268
TEST_TIME 102 9216
TEST_TIME 103 9157
TEST_TIME 104 9160
TEST_TIME 105 9116
TEST_TIME 106 9047
TEST_TIME 107 9068
TEST_TIME 108 9031
TEST_TIME 109 9045
TEST_TIME 110 9067
TEST_TIME 111 9026
TEST_TIME 112 8990
TEST_TIME 113 9044
TEST_TIME 114 9068
TEST_TIME 115 9097
TEST_TIME 116 9115
TEST_TIME 117 9119
TEST_TIME 118 9134
TEST_TIME 119 9127
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-11-2021 16:43:31 to 09-11-2021 16:43:35.

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 1498024 1498024 0 0.00 0 19392 6 0 0
doc_list_biud 1498024 1498024 0 0.00 0 45491 28 0 0
sp_add_invoice_to_stock 4940 4801 139 2.81 12 196682 18467 0 139
sp_cancel_adding_invoice 5760 5760 0 0.00 5 57623 756 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 11163 362 0 0
sp_cancel_customer_reserve 565571 565571 0 0.00 1 45493 58 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 11785 95 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 2 3159 57 0 0
sp_cancel_supplier_invoice 6491 6482 9 0.13 2 22272 865 0 9
sp_cancel_supplier_order 426 426 0 0.00 6 945873 100679 0 0
sp_cancel_write_off 9294 9294 0 0.00 1 8721 115 0 0
sp_client_order 19829 19829 0 0.00 4 26596 411 0 0
sp_customer_reserve 440657 440657 0 0.00 0 51486 183 0 0
sp_fill_shopping_cart 30557 30557 0 0.00 1 28162 45 0 0
sp_fill_shopping_cart_clo_res 440642 440642 0 0.00 0 17631 15 0 0
sp_get_clo_for_invoice 4784 4784 0 0.00 0 32151 333 0 0
sp_kill_cost_storno 16120 16120 0 0.00 0 11778 60 0 0
sp_kill_qstorno_ret_qs2qd 1147344 1147344 0 0.00 0 45488 32 0 0
sp_kill_qty_storno 603653 603653 0 0.00 0 45491 69 0 0
sp_lock_selected_doc 20142 20142 0 0.00 0 7850 5 0 0
sp_make_cost_storno 26750 26750 0 0.00 0 16988 54 0 0
sp_multiply_rows_for_pdistr 26750 26750 0 0.00 0 7684 29 0 0
sp_multiply_rows_for_qdistr 19829 19829 0 0.00 0 13424 192 0 0
sp_pay_from_customer 6846 6846 0 0.00 4 10517 109 0 0
sp_pay_to_supplier 5544 5544 0 0.00 5 10639 135 0 0
sp_payment_common 12390 12390 0 0.00 1 8440 25 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 11156 342 0 0
sp_qd_handle_on_invoice_upd_sts 10544 10544 0 0.00 0 25589 339 0 0
sp_qd_handle_on_reserve_upd_sts 9576 9576 0 0.00 0 20421 62 0 0
sp_reserve_write_off 9576 9576 0 0.00 6 20471 183 0 0
sp_supplier_invoice 5387 5387 0 0.00 6 92265 3603 0 0
sp_supplier_order 5345 5345 0 0.00 6 26348 1364 0 0
srv_aggregate_perf_data 91 91 0 0.00 2585 429483 24963 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2261 124750 42610 0 0
srv_find_qd_qs_mism 1044120 1044120 0 0.00 0 19141 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 43 87634 1317 0 0
srv_make_money_saldo 761 761 0 0.00 1 6148 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 113821 409552 290735 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4803 4803 0 0.00 0 8985 163 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 7514 92 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3615 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 781 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 655 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 3578 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 3005 22 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 20035 1551 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 732 6 0 0
t$perf-norm:sp_client_order 19829 19829 0 0.00 0 22437 17 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 2338 5 0 0
t$perf-norm:sp_pay_from_customer 6846 6846 0 0.00 0 9425 10 0 0
t$perf-norm:sp_pay_to_supplier 5544 5544 0 0.00 0 8475 13 0 0
t$perf-norm:sp_reserve_write_off 9576 9576 0 0.00 0 7031 13 0 0
t$perf-norm:sp_supplier_invoice 5387 5387 0 0.00 0 4642 15 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 3915 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 146 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 3290 28 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1035 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 10 6 0 0
v_add_invoice_to_stock 9846 9568 278 2.82 0 6280 19 0 278
v_all_customers 36124 36124 0 0.00 0 15 1 0 0
v_all_suppliers 29468 29468 0 0.00 0 15 1 0 0
v_all_wares 39658 39658 0 0.00 0 112 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 136 5 0 0
v_cancel_client_order 3520 3520 0 0.00 0 1586 4 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 3024 8 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2070 5 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 201 2 0 0
v_cancel_supplier_invoice 860 842 18 2.09 0 2900 17 0 18
v_cancel_supplier_order 852 852 0 0.00 0 6280 16 0 0
v_cancel_write_off 2006 2006 0 0.00 0 7750 18 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 590 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 2655 13 0 0
v_max_id_ord_sup 5384 5384 0 0.00 0 5301 6 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 1915 4 0 0
v_max_non_paid_realizn 3653 3653 0 0.00 0 2211 4 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 7670 11 0 0
v_min_id_clo_res 949 949 0 0.00 0 2910 10 0 0
v_min_id_ord_sup 5384 5384 0 0.00 0 7288 15 0 0
v_min_non_paid_invoice 5544 5544 0 0.00 0 7833 19 0 0
v_min_non_paid_realizn 6846 6846 0 0.00 0 6422 16 0 0
v_our_firm 9920 9920 0 0.00 0 18 1 0 0
v_reserve_write_off 19152 19152 0 0.00 0 6389 4 0 0
x_lock_depdocs_on_canc_invoice 11821 11821 0 0.00 0 17631 119 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 20581 468 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 2 26337 1278 0 0
x_make_qstorno_1200_2000 5387 5387 0 0.00 3 84923 3435 0 0
x_make_qstorno_2100_3300 440657 440657 0 0.00 0 51483 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2021 16:43:36 to 09-11-2021 16:43:40.

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
14:12:40 2 8192000000 8799481472 8811085824 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:41 28853 8192000000 9902486448 10213998592 979923552 10.683 100 86 172 0 1008859904 6172960 22763392
14:15:11 20602 8192000000 9950051744 10274267136 1031587904 11.184 100 92 184 0 1063525632 8089840 23847888
14:16:35 9298 8192000000 9985517632 10319941632 1069211616 11.545 100 86 172 2 1100754448 9756752 21786080
14:17:52 15433 8192000000 10044462624 10395713536 1128110416 12.104 100 82 164 0 1159126416 11245696 19770304
14:19:10 10127 8192000000 10091555072 10455027712 1176364304 12.556 100 81 160 1 1207620976 12548176 18708496
14:20:22 5689 8192000000 10131556144 10502594560 1219798992 12.960 100 81 162 2 1252295712 14184592 18312128
14:21:29 3064 8192000000 10160408896 10537500672 1255119872 13.285 100 87 174 3 1288067104 12664880 20282352
14:22:34 2608 8192000000 10190791440 10572910592 1275875376 13.475 100 86 172 0 1307786208 12966560 18944272
14:23:39 2318 8192000000 10215996784 10601283584 1297416704 13.672 100 90 182 2 1329966816 11980720 20569392
14:24:43 6456 8192000000 10199134304 10602856448 1306465616 13.754 100 41 82 0 1323987776 8228368 9293792
14:25:50 25130 8192000000 10217020944 10617942016 1310873264 13.794 100 67 135 1 1332738000 5555360 16309376
14:27:15 7239 8192000000 10209537360 10623123456 1313509792 13.818 100 26 52 2 1329938192 10512304 5916096
14:28:24 7324 8192000000 10211782576 10633981952 1325179968 13.924 100 22 43 1 1335842592 5710400 4952224
14:29:32 4002 8192000000 10217707920 10640232448 1331323168 13.979 100 26 52 0 1344060560 6781584 5955808
14:30:36 63763 8192000000 10237396464 10652045312 1334566320 14.008 100 26 52 0 1345681328 5090736 6024272
14:32:40 4433 8192000000 10208817696 10625413120 1314844832 13.830 100 30 59 0 1327924304 6474592 6604880
14:33:46 8603 8192000000 10179419344 10596859904 1292279200 13.625 100 28 54 1 1308741296 9910848 6551248
14:34:55 9159 8192000000 10161191616 10565218304 1225948944 13.017 95 20 41 1 1236240080 4665600 5625536
14:36:08 18413 8192000000 10134222480 10532323328 1198628976 12.764 94 31 62 1 1211861264 6862288 6370000
14:37:31 7714 8192000000 10155395664 10552508416 1240405616 13.150 98 28 56 2 1252628016 5357312 6865088
14:38:40 7681 8192000000 10162315808 10571145216 1272654096 13.446 100 27 54 0 1285410464 5484736 7271632
14:39:51 72576 8192000000 10186450736 10597363712 1296410176 13.663 100 22 44 0 1306739968 5123936 5205856
14:42:04 6876 8192000000 10204464368 10615476224 1309841904 13.785 100 25 49 1 1320685072 5608768 5234400
14:43:16 4064 8192000000 10233033664 10640412672 1333350720 13.997 100 47 92 2 1352070240 6392960 12326560
14:44:28 19102 8192000000 10261672576 10672619520 1353467744 14.179 100 50 100 1 1374790800 8837280 12485776
14:45:49 28779 8192000000 10263550448 10683215872 1362183504 14.257 100 40 76 3 1381228128 9581536 9463088
14:47:21 9418 8192000000 10272838496 10698235904 1365222032 14.284 100 33 65 1 1387248928 14101888 7925008
14:48:35 7204 8192000000 10285452848 10710786048 1368829120 14.317 99 55 110 2 1395342480 12848992 13664368
14:49:45 8095 8192000000 10302605584 10725199872 1390463232 14.510 100 49 97 1 1412609136 10923584 11222320
14:51:01 9316 8192000000 10321521968 10745581568 1400037232 14.595 100 55 110 0 1435018176 21626496 13354448
14:52:13 7598 8192000000 10329498736 10753695744 1404129552 14.632 100 52 104 2 1435573744 19205408 12238784
14:53:29 5482 8192000000 10338182336 10763796480 1402845088 14.620 99 59 115 3 1439528000 21659008 15023904
14:54:38 6364 8192000000 10341715792 10766168064 1413559344 14.716 100 58 116 0 1450387968 22302480 14526144
14:55:47 2258 8192000000 10347777344 10757193728 1392414880 14.527 99 83 166 1 1441629648 28441584 20773184
14:57:01 6008 8192000000 10347155760 10768785408 1411621952 14.698 100 64 126 1 1455788448 28048496 16118000
14:58:15 3167 8192000000 10355236704 10773340160 1400833968 14.602 99 74 145 1 1449978448 31798288 17346192
14:59:24 5142 8192000000 10376439968 10791759872 1418573712 14.760 100 80 158 2 1476631696 38319712 19738272
15:00:33 4722 8192000000 10363766736 10784055296 1412479808 14.706 100 66 130 1 1476621632 47284576 16857248
15:01:42 7738 8192000000 10338789264 10766413824 1379704752 14.414 99 52 104 1 1441123168 48062160 13356256
15:03:02 4296 8192000000 10318415248 10745540608 1373770432 14.361 100 52 104 2 1429989536 42666992 13552112
15:04:12 5890 8192000000 10313536992 10739167232 1376765376 14.388 100 43 86 0 1428840208 40736096 11338736
15:05:23 11406 8192000000 10309728656 10736070656 1366476624 14.295 99 34 68 0 1412830480 37450576 8903280
15:06:37 26910 8192000000 10310870480 10736377856 1360908096 14.246 99 29 58 2 1406176080 37667776 7600208
15:08:13 23740 8192000000 10291361216 10726846464 1361440720 14.250 100 26 52 0 1406496768 37919408 7136640
15:09:39 14643 8192000000 10294524880 10730889216 1351937232 14.165 100 27 54 0 1405121824 45073872 8110720
15:10:56 31431 8192000000 10287833504 10731663360 1348549488 14.134 100 23 46 1 1398011968 42935440 6527040
15:12:39 87544 8192000000 10264601712 10695794688 1312479552 13.809 96 20 40 1 1335545760 18144400 4921808
15:15:11 42111 8192000000 10257036800 10701099008 1343224128 14.086 100 26 51 2 1364812960 15906976 5681856
15:16:55 32025 8192000000 10266121712 10706477056 1353317056 14.177 99 22 44 0 1366181232 7917728 4946448
15:18:30 97312 8192000000 10264951984 10716147712 1336934448 14.030 97 24 48 1 1350234784 8233136 5067200
15:21:40 81827 8192000000 10269599456 10712563712 1341175456 14.068 97 21 43 1 1356996752 11426800 4394496
15:24:07 74137 8192000000 10299149344 10744819712 1386408768 14.474 100 22 42 0 1403814352 12865984 4539600
15:26:39 71203 8192000000 10296373600 10744016896 1390766576 14.513 100 38 76 0 1413472352 13175776 9530000
15:28:57 52952 8192000000 10296185952 10756112384 1379591152 14.413 99 24 48 2 1408106448 22960736 5554560
15:30:53 40829 8192000000 10300472592 10750779392 1392421856 14.527 100 18 36 0 1411322896 14761488 4139552
15:32:45 27783 8192000000 10309913072 10756894720 1390986528 14.515 99 38 78 3 1413336512 12772608 9577376
15:34:15 46549 8192000000 10296280064 10757005312 1398441872 14.581 100 24 48 1 1419932800 15107440 6383488
15:36:21 60296 8192000000 10307859504 10764005376 1387311232 14.482 99 11 22 0 1404411792 14411472 2689088
15:38:25 70322 8192000000 10311419392 10784452608 1405051792 14.640 100 12 24 1 1431833728 23265296 3516640
15:40:35 64164 8192000000 10299927856 10761502720 1402127296 14.614 100 6 12 0 1417039440 13680656 1231488
15:42:47 52032 8192000000 10283558192 10746503168 1353165232 14.176 96 7 12 1 1361574144 7382368 1026544
15:44:58 42021 8192000000 10296575504 10758737920 1391310160 14.518 99 11 22 0 1402264064 8976640 1977264
15:47:24 47264 8192000000 10282859744 10731925504 1359393184 14.232 98 13 24 1 1369568176 7068720 3106272
15:49:11 37871 8192000000 10307781824 10752020480 1363720640 14.271 97 25 50 1 1385621360 16372368 5528352
15:50:50 17803 8192000000 10293176720 10745933824 1383397040 14.447 100 33 67 0 1410119920 19178096 7544784
15:52:13 66479 8192000000 10296420064 10748043264 1365506064 14.287 99 18 36 0 1393642480 23901184 4235232
15:54:21 81163 8192000000 10258465264 10704646144 1278760992 13.502 93 21 41 3 1300414064 15913152 5739920
15:56:42 42097 8192000000 10272810656 10721697792 1344310304 14.096 99 15 31 1 1365793952 18114480 3369168
15:58:53 70700 8192000000 10253755456 10705993728 1344290752 14.096 99 26 52 2 1366306768 16519264 5496752
16:01:33 61925 8192000000 10271347408 10730438656 1371598080 14.341 100 21 42 0 1387535216 11277664 4659472
16:03:36 54821 8192000000 10261320224 10717384704 1344908208 14.102 98 26 51 2 1360646208 9827248 5910752
16:06:08 74103 8192000000 10270969856 10723631104 1352498736 14.170 98 10 20 1 1366272704 11431840 2342128
16:08:32 34315 8192000000 10255163472 10711777280 1344046848 14.094 98 21 40 1 1359444736 10333072 5064816
16:10:21 55768 8192000000 10269812336 10720477184 1305945392 13.749 95 8 16 1 1316192704 8604192 1643120
16:12:17 39208 8192000000 10266912704 10702364672 1307192704 13.761 96 40 80 0 1327320352 11321872 8805776
16:14:05 66468 8192000000 10277934720 10742865920 1362315488 14.258 99 11 22 0 1376318208 11726640 2276080
16:16:28 47083 8192000000 10284198304 10729598976 1361376192 14.250 98 22 44 0 1374964832 8521360 5067280
16:18:32 110779 8192000000 10306391328 10751922176 1361864864 14.254 98 21 42 0 1377581680 11194096 4522720
16:21:23 52828 8192000000 10310684848 10759446528 1395887760 14.558 100 24 49 0 1411645920 10427600 5330560
16:23:17 19881 8192000000 10300838736 10741854208 1372918000 14.353 99 42 84 2 1393809024 11868608 9022416
16:24:38 124750 8192000000 10286139952 10741542912 1363911520 14.272 98 25 50 0 1381503136 11229472 6362144
16:27:44 65030 8192000000 10285501184 10745540608 1389995120 14.506 100 11 22 1 1403213488 10236704 2981664
16:29:50 63662 8192000000 10286565008 10742677504 1362820816 14.263 98 22 44 0 1379517872 11473504 5223552
16:32:11 85127 8192000000 10286120832 10742980608 1364054032 14.274 98 10 20 1 1377592144 11340752 2197360
16:34:36 91528 8192000000 10290412752 10747179008 1359282256 14.231 97 17 34 0 1377167024 13617568 4267200
16:37:21 63885 8192000000 10298450784 10759786496 1378533616 14.403 99 16 32 0 1396980576 14617552 3829408
16:39:26 80885 8192000000 10280115440 10728443904 1337511248 14.035 96 17 34 2 1356274544 15253392 3509904
16:41:47 18240 8192000000 10307645552 10739412992 1380471120 14.421 100 45 90 0 1401224752 10569152 10184480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2021 16:43:42 to 09-11-2021 16:43:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-11-2021 16:43:58 to 09-11-2021 16:43:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231260
MON$OLDEST_ACTIVE               234839
MON$OLDEST_SNAPSHOT             234839
MON$NEXT_TRANSACTION            234839
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-03 16:41:55.3470
MON$PAGES                       2019136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Nov  9 16:42:38 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Nov  9 16:42:38 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Nov  9 16:42:38 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Nov  9 16:42:40 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Tue Nov  9 16:42:47 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

09.11.21 16:44:00. There are 3 logs in /var/tmp/logs-oltp30 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-11-09 16:44:03 No severe PSQL-related problems occured
09.11.21 16:44:04. End of report.