Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 22:38
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 15910652 kB
MemAvailable 30881912 kB
Buffers 0 kB
Cached 15759052 kB
SwapCached 340 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-09-04 22:37:48
DTS_END                         2021-09-04 22:37:48.4530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40750
TRN_ID                          253323
ATT_ID                          665
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: 9440.54 21790 1132865
customer order: creation 190.63 352 22875
customer order: refuse 15.78 350 1894
order to supplier: creation 50.49 1202 6059
order to supplier: removal 3.39 95770 407
invoice (draft): creation 50.05 3148 6006
invoice (draft): removal 51.78 761 6213
invoice accept: apply 45.33 16811 5440
invoice accept: cancel 47.11 770 5653
customer reserve: creation 4054.24 172 486509
customer reserve: removal 4612.14 55 553457
realization accept: apply 92.58 163 11110
realization accept: cancel 85.71 94 10285
payment from customer: creation 64.57 108 7748
payment from customer: removal 6.09 84 731
payment to supplier: creation 53.48 114 6417
payment to supplier: removal 3.88 63 466
service: total inventory turnovers 5.33 1086 640
service: total monetary turnovers 7.01 27 841
service: aggregate perf. data 0.92 21855 110
service: refresh index statistics 0.03 292818 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-09-2021 22:38:54 to 04-09-2021 22:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4425
TEST_TIME 2 4319
TEST_TIME 3 4783
TEST_TIME 4 4800
TEST_TIME 5 4979
TEST_TIME 6 4964
TEST_TIME 7 5407
TEST_TIME 8 5752
TEST_TIME 9 6110
TEST_TIME 10 6295
TEST_TIME 11 6392
TEST_TIME 12 6730
TEST_TIME 13 7056
TEST_TIME 14 7453
TEST_TIME 15 8041
TEST_TIME 16 8126
TEST_TIME 17 8170
TEST_TIME 18 8373
TEST_TIME 19 8422
TEST_TIME 20 8504
TEST_TIME 21 8992
TEST_TIME 22 9321
TEST_TIME 23 9417
TEST_TIME 24 9441
TEST_TIME 25 10097
TEST_TIME 26 10958
TEST_TIME 27 10884
TEST_TIME 28 11000
TEST_TIME 29 10895
TEST_TIME 30 10830
TEST_TIME 31 10861
TEST_TIME 32 10767
TEST_TIME 33 10645
TEST_TIME 34 10534
TEST_TIME 35 10474
TEST_TIME 36 10312
TEST_TIME 37 10298
TEST_TIME 38 10324
TEST_TIME 39 10328
TEST_TIME 40 10220
TEST_TIME 41 10250
TEST_TIME 42 10229
TEST_TIME 43 10295
TEST_TIME 44 10296
TEST_TIME 45 10327
TEST_TIME 46 10474
TEST_TIME 47 10394
TEST_TIME 48 10235
TEST_TIME 49 10146
TEST_TIME 50 10164
TEST_TIME 51 10109
TEST_TIME 52 9987
TEST_TIME 53 9924
TEST_TIME 54 9855
TEST_TIME 55 9744
TEST_TIME 56 9796
TEST_TIME 57 9950
TEST_TIME 58 10014
TEST_TIME 59 9931
TEST_TIME 60 9946
TEST_TIME 61 9814
TEST_TIME 62 9739
TEST_TIME 63 9745
TEST_TIME 64 9680
TEST_TIME 65 9772
TEST_TIME 66 9775
TEST_TIME 67 9736
TEST_TIME 68 9703
TEST_TIME 69 9822
TEST_TIME 70 9854
TEST_TIME 71 9848
TEST_TIME 72 9797
TEST_TIME 73 9858
TEST_TIME 74 9858
TEST_TIME 75 9903
TEST_TIME 76 9911
TEST_TIME 77 9885
TEST_TIME 78 9869
TEST_TIME 79 9857
TEST_TIME 80 9846
TEST_TIME 81 9842
TEST_TIME 82 9777
TEST_TIME 83 9749
TEST_TIME 84 9685
TEST_TIME 85 9706
TEST_TIME 86 9711
TEST_TIME 87 9689
TEST_TIME 88 9665
TEST_TIME 89 9614
TEST_TIME 90 9542
TEST_TIME 91 9538
TEST_TIME 92 9521
TEST_TIME 93 9517
TEST_TIME 94 9492
TEST_TIME 95 9521
TEST_TIME 96 9510
TEST_TIME 97 9566
TEST_TIME 98 9511
TEST_TIME 99 9590
TEST_TIME 100 9615
TEST_TIME 101 9594
TEST_TIME 102 9577
TEST_TIME 103 9608
TEST_TIME 104 9612
TEST_TIME 105 9652
TEST_TIME 106 9689
TEST_TIME 107 9693
TEST_TIME 108 9646
TEST_TIME 109 9588
TEST_TIME 110 9591
TEST_TIME 111 9536
TEST_TIME 112 9505
TEST_TIME 113 9452
TEST_TIME 114 9492
TEST_TIME 115 9455
TEST_TIME 116 9476
TEST_TIME 117 9457
TEST_TIME 118 9451
TEST_TIME 119 9483
TEST_TIME 120 9444

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-09-2021 22:38:57 to 04-09-2021 22:39:01.

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 1585394 1585394 0 0.00 0 15964 6 0 0
doc_list_biud 1585394 1585394 0 0.00 0 35351 25 0 0
sp_add_invoice_to_stock 5572 5440 132 2.36 32 154842 16811 0 132
sp_cancel_adding_invoice 5653 5653 0 0.00 5 50385 771 0 0
sp_cancel_client_order 1894 1894 0 0.00 5 12091 351 0 0
sp_cancel_customer_reserve 553457 553457 0 0.00 1 18747 55 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 6557 84 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 2230 64 0 0
sp_cancel_supplier_invoice 6228 6213 15 0.24 1 35317 762 0 15
sp_cancel_supplier_order 407 407 0 0.00 5 1170026 95770 0 0
sp_cancel_write_off 10285 10285 0 0.00 1 6679 94 0 0
sp_client_order 22875 22875 0 0.00 3 65199 352 0 0
sp_customer_reserve 486509 486509 0 0.00 0 67725 172 0 0
sp_fill_shopping_cart 34940 34940 0 0.00 1 18035 40 0 0
sp_fill_shopping_cart_clo_res 486506 486506 0 0.00 0 18233 13 0 0
sp_get_clo_for_invoice 5424 5424 0 0.00 0 18299 261 0 0
sp_kill_cost_storno 17135 17135 0 0.00 0 12867 55 0 0
sp_kill_qstorno_ret_qs2qd 1123819 1123819 0 0.00 0 35345 30 0 0
sp_kill_qty_storno 594441 594441 0 0.00 0 35349 65 0 0
sp_lock_selected_doc 22790 22790 0 0.00 0 9061 5 0 0
sp_make_cost_storno 30697 30697 0 0.00 0 15731 49 0 0
sp_multiply_rows_for_pdistr 30697 30697 0 0.00 0 6344 29 0 0
sp_multiply_rows_for_qdistr 22875 22875 0 0.00 0 65188 150 0 0
sp_pay_from_customer 7748 7748 0 0.00 4 19022 109 0 0
sp_pay_to_supplier 6417 6417 0 0.00 5 11769 115 0 0
sp_payment_common 14165 14165 0 0.00 1 19017 27 0 0
sp_qd_handle_on_cancel_clo 1894 1894 0 0.00 0 12085 325 0 0
sp_qd_handle_on_invoice_upd_sts 11075 11075 0 0.00 0 18864 302 0 0
sp_qd_handle_on_reserve_upd_sts 11110 11110 0 0.00 0 18714 58 0 0
sp_reserve_write_off 11110 11110 0 0.00 6 18739 163 0 0
sp_supplier_invoice 6006 6006 0 0.00 5 80450 3149 0 0
sp_supplier_order 6059 6059 0 0.00 5 38040 1202 0 0
srv_aggregate_perf_data 110 110 0 0.00 2838 404826 21855 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1555 138445 44045 0 0
srv_find_qd_qs_mism 1082238 1082238 0 0.00 0 15961 5 0 0
srv_make_invnt_saldo 640 640 0 0.00 31 45265 1087 0 0
srv_make_money_saldo 841 841 0 0.00 0 616 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 166783 415822 292818 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5440 5440 0 0.00 0 26376 196 0 0
t$perf-norm:sp_cancel_adding_invoice 474 474 0 0.00 0 25140 228 0 0
t$perf-norm:sp_cancel_client_order 1894 1894 0 0.00 0 1346 11 0 0
t$perf-norm:sp_cancel_customer_reserve 773 773 0 0.00 0 2322 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 3517 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 613 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 4247 27 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 20825 1632 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 10836 19 0 0
t$perf-norm:sp_client_order 22875 22875 0 0.00 0 10714 16 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 5748 16 0 0
t$perf-norm:sp_pay_from_customer 7748 7748 0 0.00 0 10716 12 0 0
t$perf-norm:sp_pay_to_supplier 6417 6417 0 0.00 0 6500 11 0 0
t$perf-norm:sp_reserve_write_off 11110 11110 0 0.00 0 6947 11 0 0
t$perf-norm:sp_supplier_invoice 6006 6006 0 0.00 0 8260 21 0 0
t$perf-norm:sp_supplier_order 6059 6059 0 0.00 0 6884 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 178 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 159 6 0 0
t$perf-norm:srv_make_invnt_saldo 640 640 0 0.00 0 1014 18 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 1790 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 70 20 0 0
v_add_invoice_to_stock 11108 10844 264 2.37 0 7386 19 0 264
v_all_customers 41220 41220 0 0.00 0 261 1 0 0
v_all_suppliers 33330 33330 0 0.00 0 21 1 0 0
v_all_wares 45750 45750 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 948 948 0 0.00 0 1298 8 0 0
v_cancel_client_order 3788 3788 0 0.00 0 2995 5 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 5316 15 0 0
v_cancel_customer_reserve 1546 1546 0 0.00 0 901 4 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 397 4 0 0
v_cancel_supplier_invoice 900 870 30 3.33 0 2924 22 0 30
v_cancel_supplier_order 814 814 0 0.00 0 997 4 0 0
v_cancel_write_off 2156 2156 0 0.00 0 3131 13 0 0
v_max_id_clo_ord 6059 6059 0 0.00 0 527 1 0 0
v_max_id_clo_res 1095 1095 0 0.00 0 2388 13 0 0
v_max_id_ord_sup 6006 6006 0 0.00 0 6404 4 0 0
v_max_non_paid_invoice 1817 1817 0 0.00 0 3227 5 0 0
v_max_non_paid_realizn 4246 4246 0 0.00 0 2971 5 0 0
v_min_id_clo_ord 6059 6059 0 0.00 0 3843 6 0 0
v_min_id_clo_res 1095 1095 0 0.00 0 9244 24 0 0
v_min_id_ord_sup 6006 6006 0 0.00 0 6843 7 0 0
v_min_non_paid_invoice 6417 6417 0 0.00 0 10618 12 0 0
v_min_non_paid_realizn 7748 7748 0 0.00 0 8231 12 0 0
v_our_firm 11534 11534 0 0.00 0 14 1 0 0
v_reserve_write_off 22220 22220 0 0.00 0 5666 5 0 0
x_lock_depdocs_on_canc_invoice 11431 11431 0 0.00 0 32114 121 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 11464 363 0 0
x_make_qstorno_1000_1200 6059 6059 0 0.00 2 37494 1117 0 0
x_make_qstorno_1200_2000 6006 6006 0 0.00 2 80437 3008 0 0
x_make_qstorno_2100_3300 486509 486509 0 0.00 0 67724 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-09-2021 22:39:02 to 04-09-2021 22:39:06.

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:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 26797 8192000000 10374163760 10694328320 1420664224 14.779 100 93 186 0 1456955280 6184384 30106672
20:10:27 19740 8192000000 10437397376 10768793600 1488951104 15.380 100 85 170 1 1524406704 8399472 27056128
20:11:50 17694 8192000000 10490351616 10831921152 1545070432 15.867 100 86 172 0 1581530896 10137344 26323120
20:13:10 14350 8192000000 10560669856 10915225600 1615893984 16.475 100 79 158 0 1650101552 10961552 23246016
20:14:32 9185 8192000000 10642933088 11011747840 1703996848 17.219 100 81 162 0 1739774800 12960880 22817072
20:15:44 6666 8192000000 10702306304 11078582272 1763338672 17.712 100 82 162 1 1800179232 13806816 23033744
20:16:53 9434 8192000000 10721821856 11111514112 1795746128 17.979 100 78 156 1 1831733392 14422736 21564528
20:18:05 2918 8192000000 10799714256 11186532352 1855163824 18.464 100 88 178 1 1893810048 14548384 24097840
20:19:10 20016 8192000000 10805950768 11209363456 1873823008 18.615 100 65 130 2 1901537776 9323344 18391424
20:20:33 10066 8192000000 10779054912 11207962624 1875888704 18.632 100 30 60 0 1891239840 7312736 8038400
20:21:45 10208 8192000000 10798001152 11229736960 1896492640 18.798 100 28 56 2 1911317280 6712576 8112064
20:22:55 12565 8192000000 10824039584 11243524096 1901619712 18.839 100 14 28 0 1910865648 5151680 4094256
20:24:08 49835 8192000000 10870451456 11270443008 1918693680 18.976 100 81 166 2 1949309760 5832736 24783344
20:25:58 3549 8192000000 10840536448 11263909888 1911852208 18.922 100 37 74 2 1933458896 11232016 10374672
20:27:03 5797 8192000000 10822012944 11248332800 1886407696 18.717 99 33 66 0 1903223520 7541936 9273888
20:28:09 45991 8192000000 10791121232 11226763264 1842389888 18.360 97 30 61 0 1856677248 5697536 8589824
20:29:57 7645 8192000000 10740500784 11155296256 1781604400 17.863 97 25 49 3 1795556400 6107520 7844480
20:31:07 6984 8192000000 10706669904 11115929600 1737142864 17.495 96 33 63 5 1751727152 4547600 10036688
20:32:21 57526 8192000000 10703260656 11120050176 1765943040 17.734 98 23 46 1 1778229568 4817552 7468976
20:34:19 3958 8192000000 10737525824 11157127168 1821507552 18.190 100 30 60 0 1836275936 6382432 8385952
20:35:23 4451 8192000000 10775837328 11196846080 1851933552 18.438 100 32 65 1 1868241664 6758928 9549184
20:36:32 14165 8192000000 10783517072 11209687040 1869108896 18.577 100 19 37 2 1880288576 5776560 5403120
20:37:46 30019 8192000000 10832752400 11245260800 1902950656 18.850 100 51 102 2 1923195792 6088400 14156736
20:39:19 6891 8192000000 10870516448 11284484096 1934389680 19.102 100 54 107 1 1959212768 9397872 15425216
20:40:32 20851 8192000000 10877484736 11304820736 1950973088 19.234 100 53 105 0 1977316736 11688944 14654704
20:42:03 29230 8192000000 10894965216 11330318336 1965729312 19.352 100 31 62 0 1988020112 13090688 9200112
20:43:41 2896 8192000000 10942252288 11360337920 1987886544 19.527 100 74 148 1 2027583568 17735744 21961280
20:44:48 29736 8192000000 10933126160 11367366656 1985755472 19.510 100 39 78 1 2019220944 21414096 12051376
20:46:23 8027 8192000000 10945238288 11372933120 1990990288 19.552 100 53 106 2 2031336720 24620448 15725984
20:47:36 1553 8192000000 10976145232 11390615552 2013190400 19.727 100 84 164 1 2061232784 22057312 25985072
20:48:43 3945 8192000000 10953542592 11378745344 1993363216 19.570 100 71 142 0 2043473440 28614096 21496128
20:49:53 4524 8192000000 10935953056 11372150784 1980045264 19.465 100 60 120 1 2031156016 32483584 18627168
20:51:04 1977 8192000000 10936883808 11350224896 1961904400 19.321 99 89 178 3 2020857328 31832272 27120656
20:52:12 7673 8192000000 10945788672 11367919616 1978850416 19.456 100 63 126 1 2026524160 29184336 18489408
20:53:31 4342 8192000000 10958691408 11379523584 1987025504 19.520 100 82 159 1 2039184336 29453168 22705664
20:54:39 2656 8192000000 10946394272 11359776768 1960094720 19.307 99 78 152 0 2014773696 32133248 22545728
20:55:49 4195 8192000000 10972308224 11387322368 1971729488 19.399 99 76 150 2 2033335344 38347440 23258416
20:56:58 1959 8192000000 10952714416 11369652224 1966771440 19.360 100 83 162 1 2031161552 38004624 26385488
20:58:04 1782 8192000000 10963028176 11375521792 1976371504 19.436 100 86 173 3 2045929280 41691408 27866368
20:59:09 11758 8192000000 10939907216 11384377344 1970347040 19.388 100 40 79 1 2027001712 43649488 13005184
21:00:28 16983 8192000000 10911230496 11349409792 1949574608 19.223 100 30 60 0 1998987024 39622480 9789936
21:01:47 14506 8192000000 10911643584 11358183424 1920090416 18.988 99 35 70 0 1982196912 50003168 12103328
21:03:05 23676 8192000000 10895444336 11363995648 1943486160 19.175 100 17 34 1 1991873808 42323248 6064400
21:04:33 29730 8192000000 10878558064 11329236992 1901642016 18.839 98 21 42 0 1925399760 16902640 6855104
21:06:03 41412 8192000000 10887853904 11347763200 1948727120 19.216 100 25 51 0 1970916016 14582640 7606256
21:07:50 57877 8192000000 10899116752 11352678400 1953005712 19.250 99 38 74 2 1975569024 11451024 11112288
21:10:14 54358 8192000000 10899712880 11356913664 1949366112 19.221 99 7 14 0 1962826240 12088784 1371344
21:12:12 57638 8192000000 10902330016 11364757504 1957269984 19.284 99 13 26 0 1969347392 8376544 3700864
21:14:15 16665 8192000000 10924781440 11377778688 1976279248 19.435 99 54 108 0 2002020720 9826464 15915008
21:15:32 92141 8192000000 10918437936 11389857792 1953628992 19.255 98 8 16 0 1966591616 10834464 2128160
21:18:10 132570 8192000000 10937160288 11401662464 1962854464 19.329 98 6 12 0 1976006128 11581392 1570272
21:21:29 111749 8192000000 10935828432 11416936448 1961360960 19.317 98 6 12 0 1982592144 19472576 1758608
21:24:22 124230 8192000000 10942954992 11392851968 1982689248 19.486 99 28 52 2 2004171056 13307504 8174304
21:27:27 38904 8192000000 10921903072 11368304640 1913544192 18.935 96 45 90 0 1936803808 10863968 12395648
21:29:07 25994 8192000000 10876333488 11340664832 1916012608 18.955 98 35 70 1 1937485360 11476128 9996624
21:30:40 31469 8192000000 10867309024 11336155136 1894917040 18.785 97 20 40 2 1919341632 18427904 5996688
21:32:12 41838 8192000000 10930521680 11376631808 1968594208 19.374 100 43 86 1 2004909312 22788816 13526288
21:34:01 51275 8192000000 10888228496 11383033856 1936845680 19.122 99 16 31 1 1965558928 23532928 5180320
21:35:52 33762 8192000000 10874835072 11355680768 1940061360 19.147 99 8 16 2 1960058608 17630288 2366960
21:37:28 83996 8192000000 10865121504 11334062080 1897473232 18.806 98 10 20 0 1906086208 5813584 2799392
21:39:52 35161 8192000000 10857529392 11327909888 1901223728 18.836 98 9 18 0 1922025440 18268176 2533536
21:41:27 77638 8192000000 10872998160 11346296832 1904185360 18.860 97 15 30 0 1924850960 16423184 4242416
21:43:45 27034 8192000000 10868470176 11346407424 1905478560 18.870 97 24 45 2 1920894976 9288384 6128032
21:45:22 17237 8192000000 10894321344 11349528576 1949384528 19.222 100 53 107 0 1980535920 16419216 14732176
21:46:40 78025 8192000000 10904485504 11386933248 1961463984 19.318 100 15 30 1 1984346400 18079552 4802864
21:48:59 87444 8192000000 10869959904 11347771392 1866839648 18.559 95 17 34 1 1881410256 9753504 4817104
21:51:27 106306 8192000000 10894223152 11364491264 1933668160 19.096 99 11 22 0 1951113504 14688464 2756880
21:54:14 26269 8192000000 10888545760 11344904192 1932458752 19.087 99 43 86 2 1955108192 10533552 12115888
21:55:43 138439 8192000000 10881579376 11364118528 1941263728 19.157 100 17 34 0 1972665696 25871872 5530096
21:59:08 21418 8192000000 10901597008 11354259456 1960473536 19.310 100 34 68 3 1978804720 8139040 10192144
22:00:37 59092 8192000000 10901309392 11369246720 1938493776 19.135 98 34 68 1 1959435360 11445104 9496480
22:02:45 48890 8192000000 10902985280 11383930880 1970302816 19.388 100 15 29 2 1987831200 13060240 4468144
22:04:37 37420 8192000000 10902047376 11383058432 1978553776 19.453 100 10 18 0 1989809344 8458016 2797552
22:06:19 68643 8192000000 10895670592 11378716672 1978137744 19.450 100 13 26 0 1990953888 9661776 3154368
22:08:28 57161 8192000000 10879358592 11369017344 1949829536 19.225 99 10 20 1 1964920576 12526032 2565008
22:10:27 77908 8192000000 10893209616 11380076544 1923229008 19.013 97 15 30 0 1938590400 11503792 3857600
22:12:46 50820 8192000000 10894666944 11371937792 1898339888 18.813 96 9 18 0 1912086112 11609680 2136544
22:14:44 32348 8192000000 10888370064 11352690688 1879816880 18.664 95 17 34 2 1893167824 7957520 5393424
22:16:19 99261 8192000000 10871233248 11366076416 1877250944 18.643 96 23 46 0 1900900496 17137248 6512304
22:19:05 60031 8192000000 10890915648 11366596608 1924944272 19.026 98 21 40 0 1944984208 14700688 5339248
22:21:23 43812 8192000000 10869935088 11336908800 1899018000 18.818 97 27 54 1 1923660816 17655440 6987376
22:23:12 115317 8192000000 10874086384 11346268160 1877638336 18.646 96 18 36 1 1907470992 23991680 5840976
22:27:35 39062 8192000000 10835084896 11317481472 1919641152 18.984 99 6 12 1 1927574704 6596144 1337408
22:29:29 73883 8192000000 10850474768 11326967808 1909519552 18.903 99 11 22 0 1924324880 11629232 3176096
22:31:44 70048 8192000000 10848955040 11310829568 1852598000 18.443 96 16 32 0 1868839744 11615136 4626608
22:33:55 32505 8192000000 10847539136 11309912064 1884189104 18.699 98 39 76 1 1902554848 7578688 10787056
22:35:29 43869 8192000000 10821665824 11288858624 1841796976 18.355 96 25 50 3 1861266688 12357056 7112656
22:37:27 27101 8192000000 9765555392 10123087872 903148176 9.929 46 23 46 0 917810288 8208624 6453488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-09-2021 22:39:08 to 04-09-2021 22:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

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          253540
MON$OLDEST_ACTIVE               253541
MON$OLDEST_SNAPSHOT             253541
MON$NEXT_TRANSACTION            253541
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                       2057952
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:00 2021
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:01 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:02 2021
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:04 2021
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:07 2021
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Sat Sep  4 22:38:11 2021
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:175: 
--- end of diff output ---

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

04.09.21 22:39:27. 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-09-04 22:39:29 No severe PSQL-related problems occured
04.09.21 22:39:30. End of report.