Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 22:39
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 16658644 kB
MemAvailable 31014608 kB
Buffers 0 kB
Cached 15044552 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-16 22:37:44
DTS_END                         2021-08-16 22:37:44.2820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51228
TRN_ID                          238095
ATT_ID                          708
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: 9600.28 23557 1152034
customer order: creation 174.30 331 20916
customer order: refuse 14.73 378 1767
order to supplier: creation 46.60 1267 5592
order to supplier: removal 3.51 103862 421
invoice (draft): creation 45.60 3327 5472
invoice (draft): removal 55.31 760 6637
invoice accept: apply 41.96 18434 5035
invoice accept: cancel 50.67 764 6080
customer reserve: creation 3910.28 181 469234
customer reserve: removal 4954.27 58 594512
realization accept: apply 85.78 183 10294
realization accept: cancel 87.04 116 10445
payment from customer: creation 58.92 122 7070
payment from customer: removal 5.81 104 697
payment to supplier: creation 49.29 145 5915
payment to supplier: removal 3.60 102 432
service: total inventory turnovers 4.90 1087 588
service: total monetary turnovers 6.81 45 817
service: aggregate perf. data 0.89 19023 107
service: refresh index statistics 0.03 320854 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 22:39:50 to 16-08-2021 22:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8233
TEST_TIME 2 5606
TEST_TIME 3 4840
TEST_TIME 4 5351
TEST_TIME 5 4914
TEST_TIME 6 5916
TEST_TIME 7 6264
TEST_TIME 8 6411
TEST_TIME 9 6407
TEST_TIME 10 6696
TEST_TIME 11 6891
TEST_TIME 12 7079
TEST_TIME 13 7132
TEST_TIME 14 7194
TEST_TIME 15 7207
TEST_TIME 16 7330
TEST_TIME 17 7359
TEST_TIME 18 7574
TEST_TIME 19 7800
TEST_TIME 20 8152
TEST_TIME 21 8715
TEST_TIME 22 8713
TEST_TIME 23 9016
TEST_TIME 24 9095
TEST_TIME 25 9232
TEST_TIME 26 9205
TEST_TIME 27 9653
TEST_TIME 28 9912
TEST_TIME 29 10762
TEST_TIME 30 10594
TEST_TIME 31 10411
TEST_TIME 32 10544
TEST_TIME 33 10543
TEST_TIME 34 10515
TEST_TIME 35 10619
TEST_TIME 36 10500
TEST_TIME 37 10389
TEST_TIME 38 10316
TEST_TIME 39 10113
TEST_TIME 40 10111
TEST_TIME 41 10121
TEST_TIME 42 10139
TEST_TIME 43 10071
TEST_TIME 44 10014
TEST_TIME 45 9948
TEST_TIME 46 9866
TEST_TIME 47 10083
TEST_TIME 48 9992
TEST_TIME 49 9931
TEST_TIME 50 9819
TEST_TIME 51 9706
TEST_TIME 52 9742
TEST_TIME 53 9719
TEST_TIME 54 9746
TEST_TIME 55 9732
TEST_TIME 56 9715
TEST_TIME 57 9703
TEST_TIME 58 9718
TEST_TIME 59 9665
TEST_TIME 60 9658
TEST_TIME 61 9964
TEST_TIME 62 9906
TEST_TIME 63 9930
TEST_TIME 64 10066
TEST_TIME 65 10028
TEST_TIME 66 10027
TEST_TIME 67 10184
TEST_TIME 68 10169
TEST_TIME 69 10055
TEST_TIME 70 10024
TEST_TIME 71 9965
TEST_TIME 72 9925
TEST_TIME 73 9927
TEST_TIME 74 9877
TEST_TIME 75 9883
TEST_TIME 76 9917
TEST_TIME 77 9850
TEST_TIME 78 9795
TEST_TIME 79 9708
TEST_TIME 80 9707
TEST_TIME 81 9664
TEST_TIME 82 9627
TEST_TIME 83 9612
TEST_TIME 84 9608
TEST_TIME 85 9570
TEST_TIME 86 9585
TEST_TIME 87 9548
TEST_TIME 88 9656
TEST_TIME 89 9655
TEST_TIME 90 9696
TEST_TIME 91 9715
TEST_TIME 92 9735
TEST_TIME 93 9760
TEST_TIME 94 9799
TEST_TIME 95 9805
TEST_TIME 96 9798
TEST_TIME 97 9750
TEST_TIME 98 9702
TEST_TIME 99 9663
TEST_TIME 100 9658
TEST_TIME 101 9661
TEST_TIME 102 9626
TEST_TIME 103 9593
TEST_TIME 104 9580
TEST_TIME 105 9530
TEST_TIME 106 9541
TEST_TIME 107 9521
TEST_TIME 108 9517
TEST_TIME 109 9493
TEST_TIME 110 9547
TEST_TIME 111 9556
TEST_TIME 112 9546
TEST_TIME 113 9550
TEST_TIME 114 9552
TEST_TIME 115 9510
TEST_TIME 116 9565
TEST_TIME 117 9632
TEST_TIME 118 9648
TEST_TIME 119 9628
TEST_TIME 120 9603

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2021 22:39:54 to 16-08-2021 22:39:57.

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 1587968 1587968 0 0.00 0 16055 6 0 0
doc_list_biud 1587965 1587965 0 0.00 0 30670 27 0 0
sp_add_invoice_to_stock 5293 5035 258 4.87 33 173393 18434 0 258
sp_cancel_adding_invoice 6080 6080 0 0.00 5 63837 765 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 11083 378 0 0
sp_cancel_customer_reserve 594512 594512 0 0.00 1 30673 58 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 6715 105 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 6917 103 0 0
sp_cancel_supplier_invoice 6658 6637 21 0.31 2 19630 761 0 21
sp_cancel_supplier_order 421 421 0 0.00 10 909894 103862 0 0
sp_cancel_write_off 10445 10445 0 0.00 1 14407 116 0 0
sp_client_order 20916 20916 0 0.00 4 18761 332 0 0
sp_customer_reserve 469234 469234 0 0.00 0 94639 182 0 0
sp_fill_shopping_cart 31951 31951 0 0.00 1 10461 36 0 0
sp_fill_shopping_cart_clo_res 469224 469224 0 0.00 0 11352 13 0 0
sp_get_clo_for_invoice 5025 5025 0 0.00 0 24891 259 0 0
sp_kill_cost_storno 17654 17654 0 0.00 0 14385 59 0 0
sp_kill_qstorno_ret_qs2qd 1206527 1206527 0 0.00 0 30663 31 0 0
sp_kill_qty_storno 635176 635176 0 0.00 0 30670 67 0 0
sp_lock_selected_doc 21104 21104 0 0.00 0 7523 5 0 0
sp_make_cost_storno 28301 28301 0 0.00 0 16043 56 0 0
sp_multiply_rows_for_pdistr 28299 28299 0 0.00 0 9440 34 0 0
sp_multiply_rows_for_qdistr 20916 20916 0 0.00 0 13022 151 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 11062 122 0 0
sp_pay_to_supplier 5915 5915 0 0.00 4 19839 146 0 0
sp_payment_common 12985 12985 0 0.00 1 19826 39 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 8963 346 0 0
sp_qd_handle_on_invoice_upd_sts 11100 11100 0 0.00 0 15286 312 0 0
sp_qd_handle_on_reserve_upd_sts 10294 10294 0 0.00 0 12069 52 0 0
sp_reserve_write_off 10294 10294 0 0.00 6 13466 184 0 0
sp_supplier_invoice 5472 5472 0 0.00 7 106987 3327 0 0
sp_supplier_order 5592 5592 0 0.00 6 34737 1268 0 0
srv_aggregate_perf_data 107 107 0 0.00 2899 192661 19024 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1730 140257 42199 0 0
srv_find_qd_qs_mism 1104530 1104530 0 0.00 0 10264 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 53 33463 1087 0 0
srv_make_money_saldo 817 817 0 0.00 0 9082 45 0 0
srv_recalc_idx_stat 3 3 0 0.00 128649 502188 320854 0 0
t$perf-abend:sp_add_invoice_to_stock 258 258 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5035 5035 0 0.00 0 12827 169 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 2188 91 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 6225 23 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1284 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 700 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1948 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 932 18 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 33125 1494 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 3275 15 0 0
t$perf-norm:sp_client_order 20916 20916 0 0.00 0 5526 16 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 3347 7 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 3808 9 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 5872 11 0 0
t$perf-norm:sp_reserve_write_off 10294 10294 0 0.00 0 8916 12 0 0
t$perf-norm:sp_supplier_invoice 5472 5472 0 0.00 0 3481 20 0 0
t$perf-norm:sp_supplier_order 5592 5592 0 0.00 0 9419 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 173 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1587 41 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 1272 19 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 757 13 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 18 8 0 0
v_add_invoice_to_stock 10552 10036 516 4.89 0 4711 20 0 516
v_all_customers 37740 37740 0 0.00 0 98 1 0 0
v_all_suppliers 30666 30666 0 0.00 0 53 1 0 0
v_all_wares 41822 41822 0 0.00 0 188 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 622 8 0 0
v_cancel_client_order 3534 3534 0 0.00 0 10780 9 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 4144 10 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 2219 8 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2483 10 0 0
v_cancel_supplier_invoice 846 804 42 4.96 0 3131 21 0 42
v_cancel_supplier_order 842 842 0 0.00 0 1526 6 0 0
v_cancel_write_off 1958 1958 0 0.00 0 5104 23 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 2957 2 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 3234 14 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 4296 4 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 1715 4 0 0
v_max_non_paid_realizn 3845 3845 0 0.00 0 813 3 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 6561 10 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 7954 15 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 5368 8 0 0
v_min_non_paid_invoice 5915 5915 0 0.00 0 19821 29 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 11052 28 0 0
v_our_firm 10532 10532 0 0.00 0 15 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 10850 10 0 0
x_lock_depdocs_on_canc_invoice 12315 12315 0 0.00 0 12742 112 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 10000 383 0 0
x_make_qstorno_1000_1200 5592 5592 0 0.00 2 34721 1179 0 0
x_make_qstorno_1200_2000 5472 5472 0 0.00 3 101607 3208 0 0
x_make_qstorno_2100_3300 469234 469234 0 0.00 0 94635 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 22:39:58 to 16-08-2021 22:40:02.

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
20:07:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:48 20219 8192000000 10348388096 10663895040 1407558352 14.662 100 88 176 0 1442521376 5869824 29093200
20:10:14 12791 8192000000 10412950304 10737242112 1468640880 15.202 100 88 176 0 1505643664 8462528 28540256
20:11:41 18044 8192000000 10493656768 10837884928 1541448560 15.836 100 79 158 1 1576044496 9775056 24820880
20:13:07 11673 8192000000 10591392272 10949304320 1638029536 16.663 100 77 154 0 1672270032 11395632 22844864
20:14:20 6951 8192000000 10636249776 11008733184 1699613616 17.182 100 72 142 1 1731917952 12022560 20281776
20:15:29 4079 8192000000 10698942592 11078328320 1762581472 17.706 100 77 154 0 1795345712 11232288 21531952
20:16:36 4142 8192000000 10747894624 11124613120 1800250368 18.016 100 89 180 1 1840781184 15617024 24913792
20:17:42 3132 8192000000 10779264832 11160088576 1833821312 18.290 100 83 164 0 1870222224 13184464 23216448
20:18:47 2135 8192000000 10806863712 11191533568 1861966864 18.519 100 96 191 2 1902576016 13918208 26690944
20:19:52 1981 8192000000 10818437296 11208773632 1873611168 18.613 100 96 191 5 1913513808 12688096 27214544
20:20:58 4831 8192000000 10826887744 11220140032 1885928352 18.713 100 86 172 1 1924455968 14161184 24366432
20:22:05 7288 8192000000 10811203632 11226861568 1884747232 18.703 100 23 47 0 1897792736 6243168 6802336
20:23:13 9673 8192000000 10798608704 11227832320 1892953680 18.770 100 27 55 0 1907154176 6710816 7489680
20:24:24 5622 8192000000 10805153248 11239370752 1901454880 18.838 100 26 52 2 1915781344 6688704 7637760
20:25:33 36544 8192000000 10839981568 11248050176 1891455216 18.758 99 67 134 1 1917290496 6310448 19524832
20:27:11 4202 8192000000 10836497200 11264716800 1913537744 18.935 100 49 98 0 1939980496 12616784 13825968
20:28:18 20451 8192000000 10779333456 11205783552 1812474816 18.116 96 44 85 0 1832420560 7617760 12327984
20:29:45 5849 8192000000 10751039648 11176714240 1816064736 18.146 98 29 57 0 1829901664 6365424 7471504
20:30:53 10021 8192000000 10739538672 11169378304 1792036016 17.949 97 14 28 1 1801589968 5479056 4074896
20:32:05 29703 8192000000 10728164672 11134296064 1789740864 17.930 98 70 140 1 1817904784 5754448 22409472
20:33:37 6562 8192000000 10728307568 11144568832 1798504400 18.002 99 37 73 2 1815602544 5149600 11948544
20:34:45 5376 8192000000 10755656624 11169148928 1835836608 18.307 100 29 58 3 1849220032 5284480 8098944
20:35:52 47169 8192000000 10776274528 11197927424 1859923040 18.503 100 48 97 3 1880665056 5071344 15670672
20:37:45 10189 8192000000 10831586656 11245801472 1899313680 18.821 100 53 104 0 1921078272 6086304 15678288
20:39:03 6401 8192000000 10860498144 11278204928 1921847680 19.002 100 50 99 1 1945114016 9033536 14232800
20:40:13 18033 8192000000 10878572800 11302641664 1941185744 19.156 100 56 112 1 1968165200 11460160 15519296
20:42:41 16413 8192000000 10894886368 11327799296 1954526544 19.263 100 47 93 2 1984093808 15703120 13864144
20:44:01 3852 8192000000 10915170592 11348008960 1972944656 19.409 100 57 114 1 2009282112 18743984 17593472
20:45:13 3649 8192000000 10938192144 11354103808 1980579408 19.469 100 77 155 1 2023085216 19864736 22641072
20:46:21 8330 8192000000 10946621120 11377143808 1999362288 19.618 100 59 115 1 2039257600 22752528 17142784
20:47:35 3374 8192000000 10941938624 11366457344 1975067136 19.426 99 67 134 1 2021262864 26056864 20138864
20:48:43 3903 8192000000 10961326640 11380224000 1996656016 19.596 100 81 161 2 2051895040 29974768 25264256
20:49:50 4051 8192000000 10986890384 11402907648 1998233184 19.609 99 88 175 4 2056005824 31895008 25877632
20:51:00 4253 8192000000 10972534448 11404357632 1999275456 19.617 99 59 117 0 2049023440 31718688 18029296
20:52:09 3391 8192000000 10977804992 11397971968 2005324880 19.665 100 74 147 0 2061792496 34194000 22273616
20:53:16 4858 8192000000 10974186640 11402862592 2000567088 19.627 100 70 138 0 2060475600 37860720 22047792
20:54:28 3646 8192000000 10978423984 11396513792 1988775120 19.534 100 86 169 0 2060316784 45547424 25994240
20:55:39 1728 8192000000 10951904752 11367886848 1955654848 19.271 99 84 164 1 2033996528 53949200 24392480
20:56:46 4663 8192000000 10964135024 11379740672 1969312688 19.380 100 71 142 0 2043087136 50686080 23088368
20:57:55 5005 8192000000 10969284784 11396739072 1979266944 19.459 100 54 108 0 2044434384 47479328 17688112
20:59:08 9015 8192000000 10937223264 11371098112 1964068080 19.338 100 35 70 3 2019645392 43826592 11750720
21:00:26 13744 8192000000 10953421136 11401035776 1978202320 19.450 100 33 66 2 2038968256 48830288 11935648
21:01:46 14849 8192000000 10961815776 11410096128 1991600720 19.556 100 25 48 0 2037707536 38315344 7791472
21:03:06 29159 8192000000 10935151776 11392806912 1958034384 19.290 99 21 42 0 2009654528 44152880 7467264
21:04:39 24155 8192000000 10915643904 11370446848 1950163728 19.228 100 18 36 1 2004827808 48223840 6440240
21:06:25 62706 8192000000 10889405136 11346636800 1884791264 18.704 96 17 35 1 1919140800 27978448 6371088
21:08:28 48438 8192000000 10919013952 11381448704 1979606224 19.462 100 26 52 0 2003077984 16177616 7294144
21:11:03 93676 8192000000 10884582640 11366064128 1904996672 18.866 97 20 39 3 1927272656 16611696 5664288
21:13:44 53798 8192000000 10898418912 11341414400 1953075184 19.251 99 28 56 1 1972923760 12118576 7730000
21:16:07 46866 8192000000 10937954784 11382415360 1963285552 19.332 99 56 110 2 1993799552 14408128 16105872
21:17:58 18111 8192000000 10928087600 11381526528 1977353712 19.444 100 58 116 0 2014281408 20306656 16621040
21:19:23 35542 8192000000 10904518608 11385184256 1975673600 19.430 100 17 34 0 1995434384 15482976 4277808
21:21:05 26135 8192000000 10910166960 11378614272 1956167792 19.276 98 15 30 6 1974583936 13954768 4461376
21:22:33 43784 8192000000 10899708384 11357282304 1943764304 19.177 98 32 61 4 1971905344 18712976 9428064
21:24:19 26614 8192000000 10919529248 11396751360 1982562752 19.485 100 20 40 0 2011546416 23251184 5732480
21:25:48 37963 8192000000 10916631488 11395518464 1992467120 19.563 100 17 34 0 2008884848 11271296 5146432
21:27:26 123665 8192000000 10922999776 11403681792 1978524208 19.453 99 17 34 0 2006811536 23288768 4998560
21:30:34 50186 8192000000 10921557888 11402633216 1967281504 19.364 99 20 41 0 1996943280 23539808 6121968
21:32:30 48706 8192000000 10927253040 11407216640 1964280336 19.340 99 13 25 2 1982044608 13623136 4141136
21:34:21 86480 8192000000 10930629776 11401486336 1950231664 19.228 98 19 38 0 1976851056 20850624 5768768
21:36:53 54921 8192000000 10941653200 11417628672 2008491040 19.690 100 24 48 0 2034779904 18853616 7435248
21:39:02 48528 8192000000 10943667488 11402534912 1980647056 19.470 99 30 60 0 2001335488 12661408 8027024
21:41:02 42835 8192000000 10910456848 11397390336 1947439088 19.206 98 12 24 0 1971233984 20811280 2983616
21:42:51 48855 8192000000 10906574288 11379245056 1944285728 19.181 99 9 18 1 1970455792 23526176 2643888
21:44:41 93131 8192000000 10870795072 11315425280 1770616096 17.772 90 25 50 0 1787466688 9827184 7023408
21:47:20 42108 8192000000 10883984288 11319894016 1869752688 18.582 96 37 74 2 1889388496 9618928 10016880
21:49:05 42943 8192000000 10861619440 11328622592 1890158064 18.747 97 37 74 0 1915542496 14773968 10610464
21:51:21 58044 8192000000 10861815584 11332014080 1912826112 18.929 99 17 34 3 1935312352 17483472 5002768
21:53:32 44824 8192000000 10828258176 11309461504 1899974576 18.826 99 1 2 0 1909489056 9452352 62128
21:55:18 71288 8192000000 10855274128 11331899392 1936431056 19.118 100 13 26 0 1948461728 7985328 4045344
21:57:54 40548 8192000000 10850454000 11346735104 1936523040 19.119 100 8 16 0 1949611952 10971152 2117760
22:00:27 70345 8192000000 10872008704 11351375872 1940946384 19.154 99 20 40 1 1955091824 8344464 5800976
22:02:40 28052 8192000000 10877802400 11341721600 1895978624 18.794 97 26 52 2 1912930880 10186928 6765328
22:04:08 44236 8192000000 10886927664 11352358912 1918019376 18.971 98 23 45 0 1936491136 11994144 6477616
22:05:59 89444 8192000000 10862160464 11341549568 1878850080 18.656 96 18 36 0 1902822480 19325344 4647056
22:08:31 112603 8192000000 10893983648 11359129600 1920583904 18.992 98 14 28 0 1938592944 14332432 3676608
22:11:24 63032 8192000000 10892629952 11345633280 1907819264 18.889 97 28 56 1 1925001680 9776432 7405984
22:13:32 111704 8192000000 10876270816 11346968576 1949384656 19.222 100 27 54 1 1964358624 8003584 6970384
22:16:24 57300 8192000000 10881986992 11365552128 1956027648 19.274 100 16 32 2 1971734576 11226048 4480880
22:18:24 44395 8192000000 10859779360 11331264512 1933636608 19.096 98 17 34 2 1949352992 11309808 4406576
22:20:09 41590 8192000000 10875496912 11349929984 1913545280 18.935 97 11 22 1 1923443584 6504448 3393856
22:21:50 140257 8192000000 10895499040 11391221760 1979769296 19.463 100 22 43 2 2001674640 15005664 6899680
22:25:11 52577 8192000000 10914079856 11390205952 1981420896 19.476 100 8 16 0 1991628752 8195712 2012144
22:27:04 40771 8192000000 10888012432 11366772736 1906838112 18.881 97 20 40 1 1927375968 15195104 5342752
22:28:46 69847 8192000000 10854626944 11339272192 1842961696 18.365 94 10 20 0 1859335680 13561840 2812144
22:31:00 78734 8192000000 10877211952 11346837504 1909610528 18.904 98 22 44 0 1933232800 17361088 6261184
22:34:15 89215 8192000000 10870908544 11353247744 1877724224 18.647 96 15 30 1 1898487936 16764032 3999680
22:37:35 21402 8192000000 9622948032 9951109120 758445504 8.473 39 12 19 2 772553856 10931792 3176560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-08-2021 22:40:04 to 16-08-2021 22:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 258 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2021 22:40:20 to 16-08-2021 22:40:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237210
MON$OLDEST_ACTIVE               238542
MON$OLDEST_SNAPSHOT             238542
MON$NEXT_TRANSACTION            238542
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2050528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:364: 
:365: mscs-test-161.budzdorov.ru    Mon Aug 16 22:38:07 2021
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:367: 
--- end of diff output ---

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

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