Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2021 13: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 16056976 kB
MemAvailable 30819968 kB
Buffers 0 kB
Cached 15631944 kB
SwapCached 4064 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-11-01 13:37:08
DTS_END                         2021-11-01 13:37:08.2850
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43032
TRN_ID                          230957
ATT_ID                          625
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: 9015.69 23316 1081883
customer order: creation 166.15 416 19938
customer order: refuse 14.42 311 1730
order to supplier: creation 43.67 1483 5240
order to supplier: removal 3.31 117658 397
invoice (draft): creation 43.87 3613 5264
invoice (draft): removal 51.08 1025 6129
invoice accept: apply 41.10 19326 4932
invoice accept: cancel 46.02 822 5522
customer reserve: creation 3810.67 190 457280
customer reserve: removal 4507.49 67 540899
realization accept: apply 79.68 190 9561
realization accept: cancel 82.87 132 9944
payment from customer: creation 58.19 109 6983
payment from customer: removal 5.48 77 657
payment to supplier: creation 47.62 133 5714
payment to supplier: removal 3.46 74 415
service: total inventory turnovers 3.76 3417 451
service: total monetary turnovers 5.95 428 714
service: aggregate perf. data 0.90 13365 108
service: refresh index statistics 0.04 303480 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2021 13:37:52 to 01-11-2021 13:37:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5841
TEST_TIME 2 5325
TEST_TIME 3 5560
TEST_TIME 4 4968
TEST_TIME 5 4662
TEST_TIME 6 4476
TEST_TIME 7 4706
TEST_TIME 8 4766
TEST_TIME 9 5016
TEST_TIME 10 5732
TEST_TIME 11 6053
TEST_TIME 12 6372
TEST_TIME 13 6353
TEST_TIME 14 6718
TEST_TIME 15 6790
TEST_TIME 16 7079
TEST_TIME 17 7279
TEST_TIME 18 8497
TEST_TIME 19 8633
TEST_TIME 20 8971
TEST_TIME 21 8933
TEST_TIME 22 9012
TEST_TIME 23 9136
TEST_TIME 24 9329
TEST_TIME 25 11061
TEST_TIME 26 11939
TEST_TIME 27 11561
TEST_TIME 28 11488
TEST_TIME 29 11307
TEST_TIME 30 11108
TEST_TIME 31 10934
TEST_TIME 32 10730
TEST_TIME 33 10523
TEST_TIME 34 10459
TEST_TIME 35 10278
TEST_TIME 36 10204
TEST_TIME 37 10096
TEST_TIME 38 9972
TEST_TIME 39 9908
TEST_TIME 40 10073
TEST_TIME 41 10077
TEST_TIME 42 9852
TEST_TIME 43 9786
TEST_TIME 44 9672
TEST_TIME 45 9673
TEST_TIME 46 9602
TEST_TIME 47 9669
TEST_TIME 48 9595
TEST_TIME 49 9555
TEST_TIME 50 9567
TEST_TIME 51 9556
TEST_TIME 52 9478
TEST_TIME 53 9445
TEST_TIME 54 9562
TEST_TIME 55 9612
TEST_TIME 56 9513
TEST_TIME 57 9390
TEST_TIME 58 9267
TEST_TIME 59 9232
TEST_TIME 60 9207
TEST_TIME 61 9117
TEST_TIME 62 9043
TEST_TIME 63 9142
TEST_TIME 64 9128
TEST_TIME 65 9138
TEST_TIME 66 9130
TEST_TIME 67 9167
TEST_TIME 68 9187
TEST_TIME 69 9163
TEST_TIME 70 9219
TEST_TIME 71 9245
TEST_TIME 72 9223
TEST_TIME 73 9180
TEST_TIME 74 9405
TEST_TIME 75 9331
TEST_TIME 76 9278
TEST_TIME 77 9307
TEST_TIME 78 9398
TEST_TIME 79 9424
TEST_TIME 80 9364
TEST_TIME 81 9304
TEST_TIME 82 9275
TEST_TIME 83 9223
TEST_TIME 84 9155
TEST_TIME 85 9133
TEST_TIME 86 9079
TEST_TIME 87 9022
TEST_TIME 88 9003
TEST_TIME 89 9074
TEST_TIME 90 9065
TEST_TIME 91 9040
TEST_TIME 92 9047
TEST_TIME 93 9138
TEST_TIME 94 9101
TEST_TIME 95 9098
TEST_TIME 96 9088
TEST_TIME 97 9119
TEST_TIME 98 9144
TEST_TIME 99 9156
TEST_TIME 100 9144
TEST_TIME 101 9110
TEST_TIME 102 9025
TEST_TIME 103 9080
TEST_TIME 104 9066
TEST_TIME 105 9009
TEST_TIME 106 8969
TEST_TIME 107 8977
TEST_TIME 108 8981
TEST_TIME 109 8960
TEST_TIME 110 8996
TEST_TIME 111 8961
TEST_TIME 112 8939
TEST_TIME 113 8962
TEST_TIME 114 8997
TEST_TIME 115 8985
TEST_TIME 116 8963
TEST_TIME 117 8973
TEST_TIME 118 9056
TEST_TIME 119 9052
TEST_TIME 120 9021

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-11-2021 13:37:56 to 01-11-2021 13: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 1503596 1503596 0 0.00 0 22831 8 0 0
doc_list_biud 1503596 1503596 0 0.00 0 107860 31 0 0
sp_add_invoice_to_stock 5001 4932 69 1.37 29 232438 19327 0 69
sp_cancel_adding_invoice 5522 5522 0 0.00 5 80286 823 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 9897 311 0 0
sp_cancel_customer_reserve 540899 540899 0 0.00 1 107863 68 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 2 7340 77 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 2361 74 0 0
sp_cancel_supplier_invoice 6136 6129 7 0.11 2 25141 1025 0 7
sp_cancel_supplier_order 397 397 0 0.00 7 1150263 117658 0 0
sp_cancel_write_off 9944 9944 0 0.00 1 10545 133 0 0
sp_client_order 19938 19938 0 0.00 4 25005 416 0 0
sp_customer_reserve 457280 457280 0 0.00 0 73007 191 0 0
sp_fill_shopping_cart 30429 30429 0 0.00 1 25102 56 0 0
sp_fill_shopping_cart_clo_res 457270 457270 0 0.00 0 17489 18 0 0
sp_get_clo_for_invoice 4922 4922 0 0.00 0 28968 491 0 0
sp_kill_cost_storno 16538 16538 0 0.00 0 7394 58 0 0
sp_kill_qstorno_ret_qs2qd 1098268 1098268 0 0.00 0 107837 37 0 0
sp_kill_qty_storno 579104 579104 0 0.00 0 107860 80 0 0
sp_lock_selected_doc 20131 20131 0 0.00 0 6189 5 0 0
sp_make_cost_storno 27180 27180 0 0.00 0 11788 59 0 0
sp_multiply_rows_for_pdistr 27180 27180 0 0.00 0 8781 33 0 0
sp_multiply_rows_for_qdistr 19938 19938 0 0.00 0 13380 203 0 0
sp_pay_from_customer 6983 6983 0 0.00 4 12794 110 0 0
sp_pay_to_supplier 5714 5714 0 0.00 5 21628 134 0 0
sp_payment_common 12697 12697 0 0.00 1 8796 23 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 9854 291 0 0
sp_qd_handle_on_invoice_upd_sts 10444 10444 0 0.00 0 16246 358 0 0
sp_qd_handle_on_reserve_upd_sts 9561 9561 0 0.00 0 11609 59 0 0
sp_reserve_write_off 9561 9561 0 0.00 6 14953 191 0 0
sp_supplier_invoice 5264 5264 0 0.00 7 69654 3613 0 0
sp_supplier_order 5240 5240 0 0.00 6 59598 1484 0 0
srv_aggregate_perf_data 108 108 0 0.00 2927 177124 13365 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2739 111998 44655 0 0
srv_find_qd_qs_mism 1034471 1034471 0 0.00 0 22828 7 0 0
srv_make_invnt_saldo 451 451 0 0.00 78 116328 3418 0 0
srv_make_money_saldo 714 714 0 0.00 0 13588 428 0 0
srv_recalc_idx_stat 5 5 0 0.00 171982 475399 303480 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4934 4934 0 0.00 0 10481 140 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 2164 66 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 4538 13 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3225 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 555 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 571 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 565 16 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 28914 1630 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 2059 7 0 0
t$perf-norm:sp_client_order 19938 19938 0 0.00 0 13690 15 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 857 6 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 13761 15 0 0
t$perf-norm:sp_pay_to_supplier 5714 5714 0 0.00 0 7265 7 0 0
t$perf-norm:sp_reserve_write_off 9561 9561 0 0.00 0 5082 9 0 0
t$perf-norm:sp_supplier_invoice 5264 5264 0 0.00 0 19501 23 0 0
t$perf-norm:sp_supplier_order 5240 5240 0 0.00 0 6140 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 182 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1310 21 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 1309 11 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 1857 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9974 9836 138 1.38 0 6732 15 0 138
v_all_customers 36630 36630 0 0.00 0 127 1 0 0
v_all_suppliers 29532 29532 0 0.00 0 120 1 0 0
v_all_wares 39876 39876 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 892 7 0 0
v_cancel_client_order 3460 3460 0 0.00 0 2536 4 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 1690 5 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 4209 12 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1013 7 0 0
v_cancel_supplier_invoice 814 800 14 1.71 0 2113 16 0 14
v_cancel_supplier_order 794 794 0 0.00 0 161 2 0 0
v_cancel_write_off 1938 1938 0 0.00 0 3579 16 0 0
v_max_id_clo_ord 5236 5236 0 0.00 0 181 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 2511 13 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 4078 6 0 0
v_max_non_paid_invoice 1439 1439 0 0.00 0 304 2 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 783 3 0 0
v_min_id_clo_ord 5236 5236 0 0.00 0 18242 8 0 0
v_min_id_clo_res 949 949 0 0.00 0 5459 20 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 3978 7 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 8789 18 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 7464 17 0 0
v_our_firm 9996 9996 0 0.00 0 18 1 0 0
v_reserve_write_off 19122 19122 0 0.00 0 4300 6 0 0
x_lock_depdocs_on_canc_invoice 11251 11251 0 0.00 0 9165 109 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 5865 340 0 0
x_make_qstorno_1000_1200 5240 5240 0 0.00 2 59582 1411 0 0
x_make_qstorno_1200_2000 5264 5264 0 0.00 3 67875 3374 0 0
x_make_qstorno_2100_3300 457280 457280 0 0.00 0 73006 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2021 13:38:00 to 01-11-2021 13: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
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 15777 8192000000 9715536128 10009939968 796167648 8.857 100 86 172 0 825034640 7898160 20968832
11:09:31 31400 8192000000 9770103168 10078236672 841839872 9.318 100 87 174 0 874420160 11604880 20975408
11:11:08 11527 8192000000 9795740416 10110701568 862290384 9.523 100 85 168 1 906144576 24431120 19423072
11:12:30 10426 8192000000 9871924240 10207662080 921941248 10.115 100 82 161 0 984968896 44882480 18145168
11:13:42 7893 8192000000 9925998416 10271686656 964429616 10.532 100 76 152 3 1036343824 55637568 16276640
11:14:54 3267 8192000000 9964932384 10325139456 1004335152 10.921 100 80 160 0 1084419168 63956704 16127312
11:15:58 2862 8192000000 10011955424 10374848512 1023289024 11.104 100 83 163 2 1127202688 88031984 15881680
11:17:02 1785 8192000000 10008015296 10376192000 1051085392 11.371 100 89 178 1 1122018304 51825536 19107376
11:18:06 1373 8192000000 10044192112 10417700864 1068759600 11.540 100 88 175 1 1153975824 67843456 17372768
11:19:08 7195 8192000000 9998511632 10383691776 1075147616 11.601 100 45 86 0 1112541968 28073008 9321344
11:20:15 5361 8192000000 9990468496 10383601664 1080326288 11.651 100 49 93 1 1110844544 19519536 10998720
11:21:23 61195 8192000000 10020175696 10411184128 1087728384 11.721 100 59 118 1 1119447184 17493536 14225264
11:23:29 5655 8192000000 10010599136 10412752896 1097009248 11.809 100 48 96 2 1123220336 15734848 10476240
11:24:37 5760 8192000000 10023798640 10419949568 1099690272 11.835 100 39 78 1 1131171040 22185600 9295168
11:25:45 5648 8192000000 10020146480 10424963072 1106351392 11.898 100 37 73 3 1129853056 14840864 8660800
11:26:51 30465 8192000000 9998273696 10406674432 1063194592 11.487 96 47 92 2 1091222304 17721072 10306640
11:28:23 3877 8192000000 9985565584 10370351104 1027935616 11.149 96 56 112 0 1060683152 20175408 12572128
11:29:28 10352 8192000000 9944867552 10330828800 994403776 10.824 96 35 69 3 1019676720 17009488 8263456
11:30:41 6483 8192000000 9926092016 10299699200 989193520 10.774 97 35 69 1 1021021824 24109200 7719104
11:31:50 23228 8192000000 9913989696 10307485696 979266944 10.677 96 23 46 0 1014307360 30657296 4383120
11:33:14 5875 8192000000 9967964768 10359226368 1045260432 11.315 100 50 99 2 1071804992 15313584 11230976
11:34:23 5007 8192000000 9978659680 10371743744 1045222688 11.315 99 27 54 1 1084301360 32843952 6234720
11:35:30 57229 8192000000 9993622064 10376196096 1068179264 11.535 100 48 96 1 1096268944 17554048 10535632
11:37:34 8567 8192000000 10019015344 10410463232 1087343808 11.717 100 49 99 2 1124223216 25749376 11130032
11:38:48 26305 8192000000 10062940080 10466480128 1104760880 11.883 100 49 95 1 1162282192 47818864 9702448
11:40:19 30678 8192000000 10088338656 10507403264 1124028032 12.065 100 37 70 1 1200091856 68570528 7493296
11:41:54 24822 8192000000 10136088592 10543964160 1141763488 12.232 100 42 82 2 1242121936 90822880 9535568
11:43:24 19420 8192000000 10184465488 10592022528 1150283616 12.312 100 54 108 1 1297600512 135200016 12116880
11:44:53 5447 8192000000 10241069424 10655051776 1162726064 12.429 100 62 120 2 1341186736 164774608 13686064
11:46:03 2845 8192000000 10276251712 10683752448 1175129104 12.545 100 72 143 0 1384300640 192431696 16739840
11:47:11 8906 8192000000 10316101680 10748915712 1180011728 12.590 100 69 138 0 1422428752 226621824 15795200
11:48:27 3208 8192000000 10359699376 10761117696 1190650016 12.689 100 86 170 1 1453975952 245557616 17768320
11:49:35 2736 8192000000 10416355824 10847408128 1207367584 12.845 100 72 144 0 1519548912 295895680 16285648
11:50:44 3041 8192000000 10466226320 10873274368 1210486992 12.874 100 90 180 1 1564247552 332950240 20810320
11:51:51 4187 8192000000 10488612384 10907697152 1184721888 12.634 99 70 140 0 1581473136 379867424 16883824
11:53:08 5428 8192000000 10566920864 10983522304 1195083200 12.731 100 69 137 0 1665503904 453444176 16976528
11:54:17 6963 8192000000 10562172144 10984599552 1179403936 12.585 98 66 132 1 1650371184 454496240 16471008
11:55:38 21643 8192000000 10437697744 10869075968 1145541984 12.268 98 30 60 2 1534379456 380764032 8073440
11:57:10 9194 8192000000 10435848368 10861912064 1164466448 12.445 100 36 69 2 1524367360 351403376 8497536
11:58:21 13614 8192000000 10445960000 10894970880 1146180048 12.274 99 32 64 0 1551036016 396220992 8634976
11:59:38 16017 8192000000 10511060672 10976350208 1127718192 12.100 98 35 70 3 1573648016 436594512 9335312
12:01:06 32261 8192000000 10546460000 10996015104 1140111760 12.217 100 29 58 0 1649120912 500938128 8071024
12:02:44 24886 8192000000 10226536160 10760957952 1124724096 12.072 100 23 45 1 1389125472 258977920 5423456
12:04:16 53466 8192000000 10057417856 10488049664 1090969696 11.752 96 32 64 1 1138448016 40396752 7081568
12:06:19 98725 8192000000 10049541360 10506063872 1096054528 11.800 98 27 56 0 1142030336 40118944 5856864
12:08:58 63079 8192000000 10046102560 10475831296 1069615712 11.548 96 26 52 0 1103450160 28891664 4942784
12:12:01 12516 8192000000 10037259056 10481049600 1101169232 11.849 100 27 54 0 1141568304 34663072 5736000
12:13:15 45900 8192000000 10120189152 10571395072 1123241904 12.058 100 18 36 0 1215665120 88411296 4011920
12:15:18 35589 8192000000 10171054688 10616762368 1115404288 11.984 99 31 62 0 1236066832 113542560 7119984
12:17:00 70405 8192000000 10055082496 10526556160 1108787616 11.921 99 9 17 1 1246097440 135142384 2167440
12:20:22 65253 8192000000 10064045296 10500571136 1102121440 11.858 98 20 40 1 1132237632 25433600 4682592
12:22:35 32044 8192000000 10047766400 10490400768 1123664784 12.062 100 20 40 0 1148533776 20672832 4196160
12:24:11 64743 8192000000 10056901056 10510995456 1109605520 11.929 98 12 24 0 1158140240 46175008 2359712
12:26:17 103024 8192000000 10084931312 10570194944 1132756912 12.147 99 15 30 0 1188534688 52319840 3457936
12:29:57 106495 8192000000 10191885120 10670931968 1151942768 12.328 100 22 44 0 1240293152 83775808 4574576
12:33:27 111998 8192000000 10092036688 10534449152 1155761152 12.364 100 37 74 0 1193232096 29902976 7567968
12:36:25 55840 8192000000 10105144784 10554376192 1139017456 12.206 99 24 48 0 1211471200 67724160 4729584
12:38:22 45271 8192000000 10117196512 10605391872 1139750976 12.213 99 9 17 0 1213373712 72133952 1488784
12:40:41 51680 8192000000 10063822800 10520969216 1108097328 11.914 97 10 20 0 1157680224 47855488 1727408
12:42:34 69288 8192000000 10103181536 10557435904 1136992320 12.187 99 13 26 2 1196952352 57534048 2425984
12:44:44 42608 8192000000 10051853264 10512445440 1089297040 11.736 95 23 46 0 1128301008 34427696 4576272
12:46:27 45684 8192000000 10092104720 10546900992 1114408224 11.974 98 23 45 0 1158161328 38642464 5110640
12:48:14 35800 8192000000 10049921808 10489380864 1071608096 11.567 95 15 30 0 1098335664 23684560 3043008
12:49:54 19286 8192000000 10092120752 10516086784 1125979584 12.083 100 54 108 1 1179408736 42177056 11252096
12:51:19 65841 8192000000 10080665424 10603884544 1134568192 12.164 100 15 30 0 1249238832 111058416 3612224
12:53:25 39330 8192000000 10138598688 10565054464 1083115248 11.677 96 31 62 0 1155197680 65902160 6180272
12:55:14 91332 8192000000 10056218864 10554900480 1081176080 11.659 97 13 26 0 1180299072 96335728 2787264
12:57:46 55234 8192000000 10061460496 10516869120 1110482816 11.937 100 19 38 1 1158622784 44523360 3616608
12:59:44 78599 8192000000 10040160464 10489647104 1091218944 11.754 98 10 20 0 1122256256 29034064 2003248
13:02:03 78209 8192000000 10078280464 10533744640 1104146624 11.877 98 22 44 1 1171854032 63069344 4638064
13:04:38 61348 8192000000 10088964656 10550427648 1094627328 11.787 98 15 30 0 1168605312 70761184 3216800
13:06:45 59737 8192000000 10060869280 10519359488 1091072544 11.753 98 11 22 1 1137336032 43888096 2375392
13:08:50 53500 8192000000 10095038128 10541424640 1084232928 11.688 97 34 68 0 1165882480 74440752 7208800
13:10:51 35371 8192000000 10055627616 10487058432 1103985792 11.875 99 31 58 2 1140290176 29707888 6596496
13:12:27 34330 8192000000 10088839184 10510565376 1095796336 11.798 98 34 68 1 1171439840 68757008 6886496
13:14:02 27831 8192000000 10111233536 10524696576 1130461904 12.126 100 51 102 0 1201696672 61537536 9697232
13:15:31 46774 8192000000 10111930368 10541002752 1101972992 11.856 97 33 66 0 1145539760 37276256 6290512
13:17:20 91560 8192000000 10135121296 10600861696 1144726576 12.260 100 21 42 2 1246041664 96285520 5029568
13:20:03 27721 8192000000 10130933200 10566033408 1139792784 12.214 100 31 61 1 1224829392 78388640 6647968
13:22:30 55935 8192000000 10093640592 10547314688 1141668784 12.231 100 12 25 0 1186433888 42249344 2515760
13:24:27 98480 8192000000 10056994208 10520993792 1117142064 12.000 98 17 32 1 1161692512 41364976 3185472
13:27:06 33245 8192000000 10124232752 10565480448 1143734288 12.251 100 31 62 0 1185178576 35322016 6122272
13:29:07 56284 8192000000 10122859104 10570514432 1129743280 12.119 99 30 60 1 1213204096 77523280 5937536
13:31:11 52581 8192000000 10085298272 10550861824 1133536944 12.155 99 20 39 0 1175664640 37895568 4232128
13:33:03 102073 8192000000 10138583776 10606858240 1138732416 12.204 98 24 48 1 1194959600 51308496 4918688
13:35:56 68965 8192000000 10103536400 10575515648 1113963024 11.970 97 11 22 0 1159242608 43199408 2080176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 01-11-2021 13:38:22 to 01-11-2021 13:38:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230894
MON$OLDEST_ACTIVE               232042
MON$OLDEST_SNAPSHOT             232042
MON$NEXT_TRANSACTION            232042
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                       2035296
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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 ---
:128: 
:129: mscs-test-161.budzdorov.ru    Mon Nov  1 13:37:22 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
--- end of diff output ---

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

01.11.21 13:38:24. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-01 13:38:27 No severe PSQL-related problems occured
01.11.21 13:38:28. End of report.