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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15538920 kB
MemAvailable 30761896 kB
Buffers 0 kB
Cached 16131104 kB
SwapCached 3220 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-26 13:37:08
DTS_END                         2021-09-26 13:37:08.3460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45232
TRN_ID                          240832
ATT_ID                          619
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: 9516.69 18516 1142003
customer order: creation 179.11 386 21493
customer order: refuse 14.62 319 1754
order to supplier: creation 46.23 1493 5548
order to supplier: removal 3.52 115911 422
invoice (draft): creation 46.73 3595 5608
invoice (draft): removal 54.53 943 6544
invoice accept: apply 42.26 17022 5071
invoice accept: cancel 49.92 795 5990
customer reserve: creation 3897.94 167 467753
customer reserve: removal 4876.31 65 585157
realization accept: apply 85.53 214 10263
realization accept: cancel 89.54 113 10745
payment from customer: creation 59.85 136 7182
payment from customer: removal 5.83 85 700
payment to supplier: creation 50.40 138 6048
payment to supplier: removal 3.38 73 405
service: total inventory turnovers 3.94 3542 473
service: total monetary turnovers 6.10 594 732
service: aggregate perf. data 0.93 13802 111
service: refresh index statistics 0.03 210935 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-09-2021 13:38:21 to 26-09-2021 13:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2961
TEST_TIME 2 3242
TEST_TIME 3 3778
TEST_TIME 4 4499
TEST_TIME 5 5129
TEST_TIME 6 4997
TEST_TIME 7 5283
TEST_TIME 8 5969
TEST_TIME 9 5897
TEST_TIME 10 6427
TEST_TIME 11 6686
TEST_TIME 12 6545
TEST_TIME 13 6677
TEST_TIME 14 6955
TEST_TIME 15 7139
TEST_TIME 16 7457
TEST_TIME 17 7595
TEST_TIME 18 7993
TEST_TIME 19 8141
TEST_TIME 20 8377
TEST_TIME 21 8556
TEST_TIME 22 9187
TEST_TIME 23 9221
TEST_TIME 24 9332
TEST_TIME 25 9515
TEST_TIME 26 10120
TEST_TIME 27 11144
TEST_TIME 28 11308
TEST_TIME 29 11592
TEST_TIME 30 11528
TEST_TIME 31 11381
TEST_TIME 32 11290
TEST_TIME 33 11163
TEST_TIME 34 11085
TEST_TIME 35 11263
TEST_TIME 36 11185
TEST_TIME 37 11206
TEST_TIME 38 11110
TEST_TIME 39 11029
TEST_TIME 40 11045
TEST_TIME 41 10871
TEST_TIME 42 10664
TEST_TIME 43 10476
TEST_TIME 44 10572
TEST_TIME 45 10528
TEST_TIME 46 10451
TEST_TIME 47 10409
TEST_TIME 48 10380
TEST_TIME 49 10367
TEST_TIME 50 10332
TEST_TIME 51 10233
TEST_TIME 52 10177
TEST_TIME 53 10199
TEST_TIME 54 10228
TEST_TIME 55 10192
TEST_TIME 56 10209
TEST_TIME 57 10177
TEST_TIME 58 10182
TEST_TIME 59 10260
TEST_TIME 60 10193
TEST_TIME 61 10255
TEST_TIME 62 10164
TEST_TIME 63 10209
TEST_TIME 64 10291
TEST_TIME 65 10304
TEST_TIME 66 10378
TEST_TIME 67 10359
TEST_TIME 68 10364
TEST_TIME 69 10225
TEST_TIME 70 10141
TEST_TIME 71 10130
TEST_TIME 72 10106
TEST_TIME 73 10109
TEST_TIME 74 10064
TEST_TIME 75 9999
TEST_TIME 76 10020
TEST_TIME 77 9920
TEST_TIME 78 9874
TEST_TIME 79 9866
TEST_TIME 80 9841
TEST_TIME 81 9802
TEST_TIME 82 9801
TEST_TIME 83 9797
TEST_TIME 84 9796
TEST_TIME 85 9817
TEST_TIME 86 9830
TEST_TIME 87 9904
TEST_TIME 88 9911
TEST_TIME 89 9976
TEST_TIME 90 9930
TEST_TIME 91 9881
TEST_TIME 92 9823
TEST_TIME 93 9830
TEST_TIME 94 9833
TEST_TIME 95 9755
TEST_TIME 96 9698
TEST_TIME 97 9721
TEST_TIME 98 9683
TEST_TIME 99 9720
TEST_TIME 100 9680
TEST_TIME 101 9631
TEST_TIME 102 9640
TEST_TIME 103 9702
TEST_TIME 104 9776
TEST_TIME 105 9811
TEST_TIME 106 9778
TEST_TIME 107 9839
TEST_TIME 108 9889
TEST_TIME 109 9823
TEST_TIME 110 9765
TEST_TIME 111 9728
TEST_TIME 112 9727
TEST_TIME 113 9699
TEST_TIME 114 9673
TEST_TIME 115 9623
TEST_TIME 116 9580
TEST_TIME 117 9576
TEST_TIME 118 9608
TEST_TIME 119 9586
TEST_TIME 120 9517

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2021 13:38:25 to 26-09-2021 13:38:29.

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 1575893 1575893 0 0.00 0 23770 7 0 0
doc_list_biud 1575878 1575878 0 0.00 0 58750 31 0 0
sp_add_invoice_to_stock 5172 5071 101 1.95 40 214109 17022 0 101
sp_cancel_adding_invoice 5990 5990 0 0.00 4 66350 795 0 0
sp_cancel_client_order 1754 1754 0 0.00 5 9003 319 0 0
sp_cancel_customer_reserve 585157 585157 0 0.00 1 21373 66 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 10659 85 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2956 74 0 0
sp_cancel_supplier_invoice 6552 6544 8 0.12 1 58829 944 0 8
sp_cancel_supplier_order 422 422 0 0.00 5 1164461 115912 0 0
sp_cancel_write_off 10745 10745 0 0.00 1 10732 113 0 0
sp_client_order 21493 21493 0 0.00 4 28407 387 0 0
sp_customer_reserve 467753 467753 0 0.00 0 60728 168 0 0
sp_fill_shopping_cart 32641 32641 0 0.00 1 22251 42 0 0
sp_fill_shopping_cart_clo_res 467753 467753 0 0.00 0 18234 15 0 0
sp_get_clo_for_invoice 5071 5071 0 0.00 0 27968 344 0 0
sp_kill_cost_storno 17840 17840 0 0.00 0 21714 57 0 0
sp_kill_qstorno_ret_qs2qd 1188025 1188025 0 0.00 0 58747 35 0 0
sp_kill_qty_storno 625932 625932 0 0.00 0 58749 76 0 0
sp_lock_selected_doc 21127 21127 0 0.00 0 9268 7 0 0
sp_make_cost_storno 28564 28564 0 0.00 0 23758 73 0 0
sp_multiply_rows_for_pdistr 28549 28549 0 0.00 0 9066 33 0 0
sp_multiply_rows_for_qdistr 21493 21493 0 0.00 0 21913 180 0 0
sp_pay_from_customer 7182 7182 0 0.00 4 21946 137 0 0
sp_pay_to_supplier 6048 6048 0 0.00 4 18904 139 0 0
sp_payment_common 13229 13229 0 0.00 1 8683 22 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 8997 280 0 0
sp_qd_handle_on_invoice_upd_sts 11053 11053 0 0.00 0 22085 351 0 0
sp_qd_handle_on_reserve_upd_sts 10257 10257 0 0.00 0 14328 55 0 0
sp_reserve_write_off 10263 10263 0 0.00 7 21965 214 0 0
sp_supplier_invoice 5608 5608 0 0.00 6 82408 3595 0 0
sp_supplier_order 5548 5548 0 0.00 6 31179 1494 0 0
srv_aggregate_perf_data 111 111 0 0.00 3378 142229 13802 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2313 141293 41652 0 0
srv_find_qd_qs_mism 1093741 1093741 0 0.00 0 19114 5 0 0
srv_make_invnt_saldo 473 473 0 0.00 63 114590 3543 0 0
srv_make_money_saldo 732 732 0 0.00 1 22202 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 160177 249633 210935 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5071 5071 0 0.00 0 11027 145 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 2927 79 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 4351 14 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 5988 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1232 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1718 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 496 13 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 15280 1555 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1761 6 0 0
t$perf-norm:sp_client_order 21493 21493 0 0.00 0 5225 15 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 908 5 0 0
t$perf-norm:sp_pay_from_customer 7182 7182 0 0.00 0 3745 7 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 3189 8 0 0
t$perf-norm:sp_reserve_write_off 10263 10263 0 0.00 0 4445 9 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 3734 11 0 0
t$perf-norm:sp_supplier_order 5548 5548 0 0.00 0 7695 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 578 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1221 36 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 2662 16 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 518 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 5 0 0
v_add_invoice_to_stock 10328 10126 202 1.95 0 18831 23 0 202
v_all_customers 38470 38470 0 0.00 0 64 1 0 0
v_all_suppliers 31542 31542 0 0.00 0 91 1 0 0
v_all_wares 42986 42986 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 5167 16 0 0
v_cancel_client_order 3508 3508 0 0.00 0 7104 10 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 6428 12 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 928 3 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 2771 12 0 0
v_cancel_supplier_invoice 774 758 16 2.06 0 1880 17 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2302 9 0 0
v_cancel_write_off 1970 1970 0 0.00 0 7336 17 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 4715 2 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 3894 19 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 5859 6 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 622 2 0 0
v_max_non_paid_realizn 3908 3908 0 0.00 0 2462 4 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 2312 5 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 4847 11 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 4894 7 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 8679 11 0 0
v_min_non_paid_realizn 7181 7181 0 0.00 0 6758 16 0 0
v_our_firm 11062 11062 0 0.00 0 14 1 0 0
v_reserve_write_off 20514 20514 0 0.00 0 11247 9 0 0
x_lock_depdocs_on_canc_invoice 12155 12155 0 0.00 0 11701 108 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 22346 396 0 0
x_make_qstorno_1000_1200 5548 5548 0 0.00 2 31158 1419 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 3 82398 3432 0 0
x_make_qstorno_2100_3300 467753 467753 0 0.00 0 60727 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2021 13:38:30 to 26-09-2021 13:38:34.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 31397 8192000000 9738550160 10042007552 816100176 9.059 100 89 179 0 845617632 7664992 21852464
11:09:47 20484 8192000000 9774472096 10082152448 849596288 9.396 100 89 178 0 882879904 12638448 20645168
11:11:20 6958 8192000000 9810490208 10128130048 879589456 9.696 100 85 170 0 919633824 20831072 19213296
11:12:33 11926 8192000000 9862661280 10193108992 914494112 10.042 100 76 152 0 972760832 41239152 17027568
11:13:47 9647 8192000000 9923799328 10264485888 961142704 10.500 100 84 168 1 1027372688 49000992 17228992
11:14:59 5427 8192000000 9966890224 10321915904 1000515472 10.884 100 80 158 0 1078417472 62490016 15411984
11:16:06 3712 8192000000 10013166048 10375307264 1022722960 11.098 100 92 182 1 1130394512 88838992 18832560
11:17:11 5703 8192000000 9997484640 10366984192 1047659824 11.338 100 78 156 0 1110028592 46753904 15614864
11:18:19 1695 8192000000 10018930352 10388332544 1063750272 11.492 100 90 182 2 1129541584 47176688 18614624
11:19:21 5228 8192000000 10025130672 10402598912 1067304192 11.526 100 70 141 2 1133097200 50909728 14883280
11:20:27 6759 8192000000 9989859264 10390200320 1071207936 11.564 100 33 65 0 1102995504 24463392 7324176
11:21:35 10662 8192000000 9988813504 10383450112 1077606976 11.625 100 30 60 0 1111401616 27061984 6732656
11:22:47 34687 8192000000 9990113504 10383736832 1083297216 11.679 100 31 60 1 1094480736 3459664 7723856
11:24:24 5029 8192000000 9990231360 10394247168 1086526416 11.710 100 36 71 0 1112332624 18489296 7316912
11:25:29 7089 8192000000 10012304528 10411433984 1093313008 11.774 100 30 60 0 1125308768 25355200 6640560
11:26:38 5030 8192000000 9991645616 10388406272 1077679232 11.625 99 33 66 1 1107967952 22553472 7735248
11:27:43 71390 8192000000 9954586784 10362486784 1008977472 10.965 94 30 58 0 1026917904 11721456 6218976
11:29:55 5949 8192000000 9955677776 10333487104 1030943504 11.178 99 46 93 0 1063705920 22049168 10713248
11:31:01 8918 8192000000 9938168848 10325815296 994058560 10.821 97 25 50 0 1018744944 19121840 5564544
11:32:15 8481 8192000000 9945901408 10332364800 1029224112 11.161 99 42 83 0 1064154912 24502448 10428352
11:33:25 10689 8192000000 9977419968 10384580608 1034042912 11.207 99 36 72 0 1077797392 37184176 6570304
11:34:40 4559 8192000000 9977983296 10364702720 1057513520 11.433 100 50 100 0 1079844832 9663600 12667712
11:35:48 5892 8192000000 9989090896 10386653184 1065140880 11.506 100 53 106 1 1101942224 24039792 12761552
11:36:56 72675 8192000000 10013971552 10408632320 1077306160 11.622 100 42 84 1 1102392560 15475264 9611136
11:39:13 21076 8192000000 10064546576 10469019648 1104339280 11.879 100 41 81 0 1165263888 52944832 7979776
11:40:50 7551 8192000000 10110267520 10516934656 1121052832 12.037 100 71 143 0 1216779456 80058880 15667744
11:42:02 21320 8192000000 10108716896 10527752192 1128768256 12.110 100 33 66 0 1223400016 87199376 7432384
11:43:29 7473 8192000000 10172511072 10579640320 1150722896 12.316 100 66 132 4 1280030112 115179072 14128144
11:44:42 20199 8192000000 10190808272 10610270208 1157652896 12.381 100 43 86 0 1300536144 132665520 10217728
11:46:18 4351 8192000000 10234388496 10638696448 1171679328 12.513 100 75 148 3 1337192928 149086768 16426832
11:47:26 5086 8192000000 10258715520 10671030272 1186342768 12.649 100 67 134 2 1359900976 158162912 15395296
11:48:35 11224 8192000000 10307774928 10734280704 1184340688 12.631 100 59 118 1 1412032784 213892208 13799888
11:49:49 6530 8192000000 10320402560 10758156288 1175985632 12.553 100 61 121 0 1429114640 239216784 13912224
11:51:03 5623 8192000000 10353629552 10759852032 1174232544 12.536 100 82 164 0 1451731792 258587408 18911840
11:52:12 2309 8192000000 10398577184 10813419520 1177363632 12.566 100 79 158 2 1498887632 303291328 18232672
11:53:22 2914 8192000000 10400360928 10813198336 1179657360 12.587 100 60 118 1 1499128384 305196096 14274928
11:54:30 2841 8192000000 10416683152 10821074944 1176007712 12.553 100 85 170 3 1516329536 320174224 20147600
11:55:37 7248 8192000000 10424062704 10835689472 1143646672 12.250 98 54 104 0 1514282720 357460816 13175232
11:56:45 6668 8192000000 10453702848 10868944896 1147473152 12.286 99 44 88 0 1544838560 385168864 12196544
11:57:55 8727 8192000000 10455571216 10870595584 1130235792 12.124 99 58 116 0 1556641952 411853840 14552320
11:59:10 17993 8192000000 10453465536 10890960896 1124710912 12.071 98 39 76 1 1545039376 410069264 10259200
12:00:31 22780 8192000000 10433741680 10893246464 1133123184 12.151 99 29 58 1 1518139088 377332864 7683040
12:01:55 14687 8192000000 10467035136 10904506368 1129511184 12.117 100 32 64 1 1554602704 416372992 8718528
12:03:10 13457 8192000000 10407285744 10866778112 1113440496 11.965 99 29 58 1 1492984128 371872592 7671040
12:05:06 30830 8192000000 10160441136 10597535744 1121276544 12.039 100 38 76 0 1242970352 113041536 8652272
12:06:41 141293 8192000000 10069922528 10544349184 1090998768 11.752 96 12 24 1 1163989728 70516064 2474896
12:10:03 48553 8192000000 10117533360 10547552256 1139369440 12.210 99 51 102 1 1205679712 53668384 12641888
12:11:56 66481 8192000000 10086867888 10598539264 1132598448 12.146 99 13 26 0 1235956704 100832080 2526176
12:14:03 85579 8192000000 10066871728 10512019456 1065601696 11.510 94 24 48 2 1112791744 41440048 5750000
12:16:33 21308 8192000000 10079270784 10513866752 1134467760 12.163 100 23 46 0 1171306608 33082608 3756240
12:17:56 91184 8192000000 10093299984 10549719040 1115524400 11.985 98 16 32 0 1144395408 25664816 3206192
12:20:28 29307 8192000000 10140870752 10566184960 1120621280 12.033 98 48 93 1 1202702528 72484192 9597056
12:22:11 23629 8192000000 10070062864 10581602304 1139079248 12.207 100 10 20 1 1189842688 48990656 1772784
12:23:36 33964 8192000000 10100096640 10556313600 1147197408 12.283 100 30 57 0 1193059616 40214624 5647584
12:25:20 26082 8192000000 10099907952 10541219840 1129303968 12.115 99 44 88 1 1206447984 66444752 10699264
12:26:52 71958 8192000000 10111630592 10571956224 1112733424 11.958 97 13 26 1 1187233984 71558592 2941968
12:29:13 45089 8192000000 10144700176 10623410176 1128680928 12.109 99 14 29 1 1241241248 109378064 3182256
12:31:04 44364 8192000000 10095897200 10603368448 1136830416 12.186 99 15 30 0 1231010992 90779264 3401312
12:32:49 40261 8192000000 10070498416 10532454400 1130017216 12.122 99 6 12 0 1163224288 32111840 1095232
12:34:42 39526 8192000000 10096815072 10528010240 1106464944 11.899 97 38 75 0 1148173040 34380928 7327168
12:36:22 57331 8192000000 10072008480 10518999040 1111740160 11.949 98 11 22 0 1140560976 26821792 1999024
12:38:41 36854 8192000000 10065796320 10495893504 1090554944 11.748 96 18 34 0 1123512864 29583920 3374000
12:40:19 63641 8192000000 10112479824 10540339200 1081691312 11.664 96 15 31 0 1178159488 93128048 3340128
12:42:31 62792 8192000000 10059412608 10518790144 1050368688 11.364 95 17 34 0 1110995168 56684480 3942000
12:44:35 71659 8192000000 10037244800 10508193792 1081261408 11.659 98 34 67 2 1125736144 37602112 6872624
12:46:48 76701 8192000000 10098113968 10534785024 1097902176 11.818 99 29 57 0 1120905296 16786016 6217104
12:49:05 3516 8192000000 10054500416 10513125376 1101371824 11.851 100 24 48 0 1155857376 49298208 5187344
12:50:09 62794 8192000000 10032599600 10533486592 1095833840 11.798 98 13 26 0 1161365024 62735248 2795936
12:52:15 41816 8192000000 10073339248 10521387008 1119987072 12.027 100 24 48 0 1170453104 45388048 5077984
12:53:57 61174 8192000000 10053124800 10493718528 1117396304 12.002 100 16 32 0 1141770608 21102976 3271328
12:56:13 58048 8192000000 10054101104 10541969408 1111769424 11.949 99 6 12 1 1156215552 43494416 951712
12:58:12 55075 8192000000 10069136416 10509127680 1113278736 11.963 98 30 60 2 1164711136 45013872 6418528
13:00:15 40330 8192000000 10121289152 10577825792 1139829792 12.214 100 22 44 0 1208802032 64590464 4381776
13:01:57 56110 8192000000 10122691392 10612973568 1128895280 12.111 99 20 40 1 1232405952 98692656 4818016
13:03:59 44358 8192000000 10129058704 10573541376 1149305440 12.303 100 26 52 1 1193852944 39003824 5543680
13:05:47 84796 8192000000 10078326640 10523365376 1102285728 11.859 96 15 30 0 1153005712 47812016 2907968
13:08:12 22102 8192000000 10157768032 10585780224 1155714512 12.363 100 59 116 1 1253613584 85919888 11979184
13:09:38 76262 8192000000 10115014112 10594177024 1140529968 12.221 99 35 70 0 1239056592 91444848 7081776
13:12:02 30149 8192000000 10082413776 10602352640 1151566800 12.324 100 6 11 1 1192085984 38920880 1598304
13:13:32 61654 8192000000 10122458656 10576891904 1110233296 11.935 96 17 31 1 1170572688 57474112 2865280
13:15:35 29686 8192000000 10110429616 10555949056 1109723872 11.930 97 31 62 2 1161567152 44941296 6901984
13:17:15 88688 8192000000 10180768464 10628435968 1146465952 12.276 99 23 46 0 1245831968 94864208 4501808
13:19:51 62598 8192000000 10165462704 10680705024 1158930208 12.393 100 18 36 1 1284735056 121771760 4033088
13:22:01 79839 8192000000 10096798192 10536091648 1139605376 12.212 99 28 56 0 1165735024 20055232 6074416
13:24:23 69575 8192000000 10118313520 10679558144 1135380992 12.172 99 15 30 1 1178506032 40112608 3012432
13:27:25 70602 8192000000 10064296384 10526396416 1101984656 11.856 96 21 42 1 1141037520 34394832 4658032
13:29:36 40297 8192000000 10053277136 10523209728 1090902544 11.751 96 7 14 0 1128875168 36677616 1295008
13:31:17 54300 8192000000 10045687968 10514542592 1104890064 11.884 97 19 38 1 1135140528 26637184 3613280
13:33:14 69818 8192000000 10088023824 10569711616 1076213168 11.611 95 12 24 1 1152881840 74074528 2594144
13:35:28 35863 8192000000 10032514176 10480009216 1103927440 11.875 99 20 40 0 1146478752 38510800 4040512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2021 13:38:36 to 26-09-2021 13:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2021 13:38:52 to 26-09-2021 13:38:54.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239087
MON$OLDEST_ACTIVE               240983
MON$OLDEST_SNAPSHOT             240983
MON$NEXT_TRANSACTION            240983
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2089984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:116: 
:117: mscs-test-161.budzdorov.ru    Sun Sep 26 13:37:13 2021
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Sun Sep 26 13:37:16 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
--- end of diff output ---

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

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