Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2021 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
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 17107408 kB
MemAvailable 31596492 kB
Buffers 0 kB
Cached 14543796 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-08 13:26:55
DTS_END                         2021-03-08 13:26:55.1510
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/33098
TRN_ID                          237414
ATT_ID                          694
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: 8733.38 20425 1048005
customer order: creation 175.64 436 21077
customer order: refuse 14.95 328 1794
order to supplier: creation 45.32 1382 5438
order to supplier: removal 3.21 121677 385
invoice (draft): creation 45.88 3676 5505
invoice (draft): removal 48.11 976 5773
invoice accept: apply 40.78 17938 4894
invoice accept: cancel 43.48 910 5217
customer reserve: creation 3775.24 176 453029
customer reserve: removal 4248.73 74 509847
realization accept: apply 83.38 152 10006
realization accept: cancel 78.45 112 9414
payment from customer: creation 61.02 110 7322
payment from customer: removal 5.66 66 679
payment to supplier: creation 49.39 123 5927
payment to supplier: removal 3.40 67 408
service: total inventory turnovers 3.81 3151 457
service: total monetary turnovers 5.91 561 709
service: aggregate perf. data 1.00 13276 120
service: refresh index statistics 0.03 243307 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2021 13:28:03 to 08-03-2021 13:28:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2362
TEST_TIME 2 3519
TEST_TIME 3 3761
TEST_TIME 4 5168
TEST_TIME 5 4686
TEST_TIME 6 4669
TEST_TIME 7 4845
TEST_TIME 8 5172
TEST_TIME 9 5279
TEST_TIME 10 5707
TEST_TIME 11 5864
TEST_TIME 12 6165
TEST_TIME 13 6226
TEST_TIME 14 6328
TEST_TIME 15 6521
TEST_TIME 16 6576
TEST_TIME 17 6735
TEST_TIME 18 7091
TEST_TIME 19 7708
TEST_TIME 20 7934
TEST_TIME 21 8184
TEST_TIME 22 8411
TEST_TIME 23 8272
TEST_TIME 24 8685
TEST_TIME 25 8844
TEST_TIME 26 9110
TEST_TIME 27 9356
TEST_TIME 28 10393
TEST_TIME 29 10372
TEST_TIME 30 10416
TEST_TIME 31 10380
TEST_TIME 32 10086
TEST_TIME 33 9904
TEST_TIME 34 9760
TEST_TIME 35 9688
TEST_TIME 36 9632
TEST_TIME 37 9608
TEST_TIME 38 9593
TEST_TIME 39 9480
TEST_TIME 40 9439
TEST_TIME 41 9316
TEST_TIME 42 9213
TEST_TIME 43 9209
TEST_TIME 44 9152
TEST_TIME 45 9152
TEST_TIME 46 9117
TEST_TIME 47 9118
TEST_TIME 48 9090
TEST_TIME 49 9141
TEST_TIME 50 9049
TEST_TIME 51 9231
TEST_TIME 52 9210
TEST_TIME 53 9224
TEST_TIME 54 9267
TEST_TIME 55 9351
TEST_TIME 56 9310
TEST_TIME 57 9293
TEST_TIME 58 9211
TEST_TIME 59 9130
TEST_TIME 60 9066
TEST_TIME 61 8957
TEST_TIME 62 8950
TEST_TIME 63 9047
TEST_TIME 64 9079
TEST_TIME 65 9081
TEST_TIME 66 9037
TEST_TIME 67 9005
TEST_TIME 68 9092
TEST_TIME 69 9166
TEST_TIME 70 9143
TEST_TIME 71 9172
TEST_TIME 72 9158
TEST_TIME 73 9338
TEST_TIME 74 9293
TEST_TIME 75 9296
TEST_TIME 76 9218
TEST_TIME 77 9133
TEST_TIME 78 9101
TEST_TIME 79 9060
TEST_TIME 80 9009
TEST_TIME 81 8991
TEST_TIME 82 8950
TEST_TIME 83 8977
TEST_TIME 84 8963
TEST_TIME 85 8960
TEST_TIME 86 8987
TEST_TIME 87 9000
TEST_TIME 88 8977
TEST_TIME 89 9074
TEST_TIME 90 9031
TEST_TIME 91 9054
TEST_TIME 92 9028
TEST_TIME 93 8948
TEST_TIME 94 8931
TEST_TIME 95 8908
TEST_TIME 96 8880
TEST_TIME 97 8847
TEST_TIME 98 8812
TEST_TIME 99 8808
TEST_TIME 100 8814
TEST_TIME 101 8842
TEST_TIME 102 8816
TEST_TIME 103 8815
TEST_TIME 104 8818
TEST_TIME 105 8881
TEST_TIME 106 8869
TEST_TIME 107 8855
TEST_TIME 108 8933
TEST_TIME 109 8851
TEST_TIME 110 8797
TEST_TIME 111 8770
TEST_TIME 112 8721
TEST_TIME 113 8735
TEST_TIME 114 8728
TEST_TIME 115 8730
TEST_TIME 116 8718
TEST_TIME 117 8692
TEST_TIME 118 8688
TEST_TIME 119 8704
TEST_TIME 120 8736

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-03-2021 13:28:07 to 08-03-2021 13:28:10.

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 1468585 1468585 0 0.00 0 24149 7 0 0
doc_list_biud 1468558 1468558 0 0.00 0 29739 32 0 0
sp_add_invoice_to_stock 5059 4894 165 3.26 18 171513 17939 0 165
sp_cancel_adding_invoice 5217 5217 0 0.00 5 67371 911 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 12891 329 0 0
sp_cancel_customer_reserve 509847 509847 0 0.00 1 29740 74 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 2831 66 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 6082 68 0 0
sp_cancel_supplier_invoice 5784 5773 11 0.19 2 13993 976 0 11
sp_cancel_supplier_order 385 385 0 0.00 6 975790 121677 0 0
sp_cancel_write_off 9414 9414 0 0.00 1 8523 112 0 0
sp_client_order 21077 21077 0 0.00 4 47268 437 0 0
sp_customer_reserve 453029 453029 0 0.00 0 152346 176 0 0
sp_fill_shopping_cart 32014 32014 0 0.00 1 20677 47 0 0
sp_fill_shopping_cart_clo_res 453029 453029 0 0.00 0 25647 17 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 44766 430 0 0
sp_kill_cost_storno 15718 15718 0 0.00 0 8949 56 0 0
sp_kill_qstorno_ret_qs2qd 1035266 1035266 0 0.00 0 28822 40 0 0
sp_kill_qty_storno 547315 547315 0 0.00 0 29739 85 0 0
sp_lock_selected_doc 20612 20612 0 0.00 0 6211 3 0 0
sp_make_cost_storno 28146 28146 0 0.00 0 24140 47 0 0
sp_multiply_rows_for_pdistr 28131 28131 0 0.00 0 10894 31 0 0
sp_multiply_rows_for_qdistr 21076 21076 0 0.00 0 19325 263 0 0
sp_pay_from_customer 7322 7322 0 0.00 4 9799 110 0 0
sp_pay_to_supplier 5927 5927 0 0.00 5 18466 124 0 0
sp_payment_common 13236 13236 0 0.00 1 7270 19 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 12883 304 0 0
sp_qd_handle_on_invoice_upd_sts 10103 10103 0 0.00 0 22289 360 0 0
sp_qd_handle_on_reserve_upd_sts 9999 9999 0 0.00 0 14772 50 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 14808 152 0 0
sp_supplier_invoice 5505 5505 0 0.00 8 93895 3677 0 0
sp_supplier_order 5438 5438 0 0.00 5 42328 1382 0 0
srv_aggregate_perf_data 120 120 0 0.00 2767 212018 13277 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1516 140973 46676 0 0
srv_find_qd_qs_mism 1000780 1000780 0 0.00 0 14457 6 0 0
srv_make_invnt_saldo 457 457 0 0.00 49 114289 3152 0 0
srv_make_money_saldo 709 709 0 0.00 1 15910 561 0 0
srv_recalc_idx_stat 4 4 0 0.00 114889 364587 243308 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 0 18278 165 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 3743 85 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 13994 23 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 11881 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 3290 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 3795 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 844 14 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 19870 1467 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 4786 14 0 0
t$perf-norm:sp_client_order 21077 21077 0 0.00 0 9158 18 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 767 6 0 0
t$perf-norm:sp_pay_from_customer 7322 7322 0 0.00 0 5481 6 0 0
t$perf-norm:sp_pay_to_supplier 5927 5927 0 0.00 0 3856 8 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 6417 9 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 10555 23 0 0
t$perf-norm:sp_supplier_order 5438 5438 0 0.00 0 12172 22 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 277 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 3868 25 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 3304 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10100 9770 330 3.26 0 3330 15 0 330
v_all_customers 38434 38434 0 0.00 0 18 1 0 0
v_all_suppliers 30484 30484 0 0.00 0 15 1 0 0
v_all_wares 42152 42152 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 561 6 0 0
v_cancel_client_order 3588 3588 0 0.00 0 3015 4 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 1569 5 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2341 6 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2135 6 0 0
v_cancel_supplier_invoice 778 756 22 2.82 0 3526 22 0 22
v_cancel_supplier_order 770 770 0 0.00 0 253 4 0 0
v_cancel_write_off 1944 1944 0 0.00 0 7580 16 0 0
v_max_id_clo_ord 5437 5437 0 0.00 0 821 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2939 15 0 0
v_max_id_ord_sup 5500 5500 0 0.00 0 7054 6 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 235 2 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 1181 3 0 0
v_min_id_clo_ord 5437 5437 0 0.00 0 1605 5 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 8135 23 0 0
v_min_id_ord_sup 5500 5500 0 0.00 0 3561 7 0 0
v_min_non_paid_invoice 5923 5923 0 0.00 0 6247 12 0 0
v_min_non_paid_realizn 7313 7313 0 0.00 0 5297 14 0 0
v_our_firm 10476 10476 0 0.00 0 24 1 0 0
v_reserve_write_off 19996 19996 0 0.00 0 2742 5 0 0
x_lock_depdocs_on_canc_invoice 10612 10612 0 0.00 0 10359 109 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 2355 285 0 0
x_make_qstorno_1000_1200 5438 5438 0 0.00 2 42282 1314 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 3 73215 3478 0 0
x_make_qstorno_2100_3300 453029 453029 0 0.00 0 152334 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2021 13:28:11 to 08-03-2021 13:28:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:58 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:00 16592 8192000000 9700993280 9991065600 780034752 8.694 100 82 164 0 807181136 7185056 19961328
10:59:27 22716 8192000000 9754348336 10056654848 825566576 9.155 100 93 186 0 860225312 12317072 22341664
11:01:02 12628 8192000000 9809585504 10124378112 860892000 9.509 100 76 152 0 912091904 34058304 17141600
11:02:23 12148 8192000000 9872488960 10207047680 923322720 10.129 100 81 161 2 981570464 40542576 17705168
11:03:38 7796 8192000000 9925413520 10268409856 959041696 10.480 100 79 158 0 1033388464 57901568 16445200
11:04:50 4431 8192000000 9964196448 10321178624 1000966288 10.888 100 77 154 1 1077886336 61671040 15249008
11:05:56 2194 8192000000 9984554224 10347364352 1029819808 11.167 100 74 144 0 1096582512 52208864 14553840
11:07:00 1918 8192000000 10036869376 10403770368 1051777696 11.378 100 88 176 1 1148831872 78605072 18449104
11:08:04 3837 8192000000 10027128352 10405765120 1064940896 11.504 100 72 144 0 1137579184 58252128 14386160
11:09:08 6821 8192000000 9979476192 10367361024 1068326448 11.536 100 49 94 1 1108376528 29843776 10206304
11:10:16 9699 8192000000 9997734352 10399428608 1079865024 11.646 100 38 77 0 1124611504 37024576 7721904
11:11:30 61217 8192000000 9997304752 10410803200 1083840880 11.684 100 28 55 1 1117085424 27255936 5988608
11:13:32 2924 8192000000 10045630752 10437771264 1093924864 11.780 100 62 127 0 1154259152 46108320 14225968
11:14:36 5559 8192000000 10018530592 10416029696 1093316256 11.774 100 41 84 2 1126595888 23769008 9510624
11:15:42 6443 8192000000 10024354800 10425004032 1098865504 11.827 100 28 55 3 1141496272 36964160 5666608
11:16:51 56059 8192000000 10005208704 10420572160 1067991552 11.533 98 36 72 1 1102639872 26815072 7833248
11:18:48 6494 8192000000 9976251840 10372071424 1041535760 11.279 97 40 81 2 1068645472 18087424 9022288
11:19:57 15342 8192000000 9928216080 10325159936 992388752 10.805 96 26 52 3 1023099264 24717184 5993328
11:21:13 9942 8192000000 9931998896 10328379392 1028120816 11.150 100 27 48 4 1049937952 16427680 5389456
11:22:23 49825 8192000000 9937409152 10344452096 1010965440 10.985 98 28 56 2 1033595408 16392160 6237808
11:24:13 5043 8192000000 9956646192 10347421696 1035512352 11.222 99 55 109 1 1069886480 22737680 11636448
11:25:21 9880 8192000000 9962797536 10361798656 1059832176 11.455 100 44 87 1 1092122128 21788288 10501664
11:26:31 4160 8192000000 9999676832 10390847488 1086375616 11.708 100 46 94 2 1106132016 8459552 11296848
11:27:38 21503 8192000000 10030699136 10423320576 1097666976 11.815 100 54 108 1 1134569248 25461344 11440928
11:29:07 1831 8192000000 10072104112 10469601280 1108487504 11.918 100 62 125 0 1180794032 60222048 12084480
11:30:17 6693 8192000000 10100861600 10505625600 1122135520 12.047 100 58 114 2 1215213184 79413760 13663904
11:31:31 5503 8192000000 10123062288 10531356672 1144390112 12.257 100 58 116 1 1231464928 74150112 12924704
11:32:43 14834 8192000000 10153769008 10561118208 1152488544 12.333 100 56 112 1 1263911104 99027088 12395472
11:34:03 2206 8192000000 10183514464 10592636928 1162206320 12.424 100 61 120 1 1290682592 115817552 12658720
11:35:14 25391 8192000000 10233204928 10644340736 1163233248 12.434 100 61 122 0 1337948176 160628960 14085968
11:36:45 17367 8192000000 10218252304 10650566656 1163883424 12.440 99 48 94 2 1311494672 136421888 11189360
11:38:08 2430 8192000000 10235405920 10643902464 1155879760 12.365 99 75 149 0 1331507024 159626608 16000656
11:39:13 1515 8192000000 10286861712 10694451200 1174579264 12.540 100 81 163 1 1394643088 202082704 17981120
11:40:21 2238 8192000000 10326289264 10730500096 1179430096 12.585 100 83 165 1 1417572816 219925168 18217552
11:41:28 5791 8192000000 10304022480 10720665600 1167598304 12.474 100 58 117 1 1417511952 236526496 13387152
11:42:39 4117 8192000000 10367613424 10791911424 1175068992 12.544 100 63 124 1 1476315376 286596560 14649824
11:43:54 5129 8192000000 10364436512 10791776256 1156295328 12.369 99 61 121 0 1462038864 290571216 15172320
11:45:04 3405 8192000000 10388123840 10797842432 1175390224 12.547 100 75 149 1 1490259968 296889760 17979984
11:46:10 10092 8192000000 10333608912 10778931200 1154945568 12.356 99 47 94 1 1430737904 263938384 11853952
11:47:28 16504 8192000000 10329829920 10746605568 1123614352 12.061 97 39 78 1 1410992288 277166928 10211008
11:48:58 9587 8192000000 10348734608 10779598848 1147735456 12.288 100 35 70 1 1449275072 292377040 9162576
11:50:10 14639 8192000000 10420715040 10853318656 1137341104 12.191 99 34 68 1 1509442016 362722768 9378144
11:51:28 29089 8192000000 10343032928 10823540736 1104679424 11.882 97 22 44 0 1430617376 319720416 6217536
11:53:22 21071 8192000000 10245755536 10753490944 1095336768 11.793 99 20 40 0 1380658528 280099584 5222176
11:54:55 47223 8192000000 10070289888 10512506880 1070216592 11.554 96 36 71 1 1131831264 54183072 7431600
11:56:50 90719 8192000000 10018706448 10442039296 1067440496 11.528 96 21 42 0 1103199104 31923584 3835024
11:59:21 72491 8192000000 10063753376 10498039808 1103459936 11.870 99 16 32 0 1137569616 31057680 3052000
12:01:39 41071 8192000000 10082767264 10537373696 1124548624 12.070 100 19 38 1 1194672176 65602464 4521088
12:03:22 140973 8192000000 10066237872 10529329152 1112735344 11.958 98 14 28 2 1183265408 67609200 2920864
12:06:43 44297 8192000000 10083864096 10524610560 1127492576 12.098 99 27 54 0 1170627024 37663904 5470544
12:08:31 49870 8192000000 10098856896 10583732224 1142411808 12.238 100 28 56 1 1218274496 69626864 6235824
12:10:34 75712 8192000000 10071757936 10538483712 1130747344 12.128 99 10 20 1 1154881680 22237456 1896880
12:12:59 48224 8192000000 10068352464 10507575296 1117755840 12.006 98 17 34 1 1154829824 33903952 3170032
12:14:47 57028 8192000000 10121086432 10592686080 1137194480 12.189 99 21 42 0 1211366416 70032272 4139664
12:16:48 55413 8192000000 10140286432 10585403392 1145106032 12.264 100 17 34 1 1232842016 83338544 4397440
12:18:44 74437 8192000000 10150029152 10612244480 1148698640 12.297 99 17 34 0 1211724208 59359968 3665600
12:21:52 25051 8192000000 10131103680 10562011136 1147018784 12.282 100 42 84 0 1217536256 60829040 9688432
12:23:27 102863 8192000000 10074130512 10532257792 1128431328 12.107 98 19 37 0 1166594496 34276912 3886256
12:26:11 81644 8192000000 10108943072 10558783488 1127940768 12.102 98 16 33 1 1214999280 83214400 3844112
12:28:37 65560 8192000000 10106248032 10641522688 1127316992 12.096 99 11 23 2 1220528816 91009584 2202240
12:30:50 45631 8192000000 10064055984 10515574784 1113688576 11.967 99 29 58 3 1167841744 48016784 6136384
12:32:57 62025 8192000000 10128177152 10573475840 1124846208 12.073 98 25 50 0 1215552976 85190752 5516016
12:35:28 63325 8192000000 10092200464 10525949952 1102686544 11.863 97 25 49 1 1165621584 58071728 4863312
12:37:33 30110 8192000000 10097649984 10528432128 1123142160 12.057 100 35 68 2 1172621840 42830320 6649360
12:39:07 77382 8192000000 10043416656 10512785408 1057134128 11.429 95 15 30 1 1131921248 71855088 2932032
12:41:26 49165 8192000000 10060615248 10486345728 1084874704 11.694 98 34 66 3 1133110976 41958064 6278208
12:43:16 83612 8192000000 10042694192 10498736128 1107011328 11.904 100 28 56 1 1138170352 25226384 5932640
12:45:50 42835 8192000000 10042251808 10489024512 1106094336 11.895 99 5 10 0 1136429568 29289472 1045760
12:47:34 53972 8192000000 10063769152 10516647936 1105389936 11.889 99 6 12 0 1139492544 33095568 1007040
12:49:29 82461 8192000000 10104149952 10536394752 1086944048 11.714 96 25 50 0 1160704160 68850448 4909664
12:51:55 66446 8192000000 10071201200 10495717376 1075357056 11.603 96 21 42 0 1113276896 33054176 4865664
12:54:08 53179 8192000000 10117100880 10557161472 1121292720 12.039 99 16 32 2 1187828848 63088928 3447200
12:56:14 51721 8192000000 10069847232 10493521920 1102185760 11.858 97 32 64 0 1140856640 32031056 6639824
12:58:07 122039 8192000000 10095269152 10549932032 1130406160 12.125 99 31 62 1 1186014016 49027952 6579904
13:01:46 91501 8192000000 10097787776 10594652160 1134661680 12.165 99 13 25 0 1199506016 61900736 2943600
13:04:18 60351 8192000000 10116136496 10573594624 1148434320 12.295 100 20 39 1 1221352608 68947744 3970544
13:06:19 83673 8192000000 10116517424 10584928256 1121728816 12.043 97 15 30 1 1177969136 52920960 3319360
13:08:43 53132 8192000000 10110744192 10559971328 1107594688 11.910 97 27 54 1 1168153360 54908720 5649952
13:10:37 42751 8192000000 10135750416 10581827584 1121427856 12.040 98 43 87 0 1212361200 82324768 8608576
13:12:28 7939 8192000000 10071747280 10509590528 1117666176 12.005 99 48 95 1 1147453744 19976720 9810848
13:13:38 105492 8192000000 10094293040 10613653504 1117190832 12.000 98 13 26 0 1172647808 53093520 2363456
13:16:55 70737 8192000000 10063388448 10533154816 1120594496 12.033 99 7 14 0 1158944128 37066032 1283600
13:19:39 57133 8192000000 10072863776 10527264768 1103324592 11.869 97 16 32 1 1154065952 47355920 3385440
13:21:37 63419 8192000000 10057840288 10499649536 1113037456 11.961 98 18 36 1 1138454000 21970848 3445696
13:23:44 38900 8192000000 10107886944 10551668736 1084322112 11.689 96 26 52 1 1169183488 79228720 5632656
13:25:39 88057 8192000000 9371847552 9764093952 482643344 5.563 43 10 18 1 546965616 62040784 2281488

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-03-2021 13:28:17 to 08-03-2021 13:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2021 13:28:33 to 08-03-2021 13:28:35.

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          237453
MON$OLDEST_ACTIVE               237710
MON$OLDEST_SNAPSHOT             237710
MON$NEXT_TRANSACTION            237710
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                       2080352
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:229: 
:230: mscs-test-161.budzdorov.ru    Mon Mar  8 13:27:16 2021
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:232: 
:233: 
:234: mscs-test-161.budzdorov.ru    Mon Mar  8 13:27:25 2021
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:236: 
:237: 
:238: mscs-test-161.budzdorov.ru    Mon Mar  8 13:27:25 2021
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:240: 
--- end of diff output ---

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

08.03.21 13:28:36. 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-03-08 13:28:38 No severe PSQL-related problems occured
08.03.21 13:28:40. End of report.