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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16107236 kB
MemAvailable 30799000 kB
Buffers 0 kB
Cached 15619916 kB
SwapCached 2436 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-11-11 16:37:56
DTS_END                         2021-11-11 16:37:56.5970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53540
TRN_ID                          232391
ATT_ID                          681
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: 9238.63 22197 1108636
customer order: creation 168.30 348 20196
customer order: refuse 14.13 357 1695
order to supplier: creation 44.43 1374 5332
order to supplier: removal 3.60 92985 432
invoice (draft): creation 44.21 3441 5305
invoice (draft): removal 53.78 781 6453
invoice accept: apply 41.08 19599 4929
invoice accept: cancel 48.05 751 5766
customer reserve: creation 3796.16 195 455539
customer reserve: removal 4732.18 54 567861
realization accept: apply 82.68 172 9921
realization accept: cancel 83.57 111 10028
payment from customer: creation 57.87 113 6944
payment from customer: removal 6.03 71 724
payment to supplier: creation 47.65 123 5718
payment to supplier: removal 3.37 101 404
service: total inventory turnovers 4.49 1113 539
service: total monetary turnovers 6.22 23 746
service: aggregate perf. data 0.83 18932 100
service: refresh index statistics 0.03 303289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2021 16:40:19 to 11-11-2021 16:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4368
TEST_TIME 2 5227
TEST_TIME 3 5270
TEST_TIME 4 5309
TEST_TIME 5 5173
TEST_TIME 6 4892
TEST_TIME 7 5351
TEST_TIME 8 5828
TEST_TIME 9 6322
TEST_TIME 10 6138
TEST_TIME 11 6364
TEST_TIME 12 6303
TEST_TIME 13 6459
TEST_TIME 14 6679
TEST_TIME 15 6754
TEST_TIME 16 6613
TEST_TIME 17 6991
TEST_TIME 18 7013
TEST_TIME 19 7080
TEST_TIME 20 7183
TEST_TIME 21 7764
TEST_TIME 22 8145
TEST_TIME 23 8601
TEST_TIME 24 8955
TEST_TIME 25 9220
TEST_TIME 26 9370
TEST_TIME 27 9889
TEST_TIME 28 10362
TEST_TIME 29 10436
TEST_TIME 30 10343
TEST_TIME 31 10232
TEST_TIME 32 10505
TEST_TIME 33 10282
TEST_TIME 34 10270
TEST_TIME 35 10355
TEST_TIME 36 10432
TEST_TIME 37 10366
TEST_TIME 38 10102
TEST_TIME 39 10009
TEST_TIME 40 10125
TEST_TIME 41 9942
TEST_TIME 42 9904
TEST_TIME 43 9984
TEST_TIME 44 9957
TEST_TIME 45 9828
TEST_TIME 46 9820
TEST_TIME 47 9716
TEST_TIME 48 9658
TEST_TIME 49 9589
TEST_TIME 50 9678
TEST_TIME 51 9582
TEST_TIME 52 9614
TEST_TIME 53 9527
TEST_TIME 54 9482
TEST_TIME 55 9487
TEST_TIME 56 9525
TEST_TIME 57 9509
TEST_TIME 58 9519
TEST_TIME 59 9555
TEST_TIME 60 9563
TEST_TIME 61 9574
TEST_TIME 62 9589
TEST_TIME 63 9618
TEST_TIME 64 9713
TEST_TIME 65 9672
TEST_TIME 66 9805
TEST_TIME 67 9761
TEST_TIME 68 9709
TEST_TIME 69 9600
TEST_TIME 70 9621
TEST_TIME 71 9544
TEST_TIME 72 9568
TEST_TIME 73 9554
TEST_TIME 74 9479
TEST_TIME 75 9460
TEST_TIME 76 9390
TEST_TIME 77 9378
TEST_TIME 78 9369
TEST_TIME 79 9403
TEST_TIME 80 9446
TEST_TIME 81 9413
TEST_TIME 82 9538
TEST_TIME 83 9511
TEST_TIME 84 9547
TEST_TIME 85 9539
TEST_TIME 86 9496
TEST_TIME 87 9500
TEST_TIME 88 9525
TEST_TIME 89 9604
TEST_TIME 90 9612
TEST_TIME 91 9619
TEST_TIME 92 9555
TEST_TIME 93 9479
TEST_TIME 94 9454
TEST_TIME 95 9435
TEST_TIME 96 9392
TEST_TIME 97 9377
TEST_TIME 98 9370
TEST_TIME 99 9368
TEST_TIME 100 9359
TEST_TIME 101 9320
TEST_TIME 102 9291
TEST_TIME 103 9285
TEST_TIME 104 9359
TEST_TIME 105 9331
TEST_TIME 106 9320
TEST_TIME 107 9368
TEST_TIME 108 9395
TEST_TIME 109 9367
TEST_TIME 110 9415
TEST_TIME 111 9421
TEST_TIME 112 9447
TEST_TIME 113 9443
TEST_TIME 114 9369
TEST_TIME 115 9401
TEST_TIME 116 9361
TEST_TIME 117 9347
TEST_TIME 118 9308
TEST_TIME 119 9265
TEST_TIME 120 9243
TEST_TIME 121 9167

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-11-2021 16:40:23 to 11-11-2021 16:40:26.

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 1531412 1531412 0 0.00 0 30487 7 0 0
doc_list_biud 1531410 1531410 0 0.00 0 78565 26 0 0
sp_add_invoice_to_stock 5069 4929 140 2.76 13 188738 19600 0 140
sp_cancel_adding_invoice 5766 5766 0 0.00 5 65690 751 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 16266 358 0 0
sp_cancel_customer_reserve 567861 567861 0 0.00 1 53518 55 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 5971 71 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 10783 101 0 0
sp_cancel_supplier_invoice 6466 6453 13 0.20 2 78572 781 0 13
sp_cancel_supplier_order 432 432 0 0.00 7 1055054 92986 0 0
sp_cancel_write_off 10028 10028 0 0.00 1 11877 112 0 0
sp_client_order 20196 20196 0 0.00 3 19561 348 0 0
sp_customer_reserve 455539 455539 0 0.00 0 94348 195 0 0
sp_fill_shopping_cart 30822 30822 0 0.00 1 11755 39 0 0
sp_fill_shopping_cart_clo_res 455527 455527 0 0.00 0 13054 14 0 0
sp_get_clo_for_invoice 4915 4915 0 0.00 0 24698 289 0 0
sp_kill_cost_storno 16922 16922 0 0.00 0 13609 56 0 0
sp_kill_qstorno_ret_qs2qd 1152635 1152635 0 0.00 0 78562 30 0 0
sp_kill_qty_storno 607071 607071 0 0.00 0 78564 65 0 0
sp_lock_selected_doc 20468 20468 0 0.00 0 7139 4 0 0
sp_make_cost_storno 27498 27498 0 0.00 0 30475 53 0 0
sp_multiply_rows_for_pdistr 27498 27498 0 0.00 0 11113 28 0 0
sp_multiply_rows_for_qdistr 20196 20196 0 0.00 0 16041 141 0 0
sp_pay_from_customer 6944 6944 0 0.00 4 13004 114 0 0
sp_pay_to_supplier 5718 5718 0 0.00 5 17057 123 0 0
sp_payment_common 12661 12661 0 0.00 2 8984 27 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 16258 328 0 0
sp_qd_handle_on_invoice_upd_sts 10681 10681 0 0.00 0 16798 327 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 12676 62 0 0
sp_reserve_write_off 9921 9921 0 0.00 6 15880 172 0 0
sp_supplier_invoice 5305 5305 0 0.00 6 104816 3442 0 0
sp_supplier_order 5332 5332 0 0.00 5 20520 1375 0 0
srv_aggregate_perf_data 100 100 0 0.00 3132 284433 18932 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 996 125534 47632 0 0
srv_find_qd_qs_mism 1062353 1062353 0 0.00 0 18973 6 0 0
srv_make_invnt_saldo 539 539 0 0.00 34 41551 1113 0 0
srv_make_money_saldo 746 746 0 0.00 1 459 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 110924 465135 303290 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 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 4929 4929 0 0.00 0 12920 170 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 2671 105 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 3574 12 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2462 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 1005 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 4901 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1423 18 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 27765 1568 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 4353 9 0 0
t$perf-norm:sp_client_order 20196 20196 0 0.00 0 20400 20 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 2236 7 0 0
t$perf-norm:sp_pay_from_customer 6944 6944 0 0.00 0 8020 13 0 0
t$perf-norm:sp_pay_to_supplier 5718 5718 0 0.00 0 5044 9 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 5110 9 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 2945 14 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 11270 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 113 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 1845 27 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 3695 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10102 9822 280 2.77 0 9497 18 0 280
v_all_customers 36810 36810 0 0.00 0 16 1 0 0
v_all_suppliers 29628 29628 0 0.00 0 28 1 0 0
v_all_wares 40390 40390 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 10247 21 0 0
v_cancel_client_order 3390 3390 0 0.00 0 3786 7 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 5964 11 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 430 3 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 10693 17 0 0
v_cancel_supplier_invoice 856 830 26 3.03 0 1457 20 0 26
v_cancel_supplier_order 864 864 0 0.00 0 1608 6 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1199 12 0 0
v_max_id_clo_ord 5329 5329 0 0.00 0 2290 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 2603 16 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 4839 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 1753 4 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 3733 4 0 0
v_min_id_clo_ord 5329 5329 0 0.00 0 3695 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 3482 9 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 3380 6 0 0
v_min_non_paid_invoice 5718 5718 0 0.00 0 6224 20 0 0
v_min_non_paid_realizn 6943 6943 0 0.00 0 5842 19 0 0
v_our_firm 9944 9944 0 0.00 0 29 1 0 0
v_reserve_write_off 19842 19842 0 0.00 0 11278 6 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 18452 122 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 6186 321 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 1 20500 1290 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 3 99691 3306 0 0
x_make_qstorno_2100_3300 455539 455539 0 0.00 0 94347 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2021 16:40:27 to 11-11-2021 16:40:31.

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
14:08:00 8 8192000000 8801839328 8813969408 6309952 0.076 2 1 2 0 6409584 56848 42784
14:09:01 12023 8192000000 9887866640 10193428480 974155440 10.627 100 91 182 0 1004557984 6282064 24120480
14:10:21 24342 8192000000 9928027680 10247901184 1011299872 10.988 100 87 174 0 1041528272 7628592 22599808
14:11:49 14759 8192000000 9963870784 10292289536 1046392736 11.326 100 87 174 0 1077793792 9435664 21965392
14:13:07 11655 8192000000 10010065040 10353524736 1094146000 11.782 100 74 148 1 1121614432 10057376 17411056
14:14:28 6888 8192000000 10081369888 10439491584 1166360064 12.463 100 81 160 1 1196987184 12579696 18047424
14:15:37 6937 8192000000 10135027552 10503761920 1217684688 12.940 100 87 173 1 1250785440 13748976 19351776
14:16:46 3691 8192000000 10161679856 10541178880 1248940896 13.228 100 77 148 1 1277830240 13377440 15511904
14:17:54 2599 8192000000 10193003392 10576666624 1281831344 13.530 100 86 174 0 1313301600 12127120 19343136
14:18:58 1561 8192000000 10221036256 10605604864 1301101296 13.705 100 93 186 3 1335647376 13276048 21270032
14:20:01 1979 8192000000 10217030688 10612789248 1309350496 13.780 100 76 151 0 1334811808 7965072 17496240
14:21:03 7953 8192000000 10207973488 10615877632 1311124832 13.796 100 26 50 2 1323551776 6483568 5943376
14:22:12 59456 8192000000 10229528768 10636107776 1321202320 13.888 100 34 69 0 1334279328 4756688 8320320
14:24:12 2471 8192000000 10233002912 10645696512 1330501360 13.972 100 52 104 1 1349279552 7297504 11480688
14:25:15 7601 8192000000 10223825472 10642808832 1334187104 14.005 100 24 48 1 1345228032 5160768 5880160
14:26:23 12665 8192000000 10232361328 10653724672 1341810352 14.074 100 30 59 2 1354517440 5639248 7067840
14:27:43 13101 8192000000 10229917568 10652475392 1337867040 14.038 100 20 40 0 1348644864 5927712 4850112
14:29:02 7617 8192000000 10201275456 10616332288 1281786352 13.529 97 24 48 0 1292934912 5585440 5563120
14:30:10 2964 8192000000 10173525216 10578509824 1258222352 13.314 98 29 57 1 1271572592 6607328 6742912
14:31:13 57351 8192000000 10147797984 10541887488 1169592816 12.493 92 31 61 1 1182446560 4897264 7956480
14:33:15 6339 8192000000 10132621664 10534260736 1234727952 13.098 99 30 61 1 1247679136 5907440 7043744
14:34:23 6878 8192000000 10174329168 10579566592 1278576832 13.500 100 34 67 3 1291895952 5103136 8215984
14:35:32 9620 8192000000 10193277616 10613735424 1306406288 13.753 100 18 36 1 1315716240 5543536 3766416
14:36:44 36396 8192000000 10209215840 10626023424 1318553904 13.864 100 46 91 1 1334516912 5717488 10245520
14:38:23 6224 8192000000 10245679888 10655244288 1340613408 14.063 100 50 97 1 1360065664 8050640 11401616
14:39:32 23605 8192000000 10267209072 10684456960 1361516224 14.251 100 48 96 0 1381916432 10111680 10288528
14:41:01 4033 8192000000 10292918576 10709135360 1380940192 14.425 100 65 128 2 1404987136 9448576 14598368
14:42:25 47111 8192000000 10290423056 10722504704 1389851904 14.505 100 35 70 1 1409883296 11366464 8664928
14:44:19 5349 8192000000 10303180752 10729795584 1392092624 14.525 100 50 100 3 1418023712 13806272 12124816
14:45:29 7877 8192000000 10320885280 10747052032 1401217664 14.606 100 52 102 1 1430781808 17320336 12243808
14:46:41 17855 8192000000 10335902000 10768564224 1418788336 14.762 100 48 93 0 1447242016 17706032 10747648
14:48:03 8485 8192000000 10352327872 10777870336 1422575712 14.796 100 67 134 0 1461490864 23630016 15285136
14:49:15 9022 8192000000 10352418688 10777604096 1419109984 14.765 100 68 136 2 1463989104 28533312 16345808
14:50:36 3428 8192000000 10380961744 10806218752 1436750032 14.921 100 68 133 1 1487076944 34269136 16057776
14:51:47 14466 8192000000 10368192160 10793857024 1417594240 14.751 99 69 137 0 1468249424 34112816 16542368
14:53:12 6736 8192000000 10366295472 10794246144 1424004080 14.808 100 59 118 1 1473399504 33987472 15407952
14:54:24 4427 8192000000 10377846880 10793320448 1425063776 14.818 100 77 154 1 1480069232 35406944 19598512
14:55:33 995 8192000000 10374103952 10784641024 1413568000 14.716 100 86 172 3 1474381072 39384624 21428448
14:56:39 2348 8192000000 10377334144 10794201088 1414115600 14.720 100 74 145 1 1480611856 48002000 18494256
14:57:46 1954 8192000000 10358749408 10766880768 1386545104 14.475 100 85 173 2 1461676480 53452224 21679152
14:58:53 9542 8192000000 10366496496 10792730624 1401820032 14.611 100 50 100 0 1476112944 60840832 13452080
15:00:12 9776 8192000000 10349336864 10779734016 1385485824 14.466 99 46 92 0 1452522000 53859088 13177088
15:01:25 16677 8192000000 10324975984 10757419008 1374479680 14.367 99 31 61 1 1427343808 44347664 8516464
15:02:52 22653 8192000000 10309234976 10754625536 1381900208 14.434 100 23 46 1 1426301920 37865184 6536528
15:04:28 25187 8192000000 10285630928 10718941184 1334923504 14.012 98 17 34 0 1370466656 30634496 4908656
15:05:54 64748 8192000000 10272562416 10717446144 1346513744 14.116 98 7 14 1 1365470112 17196608 1759760
15:07:59 62687 8192000000 10281007072 10720321536 1322424944 13.899 96 14 27 1 1341937280 16538128 2974208
15:10:09 41560 8192000000 10307645584 10733940736 1371369792 14.339 99 29 56 2 1384826704 7765056 5691856
15:11:53 79436 8192000000 10300792176 10753880064 1371680752 14.342 99 9 18 1 1388506848 14745056 2081040
15:14:15 105811 8192000000 10298087200 10728886272 1337407552 14.034 97 18 36 0 1364181296 22571600 4202144
15:17:02 33815 8192000000 10273894960 10738483200 1373586768 14.359 100 16 32 1 1398584480 21221856 3775856
15:18:46 26698 8192000000 10306469712 10739683328 1372504880 14.349 99 60 117 3 1400730608 14212368 14013360
15:20:13 76043 8192000000 10285642960 10754748416 1367936400 14.309 99 17 34 3 1386759840 14544960 4278480
15:23:02 59885 8192000000 10301306608 10763325440 1390492080 14.510 99 17 34 0 1408386896 13619808 4275008
15:25:11 103133 8192000000 10302893136 10763735040 1383409408 14.447 99 10 20 0 1404513600 18608560 2495632
15:28:00 46690 8192000000 10281830448 10740645888 1381274512 14.428 99 14 28 0 1396068608 11953792 2840304
15:29:55 55661 8192000000 10287900080 10760642560 1384210864 14.454 100 7 14 0 1405694736 19997856 1486016
15:31:51 48096 8192000000 10299792624 10757283840 1389228016 14.499 99 19 38 1 1404032784 10815536 3989232
15:33:46 31888 8192000000 10321143104 10765516800 1390740752 14.512 99 41 81 1 1408995984 8847440 9407792
15:36:17 101823 8192000000 10304256048 10761154560 1321836112 13.893 94 18 34 1 1335224128 9439424 3948592
15:39:00 54842 8192000000 10292228112 10745737216 1355007360 14.193 97 19 38 0 1369663424 10560272 4095792
15:40:55 70049 8192000000 10293769168 10762194944 1361259696 14.249 98 9 18 1 1377521168 14606272 1655200
15:43:11 60260 8192000000 10295281008 10768445440 1381233792 14.428 99 20 40 0 1401495472 15159440 5102240
15:45:49 120522 8192000000 10279968256 10734313472 1342575904 14.081 97 25 50 3 1357202928 8995696 5631328
15:48:58 39569 8192000000 10293903136 10744188928 1380314336 14.419 100 32 64 0 1405719584 18185392 7219856
15:50:38 34486 8192000000 10257135536 10717958144 1354971392 14.192 99 6 12 0 1363485872 7117168 1397312
15:52:13 55629 8192000000 10268124432 10723373056 1367189488 14.302 100 21 42 1 1381431760 9485920 4756352
15:54:09 99650 8192000000 10283960784 10753134592 1379487744 14.412 100 18 36 0 1401973776 17899936 4586096
15:56:49 77392 8192000000 10263023040 10723627008 1312009152 13.804 95 16 32 0 1332319840 16359552 3951136
15:59:19 56342 8192000000 10271780480 10729148416 1368706464 14.315 99 27 54 2 1386518640 11176032 6636144
16:01:17 80794 8192000000 10266043984 10708803584 1309402560 13.781 95 16 32 0 1323277808 10496720 3378528
16:03:49 42188 8192000000 10272705136 10709381120 1334280800 14.006 98 34 68 1 1352490480 10637072 7572608
16:05:35 27516 8192000000 10287258992 10731266048 1355762672 14.199 99 38 76 1 1376746992 12595648 8388672
16:07:13 61083 8192000000 10300435152 10740588544 1370819088 14.334 100 15 28 1 1388041872 14076624 3146160
16:09:14 108621 8192000000 10268618368 10712805376 1347782576 14.128 98 30 60 1 1368041248 13448544 6810128
16:12:12 79216 8192000000 10278239712 10742415360 1383162160 14.445 100 10 20 0 1397184576 11751872 2270544
16:14:31 77995 8192000000 10283327712 10742149120 1361539136 14.251 98 17 34 0 1379327392 13312464 4475792
16:16:53 59862 8192000000 10288447328 10751680512 1383806272 14.451 100 11 22 1 1394691216 8750160 2134784
16:18:53 84132 8192000000 10274924416 10731597824 1357018288 14.211 97 28 56 0 1376433952 13372832 6042832
16:21:24 83373 8192000000 10287623216 10741116928 1358463136 14.224 98 14 27 1 1369960448 8331120 3166192
16:23:49 38493 8192000000 10285647552 10746073088 1376684752 14.387 100 25 50 2 1394548240 12242864 5620624
16:25:29 81628 8192000000 10283710704 10732265472 1345066064 14.103 97 13 26 0 1358017488 10261984 2689440
16:27:57 68155 8192000000 10300473280 10755633152 1364356768 14.276 98 18 36 0 1378047056 9896464 3793824
16:30:06 125534 8192000000 10272616112 10726588416 1328107728 13.950 95 20 40 0 1346812240 13801232 4903280
16:33:12 21394 8192000000 10276713472 10743545856 1371429920 14.340 100 13 26 0 1386139568 11889536 2820112
16:35:10 57867 8192000000 10263426064 10726535168 1343824208 14.092 98 11 23 0 1355559056 9114320 2620528
16:37:20 48437 8192000000 9363620768 9664884736 524065664 6.012 38 8 16 0 535344480 9286928 1991888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-11-2021 16:40:33 to 11-11-2021 16:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 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 11-11-2021 16:40:49 to 11-11-2021 16:40:51.

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          233561
MON$OLDEST_ACTIVE               233562
MON$OLDEST_SNAPSHOT             233562
MON$NEXT_TRANSACTION            233562
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                       2022080
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Thu Nov 11 16:38:35 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
--- end of diff output ---

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

11.11.21 16:40:52. 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-11-11 16:40:54 No severe PSQL-related problems occured
11.11.21 16:40:56. End of report.