Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.08.2021 04:37
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 16284640 kB
MemAvailable 30982868 kB
Buffers 20 kB
Cached 15315996 kB
SwapCached 2624 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-08-08 04:37:05
DTS_END                         2021-08-08 04:37:05.5700
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/60118
TRN_ID                          226654
ATT_ID                          663
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: 8839.59 21958 1060751
customer order: creation 162.26 423 19471
customer order: refuse 14.39 337 1727
order to supplier: creation 43.69 1627 5243
order to supplier: removal 3.43 114153 412
invoice (draft): creation 43.37 3475 5204
invoice (draft): removal 50.91 1005 6109
invoice accept: apply 38.78 19458 4653
invoice accept: cancel 45.95 898 5514
customer reserve: creation 3624.74 190 434969
customer reserve: removal 4535.38 68 544246
realization accept: apply 78.82 215 9458
realization accept: cancel 79.21 129 9505
payment from customer: creation 54.50 134 6540
payment from customer: removal 5.52 114 662
payment to supplier: creation 44.93 160 5392
payment to supplier: removal 3.33 85 400
service: total inventory turnovers 3.59 3877 431
service: total monetary turnovers 5.95 530 714
service: aggregate perf. data 0.81 17804 97
service: refresh index statistics 0.03 274487 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-08-2021 04:37:52 to 08-08-2021 04:37:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5135
TEST_TIME 2 4606
TEST_TIME 3 3823
TEST_TIME 4 3655
TEST_TIME 5 4334
TEST_TIME 6 4624
TEST_TIME 7 4921
TEST_TIME 8 5515
TEST_TIME 9 5371
TEST_TIME 10 5835
TEST_TIME 11 6046
TEST_TIME 12 6486
TEST_TIME 13 6679
TEST_TIME 14 6880
TEST_TIME 15 7225
TEST_TIME 16 7381
TEST_TIME 17 7985
TEST_TIME 18 8430
TEST_TIME 19 8489
TEST_TIME 20 8711
TEST_TIME 21 9026
TEST_TIME 22 9199
TEST_TIME 23 9533
TEST_TIME 24 9740
TEST_TIME 25 10350
TEST_TIME 26 10745
TEST_TIME 27 10728
TEST_TIME 28 10684
TEST_TIME 29 10464
TEST_TIME 30 10198
TEST_TIME 31 10064
TEST_TIME 32 9954
TEST_TIME 33 9813
TEST_TIME 34 9729
TEST_TIME 35 9635
TEST_TIME 36 9496
TEST_TIME 37 9487
TEST_TIME 38 9615
TEST_TIME 39 9593
TEST_TIME 40 9731
TEST_TIME 41 9688
TEST_TIME 42 9667
TEST_TIME 43 9516
TEST_TIME 44 9431
TEST_TIME 45 9338
TEST_TIME 46 9261
TEST_TIME 47 9241
TEST_TIME 48 9094
TEST_TIME 49 9209
TEST_TIME 50 9164
TEST_TIME 51 9124
TEST_TIME 52 9084
TEST_TIME 53 9106
TEST_TIME 54 9211
TEST_TIME 55 9265
TEST_TIME 56 9388
TEST_TIME 57 9380
TEST_TIME 58 9349
TEST_TIME 59 9471
TEST_TIME 60 9466
TEST_TIME 61 9392
TEST_TIME 62 9500
TEST_TIME 63 9432
TEST_TIME 64 9298
TEST_TIME 65 9242
TEST_TIME 66 9195
TEST_TIME 67 9158
TEST_TIME 68 9164
TEST_TIME 69 9107
TEST_TIME 70 9068
TEST_TIME 71 9183
TEST_TIME 72 9149
TEST_TIME 73 9142
TEST_TIME 74 9198
TEST_TIME 75 9374
TEST_TIME 76 9370
TEST_TIME 77 9327
TEST_TIME 78 9278
TEST_TIME 79 9417
TEST_TIME 80 9349
TEST_TIME 81 9237
TEST_TIME 82 9207
TEST_TIME 83 9201
TEST_TIME 84 9131
TEST_TIME 85 9070
TEST_TIME 86 9058
TEST_TIME 87 8993
TEST_TIME 88 8995
TEST_TIME 89 8983
TEST_TIME 90 8985
TEST_TIME 91 8978
TEST_TIME 92 9018
TEST_TIME 93 8986
TEST_TIME 94 8975
TEST_TIME 95 9043
TEST_TIME 96 9031
TEST_TIME 97 9035
TEST_TIME 98 9036
TEST_TIME 99 9118
TEST_TIME 100 9127
TEST_TIME 101 9126
TEST_TIME 102 9111
TEST_TIME 103 9050
TEST_TIME 104 8999
TEST_TIME 105 9013
TEST_TIME 106 8981
TEST_TIME 107 8927
TEST_TIME 108 8914
TEST_TIME 109 8864
TEST_TIME 110 8831
TEST_TIME 111 8793
TEST_TIME 112 8838
TEST_TIME 113 8854
TEST_TIME 114 8869
TEST_TIME 115 8861
TEST_TIME 116 8854
TEST_TIME 117 8909
TEST_TIME 118 8917
TEST_TIME 119 8906
TEST_TIME 120 8842

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-08-2021 04:37:56 to 08-08-2021 04:37:59.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1464670 1464670 0 0.00 0 18857 7 0 0
doc_list_biud 1464670 1464670 0 0.00 0 62211 32 0 0
sp_add_invoice_to_stock 4764 4653 111 2.32 30 163846 19458 0 111
sp_cancel_adding_invoice 5514 5514 0 0.00 5 72920 898 0 0
sp_cancel_client_order 1727 1727 0 0.00 4 8111 337 0 0
sp_cancel_customer_reserve 544246 544246 0 0.00 1 54643 69 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 8476 115 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 3296 86 0 0
sp_cancel_supplier_invoice 6115 6109 6 0.09 2 62221 1005 0 6
sp_cancel_supplier_order 412 412 0 0.00 8 924384 114154 0 0
sp_cancel_write_off 9505 9505 0 0.00 1 17388 129 0 0
sp_client_order 19471 19471 0 0.00 4 21211 423 0 0
sp_customer_reserve 434969 434969 0 0.00 0 89652 190 0 0
sp_fill_shopping_cart 29910 29910 0 0.00 1 14091 42 0 0
sp_fill_shopping_cart_clo_res 434962 434962 0 0.00 0 14908 19 0 0
sp_get_clo_for_invoice 4646 4646 0 0.00 0 21689 418 0 0
sp_kill_cost_storno 16081 16081 0 0.00 0 11013 64 0 0
sp_kill_qstorno_ret_qs2qd 1104518 1104518 0 0.00 0 62209 38 0 0
sp_kill_qty_storno 581617 581617 0 0.00 0 62211 80 0 0
sp_lock_selected_doc 19747 19747 0 0.00 0 7401 6 0 0
sp_make_cost_storno 26036 26036 0 0.00 0 18837 69 0 0
sp_multiply_rows_for_pdistr 26036 26036 0 0.00 0 16681 39 0 0
sp_multiply_rows_for_qdistr 19471 19471 0 0.00 0 17641 221 0 0
sp_pay_from_customer 6540 6540 0 0.00 4 19320 135 0 0
sp_pay_to_supplier 5392 5392 0 0.00 5 19307 161 0 0
sp_payment_common 11932 11932 0 0.00 1 14851 30 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 8102 297 0 0
sp_qd_handle_on_invoice_upd_sts 10160 10160 0 0.00 0 42895 356 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 21325 61 0 0
sp_reserve_write_off 9458 9458 0 0.00 6 21346 216 0 0
sp_supplier_invoice 5204 5204 0 0.00 8 73390 3476 0 0
sp_supplier_order 5243 5243 0 0.00 5 61730 1628 0 0
srv_aggregate_perf_data 97 97 0 0.00 2760 293649 17804 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2034 132379 44871 0 0
srv_find_qd_qs_mism 1016651 1016651 0 0.00 0 8074 5 0 0
srv_make_invnt_saldo 431 431 0 0.00 65 136190 3878 0 0
srv_make_money_saldo 714 714 0 0.00 1 13498 531 0 0
srv_recalc_idx_stat 4 4 0 0.00 179636 449938 274487 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4653 4653 0 0.00 0 17722 181 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 3783 81 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 11962 16 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 7679 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 476 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1111 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1091 18 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27414 1460 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 863 4 0 0
t$perf-norm:sp_client_order 19471 19471 0 0.00 0 11458 20 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4470 11 0 0
t$perf-norm:sp_pay_from_customer 6540 6540 0 0.00 0 10386 16 0 0
t$perf-norm:sp_pay_to_supplier 5392 5392 0 0.00 0 9916 9 0 0
t$perf-norm:sp_reserve_write_off 9458 9458 0 0.00 0 12983 16 0 0
t$perf-norm:sp_supplier_invoice 5204 5204 0 0.00 0 7649 21 0 0
t$perf-norm:sp_supplier_order 5243 5243 0 0.00 0 8542 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 73 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 63 2 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 1360 12 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 1554 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9512 9290 222 2.33 0 9400 22 0 222
v_all_customers 34994 34994 0 0.00 0 16 1 0 0
v_all_suppliers 28700 28700 0 0.00 0 30 1 0 0
v_all_wares 38942 38942 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 3919 20 0 0
v_cancel_client_order 3454 3454 0 0.00 0 4106 7 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 3064 12 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 5096 12 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 840 5 0 0
v_cancel_supplier_invoice 762 750 12 1.57 0 3564 18 0 12
v_cancel_supplier_order 824 824 0 0.00 0 363 3 0 0
v_cancel_write_off 1966 1966 0 0.00 0 2900 13 0 0
v_max_id_clo_ord 5237 5237 0 0.00 0 2583 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 4257 14 0 0
v_max_id_ord_sup 5201 5201 0 0.00 0 3845 4 0 0
v_max_non_paid_invoice 1480 1480 0 0.00 0 1539 3 0 0
v_max_non_paid_realizn 3559 3559 0 0.00 0 11553 8 0 0
v_min_id_clo_ord 5237 5237 0 0.00 0 3029 6 0 0
v_min_id_clo_res 989 989 0 0.00 0 5897 20 0 0
v_min_id_ord_sup 5201 5201 0 0.00 0 5591 6 0 0
v_min_non_paid_invoice 5392 5392 0 0.00 0 10255 18 0 0
v_min_non_paid_realizn 6540 6540 0 0.00 0 9803 20 0 0
v_our_firm 9910 9910 0 0.00 0 21 1 0 0
v_reserve_write_off 18916 18916 0 0.00 0 4660 7 0 0
x_lock_depdocs_on_canc_invoice 11248 11248 0 0.00 0 14684 108 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 8009 325 0 0
x_make_qstorno_1000_1200 5243 5243 0 0.00 2 60002 1550 0 0
x_make_qstorno_1200_2000 5204 5204 0 0.00 4 73362 3319 0 0
x_make_qstorno_2100_3300 434969 434969 0 0.00 0 89651 169 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 19752 8192000000 9725594944 10021601280 801364048 8.910 100 88 177 0 829372000 7246176 20761776
02:09:39 17697 8192000000 9759487120 10063831040 833681632 9.236 100 90 180 0 866832864 11935392 21215840
02:11:00 22204 8192000000 9790041392 10104217600 858937392 9.490 100 79 158 1 898344880 21318480 18089008
02:12:27 14378 8192000000 9869321632 10203615232 914987488 10.047 100 82 165 0 980672000 47407504 18277008
02:13:47 9789 8192000000 9917066624 10262761472 956419456 10.454 100 76 152 0 1031276432 58954608 15902368
02:15:01 4662 8192000000 9940273536 10299187200 1000280736 10.881 100 75 148 2 1061133152 45651808 15200608
02:16:08 3756 8192000000 9999583136 10362331136 1028986464 11.159 100 81 159 1 1109476672 63785408 16704800
02:17:14 2360 8192000000 10019687184 10384568320 1048062544 11.342 100 88 174 0 1137673680 71832576 17778560
02:18:18 1314 8192000000 10031052768 10400460800 1067236496 11.526 100 88 176 3 1137184240 51840496 18107248
02:19:21 10813 8192000000 10060965984 10434699264 1076401792 11.613 100 93 183 2 1159285696 64328576 18555328
02:20:32 6727 8192000000 9995842256 10399219712 1077062208 11.619 100 26 51 0 1104843568 22744320 5037040
02:21:41 4494 8192000000 9998404784 10399428608 1086002512 11.705 100 45 89 1 1122087808 26583840 9501456
02:22:53 8467 8192000000 10002996336 10405961728 1093612864 11.777 100 36 72 1 1125644000 24027632 8003504
02:24:03 14153 8192000000 10007328080 10412204032 1096070704 11.800 100 34 68 1 1128579904 25260272 7248928
02:26:11 3193 8192000000 10009302960 10408624128 1087245488 11.716 99 52 104 2 1110528240 11789168 11493584
02:27:16 7061 8192000000 10006661376 10409402368 1074597184 11.596 99 27 52 1 1103823600 23123856 6102560
02:28:24 7610 8192000000 10002283520 10398240768 1046210752 11.324 98 39 77 4 1070075040 14311552 9552736
02:29:34 40595 8192000000 9922020240 10306002944 952209088 10.413 90 26 52 0 977597824 19789264 5599472
02:31:15 4498 8192000000 9932580656 10309902336 1005675088 10.934 97 52 102 1 1033212432 16753376 10783968
02:32:22 8128 8192000000 9957576320 10347266048 1018576656 11.058 98 40 79 3 1050113216 22122256 9414304
02:33:33 6883 8192000000 9966095232 10353422336 1050898272 11.369 100 31 60 3 1073745552 16650928 6196352
02:34:40 36342 8192000000 9971873312 10380791808 1052834416 11.388 100 27 54 0 1095122336 36325664 5962256
02:36:20 6026 8192000000 9982288320 10373730304 1064030656 11.495 100 42 84 1 1089046656 16505888 8510112
02:37:36 32522 8192000000 10013310736 10406916096 1089396896 11.737 100 35 69 1 1114426752 17074272 7955584
02:39:12 27071 8192000000 10097369488 10503327744 1109765904 11.930 100 47 94 0 1187145168 66938416 10440848
02:41:02 62862 8192000000 10138449488 10547208192 1128165040 12.104 100 36 69 3 1242044720 105980480 7899200
02:43:10 10292 8192000000 10159344304 10562437120 1133553536 12.155 100 51 100 3 1259844048 115611664 10678848
02:44:25 17919 8192000000 10198930448 10621841408 1147231280 12.284 100 51 100 0 1314151936 155571472 11349184
02:45:49 11559 8192000000 10221351344 10636197888 1157406320 12.379 100 53 105 1 1332270624 163468144 11396160
02:47:07 6074 8192000000 10260296128 10677628928 1163870496 12.440 100 52 102 2 1360852912 185550304 11432112
02:48:16 6969 8192000000 10273632336 10685956096 1170904960 12.505 100 67 133 1 1375865280 190162384 14797936
02:49:26 6096 8192000000 10305423040 10724167680 1172749472 12.523 100 69 138 1 1412342512 223137504 16455536
02:50:51 4794 8192000000 10374193680 10780729344 1176423440 12.557 99 80 160 0 1470941056 276232016 18285600
02:52:00 4644 8192000000 10367445920 10798481408 1176974960 12.562 99 78 154 2 1462454736 267516672 17963104
02:53:10 2032 8192000000 10405830928 10826399744 1191326960 12.696 100 84 167 0 1513730192 303057184 19346048
02:54:18 2528 8192000000 10402268768 10810179584 1177613072 12.568 100 85 169 1 1501644192 304643584 19387536
02:55:23 2460 8192000000 10380366992 10810224640 1161773680 12.420 99 81 160 0 1464279184 283855888 18649616
02:56:28 10178 8192000000 10345063952 10770505728 1165942016 12.459 100 37 73 0 1450855872 275871312 9042544
02:57:56 18427 8192000000 10364045904 10819604480 1154624976 12.353 100 30 60 1 1466521728 303771616 8125136
02:59:16 19820 8192000000 10320958560 10771673088 1140951584 12.224 100 27 54 0 1442263472 294024080 7287808
03:00:37 26629 8192000000 10283638032 10755874816 1115092320 11.981 99 24 48 2 1379632672 258265248 6275104
03:02:09 23564 8192000000 10213823008 10692849664 1131955568 12.140 100 23 46 0 1389350064 251493360 5901136
03:04:05 13996 8192000000 10067613360 10487070720 1120748720 12.034 100 49 99 0 1157530032 26563568 10217744
03:05:28 132379 8192000000 10021435488 10461364224 1056778992 11.426 95 20 40 0 1091884720 30919408 4186320
03:08:41 104154 8192000000 10079102832 10498826240 1034127328 11.208 93 39 74 2 1080602064 38567680 7907056
03:11:27 13493 8192000000 10096457472 10524348416 1119117520 12.019 100 32 64 1 1193038880 67120864 6800496
03:12:42 22931 8192000000 10153184144 10572685312 1102900256 11.865 98 44 88 0 1238686768 125878704 9907808
03:14:06 35584 8192000000 10184609312 10629746688 1126308816 12.087 100 36 68 0 1285876112 151515456 8051840
03:15:49 43465 8192000000 10119387808 10592501760 1111700864 11.949 99 15 30 0 1259161072 143761632 3698576
03:17:33 63509 8192000000 10069285040 10532225024 1121273728 12.039 100 29 57 3 1180237008 52221824 6741456
03:19:37 85523 8192000000 10058906512 10512642048 1132957184 12.149 100 26 52 0 1163761504 25672800 5131520
03:22:03 24035 8192000000 10072467872 10506571776 1142513600 12.239 100 45 91 1 1171319984 19295680 9510704
03:23:32 60058 8192000000 10100687072 10556915712 1138991728 12.206 100 10 20 1 1205356480 63982880 2381872
03:25:32 69173 8192000000 10087443840 10586238976 1137979808 12.197 100 19 38 2 1205943856 64077184 3886864
03:27:56 25583 8192000000 10072834848 10518126592 1135764208 12.176 100 36 72 2 1173320512 30109600 7446704
03:29:23 51075 8192000000 10182852336 10642038784 1150714400 12.316 100 30 60 1 1230000448 72773520 6512528
03:31:17 65513 8192000000 10153983520 10647801856 1149650720 12.306 100 18 36 1 1254670816 101248960 3771136
03:33:23 43620 8192000000 10085585216 10528866304 1144715104 12.260 100 22 42 1 1180552224 31636160 4200960
03:35:07 46166 8192000000 10117574160 10549710848 1143586320 12.249 99 43 86 1 1199228816 46962832 8679664
03:36:56 48940 8192000000 10085838320 10540392448 1157385472 12.379 100 24 49 0 1218720160 56171904 5162784
03:38:46 87366 8192000000 10099412256 10617528320 1081628864 11.663 94 7 12 1 1141977760 59205584 1143312
03:41:13 117990 8192000000 10100738368 10598395904 1110888048 11.941 98 10 20 0 1199078784 86093536 2097200
03:44:21 34259 8192000000 10119012560 10584666112 1140224080 12.218 100 14 27 1 1235691328 91820672 3646576
03:46:06 62342 8192000000 10137102608 10571300864 1085681584 11.702 95 18 35 0 1170984640 81394576 3908480
03:48:19 64510 8192000000 10125859440 10622078976 1119681776 12.024 99 28 57 0 1236516720 110377024 6457920
03:50:24 49658 8192000000 10125842304 10572607488 1108494976 11.918 98 34 68 0 1218931024 102931728 7504320
03:52:18 84701 8192000000 10069763472 10602213376 1084904000 11.694 97 17 34 1 1175283488 86460144 3919344
03:54:43 24121 8192000000 10098748736 10514354176 1109405696 11.927 99 54 109 1 1145390992 24896944 11088352
03:56:20 84907 8192000000 10056377920 10501861376 1064645984 11.501 95 18 36 1 1132249680 63980544 3623152
03:59:18 63507 8192000000 10064046752 10511097856 1097590544 11.815 98 30 60 2 1175908912 72092032 6226336
04:01:23 45207 8192000000 10062060352 10506797056 1121852544 12.044 100 10 19 2 1169065120 45104128 2108448
04:03:32 47186 8192000000 10078384048 10535092224 1120372256 12.031 99 9 18 0 1180555472 58259664 1923552
04:05:34 58961 8192000000 10027963872 10478862336 1058636032 11.443 95 9 18 0 1097343248 36622432 2084784
04:07:36 30573 8192000000 10064753360 10497413120 1104071488 11.876 99 28 56 1 1134631088 25251728 5307872
04:09:10 61845 8192000000 10053139136 10491834368 1116972176 11.998 99 15 30 0 1158242432 38438720 2831536
04:11:14 54291 8192000000 10110681104 10584481792 1130206032 12.123 100 35 70 2 1219606816 82228064 7172720
04:13:16 34465 8192000000 10066620816 10498756608 1108495296 11.918 98 28 54 2 1149720672 35477952 5747424
04:14:52 22657 8192000000 10109341600 10533117952 1116221840 11.991 98 39 78 0 1156711552 32813792 7675920
04:17:42 93312 8192000000 10107579296 10581360640 1108641264 11.920 97 18 36 1 1164407136 52072000 3693872
04:20:20 83386 8192000000 10078667120 10516631552 1133397536 12.153 100 37 74 0 1169006928 28174816 7434576
04:22:44 66335 8192000000 10069521824 10543788032 1138856256 12.205 100 9 18 0 1186318304 45579200 1882848
04:24:50 45711 8192000000 10056449520 10522177536 1143308576 12.247 100 17 35 0 1166306480 19108240 3889664
04:26:43 77239 8192000000 10110753824 10574401536 1141514672 12.230 99 14 28 0 1189348464 44777456 3056336
04:29:35 63727 8192000000 10093928528 10597556224 1120188736 12.029 97 8 16 1 1213173776 91237072 1747968
04:31:40 24048 8192000000 10087412416 10533273600 1132367984 12.144 99 21 41 2 1161175328 24778432 4028912
04:33:06 49191 8192000000 10129231776 10573963264 1135015296 12.169 99 30 60 1 1191614192 50453264 6145632
04:34:56 94563 8192000000 10104060288 10556669952 1105869728 11.893 96 15 30 1 1167122528 57969888 3282912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-08-2021 04:38:06 to 08-08-2021 04:38:21.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-08-2021 04:38:22 to 08-08-2021 04:38:24.

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          224578
MON$OLDEST_ACTIVE               226772
MON$OLDEST_SNAPSHOT             226772
MON$NEXT_TRANSACTION            226772
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                       2028000
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2563 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2563 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2563 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2563 Firebird 4.0/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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Sun Aug  8 04:37:06 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sun Aug  8 04:37:09 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Aug  8 04:37:11 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
--- end of diff output ---

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

08.08.21 04:38:25. 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-08-08 04:38:28 No severe PSQL-related problems occured
08.08.21 04:38:29. End of report.