Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2021 19: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 16290460 kB
MemAvailable 30973652 kB
Buffers 0 kB
Cached 15357528 kB
SwapCached 2576 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-13 19:37:47
DTS_END                         2021-08-13 19:37:47.2500
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(57:17)
IP                              ::1/57912
TRN_ID                          239505
ATT_ID                          676
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: 9059.75 24124 1087170
customer order: creation 168.65 353 20238
customer order: refuse 14.47 385 1736
order to supplier: creation 44.99 1327 5399
order to supplier: removal 3.29 96257 395
invoice (draft): creation 44.98 3580 5398
invoice (draft): removal 51.28 769 6154
invoice accept: apply 42.17 18675 5060
invoice accept: cancel 46.31 857 5557
customer reserve: creation 3771.98 190 452637
customer reserve: removal 4580.82 55 549698
realization accept: apply 81.61 194 9793
realization accept: cancel 80.83 108 9699
payment from customer: creation 58.55 153 7026
payment from customer: removal 5.65 54 678
payment to supplier: creation 48.67 140 5840
payment to supplier: removal 3.38 75 405
service: total inventory turnovers 4.78 1242 574
service: total monetary turnovers 6.52 81 782
service: aggregate perf. data 0.81 21562 97
service: refresh index statistics 0.03 336423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-08-2021 19:38:24 to 13-08-2021 19:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1561
TEST_TIME 2 3204
TEST_TIME 3 4273
TEST_TIME 4 4365
TEST_TIME 5 4319
TEST_TIME 6 4594
TEST_TIME 7 4989
TEST_TIME 8 5610
TEST_TIME 9 6526
TEST_TIME 10 6398
TEST_TIME 11 6593
TEST_TIME 12 6921
TEST_TIME 13 6962
TEST_TIME 14 7114
TEST_TIME 15 7355
TEST_TIME 16 7942
TEST_TIME 17 8305
TEST_TIME 18 8214
TEST_TIME 19 8308
TEST_TIME 20 8436
TEST_TIME 21 8546
TEST_TIME 22 8703
TEST_TIME 23 8997
TEST_TIME 24 9607
TEST_TIME 25 9585
TEST_TIME 26 9948
TEST_TIME 27 10652
TEST_TIME 28 10382
TEST_TIME 29 10182
TEST_TIME 30 10332
TEST_TIME 31 10592
TEST_TIME 32 10371
TEST_TIME 33 10248
TEST_TIME 34 10149
TEST_TIME 35 9958
TEST_TIME 36 9928
TEST_TIME 37 9926
TEST_TIME 38 9778
TEST_TIME 39 9907
TEST_TIME 40 9742
TEST_TIME 41 9629
TEST_TIME 42 9630
TEST_TIME 43 9603
TEST_TIME 44 9659
TEST_TIME 45 9586
TEST_TIME 46 9478
TEST_TIME 47 9418
TEST_TIME 48 9298
TEST_TIME 49 9223
TEST_TIME 50 9108
TEST_TIME 51 9215
TEST_TIME 52 9123
TEST_TIME 53 9090
TEST_TIME 54 9112
TEST_TIME 55 9083
TEST_TIME 56 9040
TEST_TIME 57 9083
TEST_TIME 58 9018
TEST_TIME 59 9186
TEST_TIME 60 9111
TEST_TIME 61 9142
TEST_TIME 62 9292
TEST_TIME 63 9417
TEST_TIME 64 9350
TEST_TIME 65 9435
TEST_TIME 66 9494
TEST_TIME 67 9495
TEST_TIME 68 9488
TEST_TIME 69 9478
TEST_TIME 70 9370
TEST_TIME 71 9353
TEST_TIME 72 9304
TEST_TIME 73 9246
TEST_TIME 74 9289
TEST_TIME 75 9241
TEST_TIME 76 9193
TEST_TIME 77 9144
TEST_TIME 78 9125
TEST_TIME 79 9078
TEST_TIME 80 9088
TEST_TIME 81 9124
TEST_TIME 82 9045
TEST_TIME 83 9113
TEST_TIME 84 9104
TEST_TIME 85 9132
TEST_TIME 86 9083
TEST_TIME 87 9071
TEST_TIME 88 9096
TEST_TIME 89 9149
TEST_TIME 90 9147
TEST_TIME 91 9252
TEST_TIME 92 9214
TEST_TIME 93 9240
TEST_TIME 94 9273
TEST_TIME 95 9342
TEST_TIME 96 9325
TEST_TIME 97 9267
TEST_TIME 98 9202
TEST_TIME 99 9186
TEST_TIME 100 9167
TEST_TIME 101 9125
TEST_TIME 102 9083
TEST_TIME 103 9037
TEST_TIME 104 9062
TEST_TIME 105 9013
TEST_TIME 106 9004
TEST_TIME 107 8980
TEST_TIME 108 8946
TEST_TIME 109 8912
TEST_TIME 110 8912
TEST_TIME 111 8916
TEST_TIME 112 8930
TEST_TIME 113 8940
TEST_TIME 114 9027
TEST_TIME 115 9010
TEST_TIME 116 9028
TEST_TIME 117 9029
TEST_TIME 118 9076
TEST_TIME 119 9065
TEST_TIME 120 9060

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2021 19:38:28 to 13-08-2021 19:38:31.

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 1506989 1506989 0 0.00 0 21833 6 0 0
doc_list_biud 1506989 1506989 0 0.00 0 29460 26 0 0
sp_add_invoice_to_stock 5163 5060 103 1.99 54 157410 18675 0 103
sp_cancel_adding_invoice 5557 5557 0 0.00 6 74864 857 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 10154 385 0 0
sp_cancel_customer_reserve 549698 549698 0 0.00 1 29462 55 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 3169 55 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 4779 76 0 0
sp_cancel_supplier_invoice 6167 6154 13 0.21 2 28646 770 0 13
sp_cancel_supplier_order 395 395 0 0.00 6 1083542 96258 0 0
sp_cancel_write_off 9699 9699 0 0.00 1 7870 109 0 0
sp_client_order 20238 20238 0 0.00 4 23502 353 0 0
sp_customer_reserve 452637 452637 0 0.00 0 67857 191 0 0
sp_fill_shopping_cart 31029 31029 0 0.00 1 38081 46 0 0
sp_fill_shopping_cart_clo_res 452633 452633 0 0.00 0 12466 14 0 0
sp_get_clo_for_invoice 5055 5055 0 0.00 0 40306 330 0 0
sp_kill_cost_storno 16339 16339 0 0.00 0 20026 54 0 0
sp_kill_qstorno_ret_qs2qd 1115644 1115644 0 0.00 0 29456 30 0 0
sp_kill_qty_storno 588084 588084 0 0.00 0 29460 66 0 0
sp_lock_selected_doc 20597 20597 0 0.00 0 11663 6 0 0
sp_make_cost_storno 27711 27711 0 0.00 0 11544 58 0 0
sp_multiply_rows_for_pdistr 27711 27711 0 0.00 0 21768 33 0 0
sp_multiply_rows_for_qdistr 20238 20238 0 0.00 0 13665 149 0 0
sp_pay_from_customer 7026 7026 0 0.00 4 37951 153 0 0
sp_pay_to_supplier 5840 5840 0 0.00 5 18323 141 0 0
sp_payment_common 12866 12866 0 0.00 1 37910 37 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 10085 350 0 0
sp_qd_handle_on_invoice_upd_sts 10609 10609 0 0.00 0 20909 329 0 0
sp_qd_handle_on_reserve_upd_sts 9793 9793 0 0.00 0 17471 67 0 0
sp_reserve_write_off 9793 9793 0 0.00 6 17505 194 0 0
sp_supplier_invoice 5398 5398 0 0.00 6 97220 3580 0 0
sp_supplier_order 5399 5399 0 0.00 4 47332 1327 0 0
srv_aggregate_perf_data 97 97 0 0.00 2640 333489 21563 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1660 132571 44842 0 0
srv_find_qd_qs_mism 1040566 1040566 0 0.00 0 10002 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 29 55977 1243 0 0
srv_make_money_saldo 782 782 0 0.00 1 38518 81 0 0
srv_recalc_idx_stat 4 4 0 0.00 163475 485689 336424 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5060 5060 0 0.00 0 9449 171 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2378 101 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 6110 14 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 2963 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 1557 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 546 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1424 16 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 28238 1642 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 5704 19 0 0
t$perf-norm:sp_client_order 20238 20238 0 0.00 0 8337 20 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 2790 10 0 0
t$perf-norm:sp_pay_from_customer 7026 7026 0 0.00 0 8043 12 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 5476 15 0 0
t$perf-norm:sp_reserve_write_off 9793 9793 0 0.00 0 6349 15 0 0
t$perf-norm:sp_supplier_invoice 5398 5398 0 0.00 0 7798 19 0 0
t$perf-norm:sp_supplier_order 5399 5399 0 0.00 0 7476 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 394 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 982 20 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1488 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10310 10104 206 1.99 0 4402 19 0 206
v_all_customers 36936 36936 0 0.00 0 16 1 0 0
v_all_suppliers 29694 29694 0 0.00 0 16 1 0 0
v_all_wares 40476 40476 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 799 6 0 0
v_cancel_client_order 3472 3472 0 0.00 0 9809 10 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 3159 6 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 262 3 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3107 11 0 0
v_cancel_supplier_invoice 870 844 26 2.98 0 2222 16 0 26
v_cancel_supplier_order 790 790 0 0.00 0 1761 8 0 0
v_cancel_write_off 1940 1940 0 0.00 0 1025 12 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 192 1 0 0
v_max_id_clo_res 943 943 0 0.00 0 5696 19 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 4104 4 0 0
v_max_non_paid_invoice 1783 1783 0 0.00 0 2754 5 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 2698 5 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 2793 7 0 0
v_min_id_clo_res 943 943 0 0.00 0 1527 4 0 0
v_min_id_ord_sup 5392 5392 0 0.00 0 7687 16 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 7594 16 0 0
v_min_non_paid_realizn 7026 7026 0 0.00 0 37901 31 0 0
v_our_firm 10068 10068 0 0.00 0 19 1 0 0
v_reserve_write_off 19586 19586 0 0.00 0 9887 8 0 0
x_lock_depdocs_on_canc_invoice 11289 11289 0 0.00 0 17208 112 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 12387 349 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 1 47272 1247 0 0
x_make_qstorno_1200_2000 5398 5398 0 0.00 3 90798 3404 0 0
x_make_qstorno_2100_3300 452637 452637 0 0.00 0 67854 174 0 0

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

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
17:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 34665 8192000000 10369523280 10688364544 1408813152 14.673 100 86 172 0 1442655584 5708768 28133664
17:10:33 7749 8192000000 10423310384 10749190144 1479126288 15.294 100 88 176 2 1515840320 8008368 28705664
17:11:43 20445 8192000000 10480001824 10824036352 1539301040 15.818 100 85 168 1 1575270928 9673552 26296336
17:13:06 11364 8192000000 10571347440 10928234496 1630569776 16.600 100 75 150 1 1664011120 11033440 22407904
17:14:23 9961 8192000000 10644122912 11011764224 1706920976 17.243 100 87 174 1 1749147376 16937264 25289136
17:15:40 6101 8192000000 10701023040 11078627328 1768173920 17.752 100 79 158 0 1803306432 13047888 22084624
17:16:47 6733 8192000000 10744745312 11134013440 1812659840 18.118 100 64 127 2 1841741840 12165984 16916016
17:18:00 1961 8192000000 10810934272 11195392000 1864143296 18.537 100 91 182 3 1902967312 12699072 26124944
17:19:04 2598 8192000000 10816168640 11217367040 1889070768 18.738 100 66 132 3 1919827104 12579024 18177312
17:20:07 22262 8192000000 10823252928 11227049984 1892229040 18.764 100 66 133 3 1918163328 5854592 20079696
17:21:30 6113 8192000000 10808106912 11232604160 1890140240 18.747 100 34 68 1 1908371824 8748944 9482640
17:22:37 5340 8192000000 10820701552 11248832512 1908506416 18.895 100 31 63 1 1923977712 6410656 9060640
17:23:43 6708 8192000000 10830909776 11261259776 1916124848 18.956 100 23 46 2 1929954784 6799632 7030304
17:24:56 30451 8192000000 10872950384 11276259328 1931119568 19.076 100 70 142 3 1958869056 5983168 21766320
17:26:27 5121 8192000000 10837833744 11275841536 1923693600 19.016 100 26 52 2 1940740208 10028608 7018000
17:27:33 3170 8192000000 10810877552 11231825920 1875261792 18.627 99 40 80 1 1894419504 7617392 11540320
17:28:41 25309 8192000000 10736923024 11164934144 1798383904 18.001 97 32 64 3 1813459360 5934736 9140720
17:30:18 6086 8192000000 10756885264 11173498880 1839124976 18.334 100 35 70 2 1856556320 7350144 10081200
17:31:28 7327 8192000000 10703830384 11119214592 1781673648 17.863 99 16 32 0 1791050624 4821648 4555328
17:32:37 7667 8192000000 10728477984 11153530880 1813808448 18.127 100 23 42 1 1825162096 5568080 5785568
17:33:45 18554 8192000000 10714321408 11129413632 1793559744 17.961 98 19 38 1 1804348464 5680032 5108688
17:35:09 8046 8192000000 10772143936 11193303040 1850043456 18.422 100 29 58 0 1864052864 5867248 8142160
17:36:17 10050 8192000000 10790350800 11210571776 1867752656 18.566 100 24 48 0 1880006064 5644176 6609232
17:37:32 58755 8192000000 10828999792 11250511872 1897845776 18.809 100 29 57 1 1911240352 5737088 7657488
17:39:39 5283 8192000000 10873830784 11287076864 1932501376 19.087 100 56 113 0 1959465408 11645520 15318512
17:40:50 9040 8192000000 10878161680 11310514176 1950974160 19.234 100 49 98 1 1976943360 11470336 14498864
17:42:06 22265 8192000000 10904922608 11334217728 1967137824 19.363 100 56 111 2 1997203712 14599264 15466624
17:43:33 9967 8192000000 10914837392 11352276992 1979680464 19.462 100 47 92 0 2010695680 17625584 13389632
17:44:46 6503 8192000000 10925331088 11359293440 1983094752 19.489 100 53 106 0 2019677392 20243680 16338960
17:46:02 13740 8192000000 10930604032 11363053568 1981660368 19.478 100 42 84 2 2013648640 20140992 11847280
17:47:22 6811 8192000000 10934995824 11359883264 1979782032 19.463 100 63 125 1 2021154720 23160672 18212016
17:48:33 3659 8192000000 10939727456 11367936000 1969278544 19.380 99 70 140 1 2019086352 29145104 20662704
17:49:40 3608 8192000000 10933207728 11349622784 1960471264 19.310 99 84 167 2 2012198160 27562352 24164544
17:50:50 11793 8192000000 10924470832 11346354176 1950669648 19.232 99 60 120 1 2000105008 30724944 18710416
17:52:06 2685 8192000000 10904377248 11310727168 1927267424 19.045 99 78 155 1 1983430272 32656464 23506384
17:53:13 5757 8192000000 10929416064 11341352960 1935226400 19.109 99 76 152 1 1997113552 39180736 22706416
17:54:32 1659 8192000000 10919896240 11324481536 1938899200 19.138 100 85 166 0 1995870816 31868080 25103536
17:55:38 3185 8192000000 10906959376 11327045632 1943534032 19.175 100 70 139 3 1996483984 31109536 21840416
17:56:45 4492 8192000000 10876493488 11297673216 1895305312 18.789 98 61 123 3 1946699888 32502656 18891920
17:57:56 4185 8192000000 10882432480 11307937792 1915572160 18.951 100 47 93 1 1965448144 35054496 14821488
17:59:06 9822 8192000000 10911854464 11342741504 1938283376 19.133 100 44 85 3 1993913904 41225392 14405136
18:00:20 3801 8192000000 10904560368 11333386240 1933688560 19.096 100 53 106 1 1988954576 37716448 17549568
18:01:27 11761 8192000000 10917323776 11345268736 1945529312 19.191 100 36 73 0 1988260768 31241328 11490128
18:02:40 32500 8192000000 10884412800 11332423680 1915769728 18.953 99 20 40 0 1958115216 35243968 7101520
18:04:18 11591 8192000000 10915419216 11360395264 1948408624 19.214 100 31 62 0 1994270640 35205200 10656816
18:05:33 23126 8192000000 10934247232 11366842368 1977320448 19.443 100 62 123 1 2010427936 14659072 18448416
18:07:06 37858 8192000000 10912472592 11370434560 1975188384 19.427 100 24 48 0 2000666208 18342400 7135424
18:09:54 79920 8192000000 10893190496 11353387008 1970595712 19.390 100 25 50 1 1988836544 11818096 6422736
18:12:19 48327 8192000000 10910444816 11375747072 1961135632 19.315 99 16 32 2 1985501168 19911344 4454192
18:15:38 108401 8192000000 10914233824 11402518528 1995358688 19.586 100 19 38 4 2019769936 19140640 5270608
18:18:29 44779 8192000000 10925411616 11392569344 1983106912 19.489 99 16 32 0 2005610320 18244432 4258976
18:20:15 72284 8192000000 10926357808 11402940416 2005046608 19.663 100 23 47 2 2028923872 17127264 6750000
18:22:28 37737 8192000000 10920132336 11388436480 2000186320 19.624 100 32 64 3 2020661216 10706704 9768192
18:24:10 41353 8192000000 10912912560 11393290240 2000646128 19.628 100 18 35 1 2015469216 9492384 5330704
18:25:52 100942 8192000000 10916248144 11394281472 1980476112 19.468 99 21 42 1 1997896064 11614496 5805456
18:30:40 46985 8192000000 10882534240 11355586560 1944711408 19.184 99 11 21 0 1963515760 14423984 4380368
18:32:27 55432 8192000000 10868332544 11340853248 1906520000 18.879 97 11 22 4 1922092656 12233776 3338880
18:34:32 46002 8192000000 10888728192 11360911360 1935137152 19.108 98 14 28 1 1950602480 11468080 3997248
18:36:23 89363 8192000000 10895535392 11356438528 1864231456 18.538 94 21 42 2 1881905136 11408688 6264992
18:38:58 75256 8192000000 10904349776 11394420736 1935836144 19.114 98 8 16 0 1959123120 21085040 2201936
18:42:02 21649 8192000000 10873045568 11332288512 1895391728 18.789 97 36 72 2 1917063472 11862128 9809616
18:43:24 132571 8192000000 10876851264 11343704064 1841625264 18.354 94 16 32 2 1862080528 16036800 4418464
18:46:40 84792 8192000000 10871072592 11318837248 1909221216 18.900 98 12 24 0 1922302336 9548688 3532432
18:49:08 41916 8192000000 10881710336 11338153984 1900916192 18.834 97 40 80 1 1923356832 11589808 10850832
18:50:55 45661 8192000000 10855775568 11309166592 1891760608 18.760 98 20 39 1 1908116944 10930512 5425824
18:52:45 55546 8192000000 10853990544 11320651776 1903547280 18.855 98 10 19 0 1913611952 7090608 2974064
18:54:42 66378 8192000000 10848450240 11318730752 1922583616 19.008 99 14 28 1 1942504400 15363136 4557648
18:56:49 96507 8192000000 10841202080 11296096256 1838118656 18.325 95 19 38 0 1861124736 17035696 5970384
18:59:27 69746 8192000000 10843806464 11327098880 1930795808 19.073 100 19 38 0 1949243504 12999568 5448128
19:01:43 66103 8192000000 10864509776 11338055680 1909801168 18.905 98 19 37 1 1925614768 9490912 6322688
19:03:53 92579 8192000000 10874000784 11340476416 1914594656 18.944 98 23 46 0 1934372592 12419376 7358560
19:06:25 43733 8192000000 10907201488 11362775040 1962253680 19.324 100 36 70 1 1984381104 11846832 10280592
19:08:22 82396 8192000000 10919080208 11367940096 1944084960 19.179 98 26 52 0 1960730240 9713888 6931392
19:10:45 128225 8192000000 10922434352 11405328384 1971038480 19.394 99 12 24 1 1992802272 18620736 3143056
19:13:53 24175 8192000000 10922886160 11374129152 1957625264 19.287 98 45 92 1 1979246960 9295568 12326128
19:15:22 35444 8192000000 10906364368 11368431616 1979600176 19.462 100 48 93 5 2001804336 8947552 13256608
19:17:00 63875 8192000000 10895268656 11378962432 1957842224 19.289 99 4 8 0 1972061296 13459424 759648
19:19:06 75458 8192000000 10861667568 11334594560 1906619760 18.880 97 12 24 0 1919082224 9497744 2964720
19:22:00 53016 8192000000 10871356480 11354001408 1927737776 19.049 98 13 26 1 1943755040 12459616 3557648
19:23:53 53155 8192000000 10880555408 11356692480 1963910912 19.337 100 33 66 1 1982290144 8941392 9437840
19:25:53 60363 8192000000 10896600528 11357831168 1886910784 18.721 95 14 28 0 1898292256 7504512 3876960
19:28:01 63377 8192000000 10915199632 11373436928 1945363840 19.190 99 17 34 1 1963176336 12740368 5072128
19:30:08 87101 8192000000 10870774192 11351040000 1874834736 18.623 95 14 28 1 1893047712 13854032 4358944
19:32:44 97363 8192000000 10875305600 11348078592 1897664224 18.808 97 17 34 0 1920443120 17904352 4874544
19:35:23 14633 8192000000 10876904608 11321262080 1866899952 18.559 96 56 112 3 1899863344 17138080 15825312
19:36:41 18584 8192000000 10897167648 11337236480 1938023248 19.131 100 54 108 2 1961322912 8885392 14414272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2021 19:38:38 to 13-08-2021 19:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2021 19:38:54 to 13-08-2021 19:38:56.

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          235326
MON$OLDEST_ACTIVE               240025
MON$OLDEST_SNAPSHOT             240025
MON$NEXT_TRANSACTION            240025
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                       2029280
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 ---
:223: 
:224: mscs-test-161.budzdorov.ru    Fri Aug 13 19:37:48 2021
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:226: 
:227: 
:228: mscs-test-161.budzdorov.ru    Fri Aug 13 19:37:48 2021
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:230: 
:231: 
:232: mscs-test-161.budzdorov.ru    Fri Aug 13 19:37:54 2021
:233:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:234: 
--- end of diff output ---

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

13.08.21 19:38:57. 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-13 19:38:59 No severe PSQL-related problems occured
13.08.21 19:39:00. End of report.