Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.11.2020 04:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 16942868 kB
MemAvailable 31437520 kB
Buffers 20 kB
Cached 14693056 kB
SwapCached 2812 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.0
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 2020-11-09 04:26:48
DTS_END                         2020-11-09 04:26:48.8770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52614
TRN_ID                          230923
ATT_ID                          682
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: 8480.40 22320 1017648
customer order: creation 166.21 432 19945
customer order: refuse 14.03 369 1684
order to supplier: creation 44.17 1377 5300
order to supplier: removal 3.07 117408 368
invoice (draft): creation 44.53 3737 5344
invoice (draft): removal 48.03 964 5764
invoice accept: apply 39.98 16957 4798
invoice accept: cancel 42.93 1018 5152
customer reserve: creation 3614.82 168 433778
customer reserve: removal 4179.27 69 501512
realization accept: apply 81.95 185 9834
realization accept: cancel 76.59 124 9191
payment from customer: creation 57.97 113 6956
payment from customer: removal 5.43 74 651
payment to supplier: creation 47.43 113 5692
payment to supplier: removal 3.27 94 392
service: total inventory turnovers 3.77 3588 452
service: total monetary turnovers 6.01 595 721
service: aggregate perf. data 0.92 15518 110
service: refresh index statistics 0.03 283503 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2020 04:28:31 to 09-11-2020 04:28:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4425
TEST_TIME 2 3849
TEST_TIME 3 4576
TEST_TIME 4 4768
TEST_TIME 5 4787
TEST_TIME 6 4911
TEST_TIME 7 4999
TEST_TIME 8 4853
TEST_TIME 9 4818
TEST_TIME 10 4729
TEST_TIME 11 5204
TEST_TIME 12 5369
TEST_TIME 13 5427
TEST_TIME 14 5789
TEST_TIME 15 6221
TEST_TIME 16 6338
TEST_TIME 17 6560
TEST_TIME 18 6547
TEST_TIME 19 7172
TEST_TIME 20 7757
TEST_TIME 21 8273
TEST_TIME 22 8486
TEST_TIME 23 8467
TEST_TIME 24 8850
TEST_TIME 25 9226
TEST_TIME 26 9228
TEST_TIME 27 9314
TEST_TIME 28 9650
TEST_TIME 29 10342
TEST_TIME 30 10142
TEST_TIME 31 9972
TEST_TIME 32 9824
TEST_TIME 33 9675
TEST_TIME 34 9550
TEST_TIME 35 9436
TEST_TIME 36 9326
TEST_TIME 37 9186
TEST_TIME 38 9051
TEST_TIME 39 8990
TEST_TIME 40 8942
TEST_TIME 41 8916
TEST_TIME 42 8958
TEST_TIME 43 8841
TEST_TIME 44 8785
TEST_TIME 45 8773
TEST_TIME 46 8706
TEST_TIME 47 8648
TEST_TIME 48 8675
TEST_TIME 49 8874
TEST_TIME 50 8803
TEST_TIME 51 8804
TEST_TIME 52 8784
TEST_TIME 53 8906
TEST_TIME 54 8775
TEST_TIME 55 8815
TEST_TIME 56 8922
TEST_TIME 57 9038
TEST_TIME 58 9137
TEST_TIME 59 9129
TEST_TIME 60 9124
TEST_TIME 61 9026
TEST_TIME 62 8919
TEST_TIME 63 8872
TEST_TIME 64 8853
TEST_TIME 65 8792
TEST_TIME 66 8734
TEST_TIME 67 8663
TEST_TIME 68 8679
TEST_TIME 69 8704
TEST_TIME 70 8758
TEST_TIME 71 8784
TEST_TIME 72 8740
TEST_TIME 73 8716
TEST_TIME 74 8790
TEST_TIME 75 8768
TEST_TIME 76 8767
TEST_TIME 77 8846
TEST_TIME 78 8842
TEST_TIME 79 8830
TEST_TIME 80 8764
TEST_TIME 81 8698
TEST_TIME 82 8690
TEST_TIME 83 8618
TEST_TIME 84 8581
TEST_TIME 85 8558
TEST_TIME 86 8550
TEST_TIME 87 8525
TEST_TIME 88 8499
TEST_TIME 89 8498
TEST_TIME 90 8471
TEST_TIME 91 8556
TEST_TIME 92 8559
TEST_TIME 93 8649
TEST_TIME 94 8652
TEST_TIME 95 8682
TEST_TIME 96 8694
TEST_TIME 97 8720
TEST_TIME 98 8634
TEST_TIME 99 8582
TEST_TIME 100 8530
TEST_TIME 101 8503
TEST_TIME 102 8488
TEST_TIME 103 8489
TEST_TIME 104 8482
TEST_TIME 105 8455
TEST_TIME 106 8438
TEST_TIME 107 8423
TEST_TIME 108 8463
TEST_TIME 109 8436
TEST_TIME 110 8517
TEST_TIME 111 8495
TEST_TIME 112 8589
TEST_TIME 113 8591
TEST_TIME 114 8618
TEST_TIME 115 8607
TEST_TIME 116 8553
TEST_TIME 117 8568
TEST_TIME 118 8519
TEST_TIME 119 8503
TEST_TIME 120 8482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-11-2020 04:28:35 to 09-11-2020 04:28:38.

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 1419530 1419530 0 0.00 0 41051 6 0 0
doc_list_biud 1419529 1419529 0 0.00 0 50119 32 0 0
sp_add_invoice_to_stock 4917 4798 119 2.42 56 207071 16957 0 119
sp_cancel_adding_invoice 5152 5152 0 0.00 3 156269 1019 0 0
sp_cancel_client_order 1684 1684 0 0.00 4 15392 369 0 0
sp_cancel_customer_reserve 501512 501512 0 0.00 1 49649 70 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 6846 75 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 2118 94 0 0
sp_cancel_supplier_invoice 5777 5764 13 0.22 2 50124 965 0 13
sp_cancel_supplier_order 368 368 0 0.00 12 1156037 117409 0 0
sp_cancel_write_off 9191 9191 0 0.00 1 10751 124 0 0
sp_client_order 19945 19945 0 0.00 4 52341 432 0 0
sp_customer_reserve 433778 433778 0 0.00 0 53704 168 0 0
sp_fill_shopping_cart 30586 30586 0 0.00 1 21316 51 0 0
sp_fill_shopping_cart_clo_res 433775 433775 0 0.00 0 34570 18 0 0
sp_get_clo_for_invoice 4794 4794 0 0.00 0 46492 427 0 0
sp_kill_cost_storno 15386 15386 0 0.00 0 10747 60 0 0
sp_kill_qstorno_ret_qs2qd 1018347 1018347 0 0.00 0 50118 38 0 0
sp_kill_qty_storno 538298 538298 0 0.00 0 50119 82 0 0
sp_lock_selected_doc 20246 20246 0 0.00 0 9418 4 0 0
sp_make_cost_storno 27276 27276 0 0.00 0 10712 57 0 0
sp_multiply_rows_for_pdistr 27275 27275 0 0.00 0 9087 29 0 0
sp_multiply_rows_for_qdistr 19945 19945 0 0.00 0 52335 242 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 12261 113 0 0
sp_pay_to_supplier 5692 5692 0 0.00 5 10921 113 0 0
sp_payment_common 12648 12648 0 0.00 1 12231 20 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 15372 336 0 0
sp_qd_handle_on_invoice_upd_sts 9946 9946 0 0.00 0 41409 402 0 0
sp_qd_handle_on_reserve_upd_sts 9833 9833 0 0.00 0 15763 59 0 0
sp_reserve_write_off 9834 9834 0 0.00 7 15951 186 0 0
sp_supplier_invoice 5344 5344 0 0.00 7 69242 3737 0 0
sp_supplier_order 5300 5300 0 0.00 6 47513 1378 0 0
srv_aggregate_perf_data 110 110 0 0.00 2440 202594 15519 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1530 144522 45495 0 0
srv_find_qd_qs_mism 972069 972069 0 0.00 0 41050 5 0 0
srv_make_invnt_saldo 452 452 0 0.00 25 118233 3589 0 0
srv_make_money_saldo 721 721 0 0.00 1 25327 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 55232 451456 283503 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4798 4798 0 0.00 0 16361 156 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 1977 83 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 1472 11 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1382 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 499 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 229 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 749 12 0 0
t$perf-norm:sp_cancel_supplier_order 368 368 0 0.00 0 49540 1754 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 1501 8 0 0
t$perf-norm:sp_client_order 19945 19945 0 0.00 0 10313 18 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 3138 6 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 7881 8 0 0
t$perf-norm:sp_pay_to_supplier 5692 5692 0 0.00 0 1210 4 0 0
t$perf-norm:sp_reserve_write_off 9834 9834 0 0.00 0 4276 11 0 0
t$perf-norm:sp_supplier_invoice 5344 5344 0 0.00 0 5869 18 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 2337 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 267 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 956 20 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 694 6 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 3348 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9826 9588 238 2.42 0 11739 21 0 238
v_all_customers 36162 36162 0 0.00 0 110 1 0 0
v_all_suppliers 29488 29488 0 0.00 0 107 1 0 0
v_all_wares 39890 39890 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 2660 11 0 0
v_cancel_client_order 3368 3368 0 0.00 0 2479 5 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 3563 7 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3499 7 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1004 7 0 0
v_cancel_supplier_invoice 834 808 26 3.11 0 207 11 0 26
v_cancel_supplier_order 736 736 0 0.00 0 1451 7 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4594 18 0 0
v_max_id_clo_ord 5297 5297 0 0.00 0 1514 2 0 0
v_max_id_clo_res 968 968 0 0.00 0 1552 13 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 7080 7 0 0
v_max_non_paid_invoice 1587 1587 0 0.00 0 143 2 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 5216 5 0 0
v_min_id_clo_ord 5297 5297 0 0.00 0 2179 6 0 0
v_min_id_clo_res 968 968 0 0.00 0 4325 15 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 7240 9 0 0
v_min_non_paid_invoice 5692 5692 0 0.00 0 6362 13 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 7005 11 0 0
v_our_firm 10128 10128 0 0.00 0 26 1 0 0
v_reserve_write_off 19666 19666 0 0.00 0 4280 5 0 0
x_lock_depdocs_on_canc_invoice 10512 10512 0 0.00 0 40541 111 0 0
x_lock_depdocs_on_canc_sup_ord 368 368 0 0.00 0 28718 437 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 2 47504 1296 0 0
x_make_qstorno_1200_2000 5344 5344 0 0.00 3 68456 3530 0 0
x_make_qstorno_2100_3300 433777 433777 0 0.00 0 46365 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2020 04:28:39 to 09-11-2020 04:28:43.

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
01:56:52 8 8192000000 8798394224 8809598976 4436416 0.054 2 1 2 0 4535888 63616 35856
01:57:53 27909 8192000000 9710491760 10007609344 787680480 8.771 100 87 174 0 816403312 7544720 21178112
01:59:27 24438 8192000000 9761499040 10068357120 833587600 9.235 100 89 178 1 867697216 12383472 21726144
02:00:55 15375 8192000000 9792247648 10107072512 858945280 9.490 100 85 170 0 897578928 18953184 19680464
02:02:25 18411 8192000000 9866756592 10196262912 916915312 10.066 100 83 166 0 972430272 37295744 18219216
02:03:46 10796 8192000000 9907191232 10250395648 946455872 10.356 100 83 166 2 1028407760 64187824 17764064
02:04:59 4576 8192000000 9939250208 10288402432 981271504 10.697 100 82 165 1 1053425504 55148880 17005120
02:06:06 2602 8192000000 9980931824 10336997376 1011766976 10.992 100 83 164 1 1095260544 67335792 16157776
02:07:10 1913 8192000000 10012760272 10378117120 1036181680 11.228 100 89 179 2 1126969552 72302592 18485280
02:08:14 1359 8192000000 10038632784 10407010304 1049870976 11.359 100 88 176 0 1153016880 85349568 17796336
02:09:16 1675 8192000000 10026136144 10398560256 1063342720 11.488 100 85 171 0 1140033536 58872912 17817904
02:10:20 4920 8192000000 10007651536 10401464320 1064375584 11.498 100 41 80 1 1119613216 47291824 7945808
02:11:28 6434 8192000000 9993901296 10382614528 1074661856 11.597 100 45 90 2 1110391040 26045856 9683328
02:12:36 58479 8192000000 9999180480 10386161664 1080833888 11.655 100 45 91 2 1110158256 19280672 10043696
02:14:35 5422 8192000000 10013072816 10410115072 1089412608 11.737 100 49 99 0 1133140496 33057904 10669984
02:15:45 4976 8192000000 10005080736 10411008000 1089103296 11.734 100 56 112 4 1123532720 22029488 12399936
02:16:52 10010 8192000000 10006247328 10416865280 1083943456 11.685 100 28 57 0 1115758608 26348512 5466640
02:18:04 32270 8192000000 10003057072 10395385856 1036902064 11.235 96 42 84 1 1066319328 21326032 8091232
02:19:37 8730 8192000000 9960064800 10358923264 992122976 10.802 94 39 76 4 1022189072 20812160 9253936
02:20:49 10310 8192000000 9942847296 10333270016 996792000 10.847 96 30 60 3 1023328560 19938624 6597936
02:22:02 8657 8192000000 9931755840 10318094336 1007810160 10.954 98 17 34 1 1031207840 19373344 4024336
02:23:11 15996 8192000000 9978303040 10360610816 1003308512 10.911 97 39 78 1 1050249600 38162288 8778800
02:24:31 10806 8192000000 9955562080 10351841280 1039929184 11.264 99 36 70 2 1061526000 13281408 8315408
02:25:42 6161 8192000000 9976677296 10373337088 1058006032 11.437 100 39 78 1 1084344400 17793680 8544688
02:26:51 2317 8192000000 9992540432 10378731520 1084597472 11.691 100 42 81 5 1102153040 8357216 9198352
02:27:55 13994 8192000000 10017352512 10408845312 1094537872 11.786 100 59 116 1 1129616176 22785712 12292592
02:29:30 24655 8192000000 10071556368 10476253184 1109246880 11.925 100 55 109 1 1177574976 57554032 10774064
02:31:00 10978 8192000000 10087095696 10491322368 1127364400 12.097 100 41 82 2 1199802272 63493200 8944672
02:32:16 21677 8192000000 10120976208 10535534592 1147907936 12.290 100 36 72 5 1248967520 93067136 7992448
02:33:49 15566 8192000000 10166448176 10573242368 1162336880 12.425 100 42 84 3 1284388944 112607712 9444352
02:35:27 10988 8192000000 10205360256 10616758272 1171371568 12.510 100 46 92 1 1313185008 131498720 10314720
02:36:46 11409 8192000000 10272760288 10679246848 1173614944 12.531 100 62 122 1 1370078912 182405888 14058080
02:38:06 3961 8192000000 10262894592 10671083520 1154181280 12.349 98 65 129 2 1355410368 187354864 13874224
02:39:15 5129 8192000000 10282105376 10688212992 1165082704 12.451 99 62 124 1 1375870896 196957888 13830304
02:40:24 3270 8192000000 10319686624 10730885120 1160997728 12.413 99 61 122 4 1414706832 239221536 14487568
02:41:33 1525 8192000000 10342604128 10744987648 1168649984 12.484 100 87 174 4 1444279664 256213216 19416464
02:42:39 2900 8192000000 10346099808 10754490368 1160531424 12.408 99 78 154 2 1447144368 268429632 18183312
02:43:52 4458 8192000000 10322588944 10739171328 1157163136 12.377 100 64 126 1 1433430576 261441904 14825536
02:45:01 3356 8192000000 10379309120 10789326848 1149188240 12.302 100 78 155 0 1482054416 315254848 17611328
02:46:09 3351 8192000000 10302395616 10728607744 1150016048 12.310 100 74 148 0 1405254704 237900288 17338368
02:47:19 17974 8192000000 10197889440 10640936960 1134751648 12.166 99 30 60 3 1325669840 183502096 7416096
02:48:45 5938 8192000000 10239407040 10649456640 1133327872 12.153 99 55 110 2 1326586032 180494544 12763616
02:49:58 9688 8192000000 10236674992 10688200704 1113631536 11.967 98 32 62 1 1345387056 224199712 7555808
02:51:18 19268 8192000000 10185124864 10648330240 1140892816 12.224 100 25 50 3 1325072448 178235584 5944048
02:52:40 35621 8192000000 10226669408 10684682240 1105275520 11.888 97 20 39 0 1301948496 191490560 5182416
02:54:17 26895 8192000000 10198256912 10621403136 1100037136 11.838 100 18 36 2 1286162880 181693376 4432368
02:55:49 83282 8192000000 10045491792 10503561216 1084834224 11.694 97 23 44 1 1137320960 47965072 4521664
02:58:44 38916 8192000000 10053483088 10483924992 1112838000 11.959 99 21 41 0 1155139216 38315456 3985760
03:00:24 46388 8192000000 10047645824 10490310656 1112947552 11.960 99 18 33 1 1145246128 28574288 3724288
03:02:17 49860 8192000000 10092176384 10533519360 1132546768 12.145 100 26 53 1 1173753376 35902064 5304544
03:04:13 27271 8192000000 10105477264 10538319872 1145266832 12.265 100 21 43 2 1200137312 49594864 5275616
03:05:42 102399 8192000000 10109186608 10570924032 1133955008 12.159 99 21 42 0 1240304608 101469824 4879776
03:08:32 66654 8192000000 10086861728 10515677184 1122671184 12.052 98 39 78 3 1188437760 57112064 8654512
03:10:52 45935 8192000000 10126376832 10570563584 1134257040 12.161 100 31 62 2 1219116368 77862800 6996528
03:12:38 56129 8192000000 10156271024 10603515904 1130357920 12.125 99 19 38 0 1240047424 104731424 4958080
03:14:38 111395 8192000000 10062007360 10492223488 1101679776 11.854 97 16 31 1 1189560000 84160128 3720096
03:17:32 61021 8192000000 10140512704 10589556736 1145464976 12.267 100 15 30 2 1210479840 61703616 3311248
03:19:35 54041 8192000000 10142769648 10589360128 1149679056 12.306 100 11 23 1 1233730000 81271376 2779568
03:21:29 71143 8192000000 10184527808 10664468480 1145846960 12.270 99 16 32 0 1266075664 116523360 3705344
03:23:43 116020 8192000000 10055377680 10507997184 1057109312 11.429 92 9 18 0 1131403024 72624272 1669440
03:26:39 144522 8192000000 10056452384 10497794048 1084023968 11.686 95 20 40 3 1126092688 38545904 3522816
03:30:15 24573 8192000000 10030191712 10466713600 1092579248 11.767 97 15 30 1 1119957088 24709136 2668704
03:31:41 41865 8192000000 10064633120 10495528960 1080155232 11.649 96 25 50 0 1124974880 39483040 5336608
03:34:40 32615 8192000000 10091532608 10561413120 1121684832 12.043 100 11 22 0 1211881136 87430944 2765360
03:36:31 42404 8192000000 10086567200 10516357120 1083414224 11.680 95 19 38 1 1142642256 55302256 3925776
03:38:32 54304 8192000000 10108181904 10549874688 1096907552 11.808 97 29 58 2 1173634528 70774592 5952384
03:40:37 32082 8192000000 10068925360 10497482752 1112950640 11.960 99 26 50 2 1153017760 34910752 5156368
03:42:32 91818 8192000000 10045995344 10569519104 1079870336 11.646 96 21 42 1 1176829632 92433856 4525440
03:45:10 58611 8192000000 10091709536 10533871616 1074254976 11.593 96 22 44 1 1138292496 59368704 4668816
03:48:16 65696 8192000000 10038107936 10511585280 1114238400 11.973 100 16 32 0 1187383856 69213360 3932096
03:50:27 61590 8192000000 10081017168 10547724288 1119465008 12.022 100 8 16 0 1176253408 55070160 1718240
03:52:40 49246 8192000000 10073265056 10509606912 1129379600 12.116 100 22 44 1 1157625488 23952832 4293056
03:54:39 41440 8192000000 10117466592 10564526080 1133815888 12.157 99 20 40 3 1201095728 62543744 4736096
03:56:21 74698 8192000000 10140675440 10608340992 1119622976 12.023 98 27 54 5 1237590768 112126880 5840912
03:58:39 86231 8192000000 10080582576 10550501376 1102872112 11.865 97 18 34 2 1173491264 66943136 3676016
04:01:07 16680 8192000000 10127765920 10550431744 1135975648 12.178 100 62 124 0 1215098352 66809120 12313584
04:02:27 39571 8192000000 10099142512 10577235968 1132592144 12.146 100 25 50 1 1221184176 83111984 5480048
04:04:13 99667 8192000000 10054252912 10502672384 1112181600 11.953 98 12 24 1 1141003488 26192096 2629792
04:06:54 64918 8192000000 10088733152 10543837184 1130257232 12.124 99 11 22 0 1183719104 51006624 2455248
04:08:59 66801 8192000000 10080023520 10527924224 1139543872 12.211 100 14 26 1 1168485424 26205792 2735760
04:11:10 42138 8192000000 10112218352 10550145024 1117751360 12.006 97 23 46 0 1172068416 49675312 4641744
04:12:54 88946 8192000000 10146959344 10606276608 1137863808 12.195 98 28 56 1 1228354272 84438160 6052304
04:15:36 91846 8192000000 10120237536 10575728640 1112366160 11.955 96 18 36 0 1206811904 90130752 4314992
04:18:18 63458 8192000000 10110711472 10558476288 1100755024 11.845 96 12 24 1 1161975296 58843280 2376992
04:20:26 46666 8192000000 10115027552 10534948864 1071233840 11.564 94 19 37 1 1163670688 88740048 3696800
04:22:20 88337 8192000000 10082798512 10564071424 1088943728 11.733 97 15 30 1 1175608736 83357936 3307072
04:25:10 69937 8192000000 10092826224 10552147968 1092585680 11.767 98 6 12 0 1148977568 54247552 2144336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2020 04:28:45 to 09-11-2020 04:29:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 09-11-2020 04:29:01 to 09-11-2020 04:29:03.

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          229060
MON$OLDEST_ACTIVE               231083
MON$OLDEST_SNAPSHOT             231083
MON$NEXT_TRANSACTION            231083
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                       2081184
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             792
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2248 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:105: 
:106: mscs-test-161.budzdorov.ru    Mon Nov  9 04:26:50 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Mon Nov  9 04:26:54 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
--- end of diff output ---

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

09.11.20 04:29:04. 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
2020-11-09 04:29:07 No severe PSQL-related problems occured
09.11.20 04:29:08. End of report.