Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16950968 kB
MemAvailable 31535336 kB
Buffers 0 kB
Cached 14685196 kB
SwapCached 5440 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-10-19 10:26:54
DTS_END                         2020-10-19 10:26:54.3430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/42908
TRN_ID                          238615
ATT_ID                          641
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: 9156.71 21702 1098805
customer order: creation 167.61 363 20113
customer order: refuse 14.41 322 1729
order to supplier: creation 45.55 1387 5466
order to supplier: removal 3.36 118835 403
invoice (draft): creation 45.57 3513 5468
invoice (draft): removal 52.99 870 6359
invoice accept: apply 41.33 17098 4960
invoice accept: cancel 47.41 813 5689
customer reserve: creation 3801.10 168 456132
customer reserve: removal 4646.96 68 557635
realization accept: apply 81.92 184 9830
realization accept: cancel 81.72 126 9807
payment from customer: creation 58.11 102 6973
payment from customer: removal 5.75 79 690
payment to supplier: creation 48.35 127 5802
payment to supplier: removal 3.53 105 423
service: total inventory turnovers 4.26 2743 511
service: total monetary turnovers 5.77 543 692
service: aggregate perf. data 0.98 13749 118
service: refresh index statistics 0.04 272845 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2020 10:27:55 to 19-10-2020 10:27:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8581
TEST_TIME 2 6751
TEST_TIME 3 6891
TEST_TIME 4 7103
TEST_TIME 5 6851
TEST_TIME 6 6850
TEST_TIME 7 7000
TEST_TIME 8 6702
TEST_TIME 9 6664
TEST_TIME 10 6914
TEST_TIME 11 7052
TEST_TIME 12 7256
TEST_TIME 13 7310
TEST_TIME 14 7587
TEST_TIME 15 7641
TEST_TIME 16 7911
TEST_TIME 17 8236
TEST_TIME 18 8160
TEST_TIME 19 8040
TEST_TIME 20 8813
TEST_TIME 21 9329
TEST_TIME 22 9015
TEST_TIME 23 9804
TEST_TIME 24 9866
TEST_TIME 25 10148
TEST_TIME 26 11898
TEST_TIME 27 11890
TEST_TIME 28 11546
TEST_TIME 29 11407
TEST_TIME 30 11156
TEST_TIME 31 11108
TEST_TIME 32 10873
TEST_TIME 33 10735
TEST_TIME 34 10654
TEST_TIME 35 10785
TEST_TIME 36 10651
TEST_TIME 37 10461
TEST_TIME 38 10376
TEST_TIME 39 10409
TEST_TIME 40 10530
TEST_TIME 41 10415
TEST_TIME 42 10436
TEST_TIME 43 10387
TEST_TIME 44 10330
TEST_TIME 45 10248
TEST_TIME 46 10313
TEST_TIME 47 10392
TEST_TIME 48 10345
TEST_TIME 49 10443
TEST_TIME 50 10449
TEST_TIME 51 10496
TEST_TIME 52 10329
TEST_TIME 53 10182
TEST_TIME 54 10082
TEST_TIME 55 9971
TEST_TIME 56 9926
TEST_TIME 57 9804
TEST_TIME 58 9763
TEST_TIME 59 9773
TEST_TIME 60 9700
TEST_TIME 61 9629
TEST_TIME 62 9701
TEST_TIME 63 9662
TEST_TIME 64 9674
TEST_TIME 65 9749
TEST_TIME 66 9774
TEST_TIME 67 9865
TEST_TIME 68 9847
TEST_TIME 69 9876
TEST_TIME 70 9906
TEST_TIME 71 9818
TEST_TIME 72 9723
TEST_TIME 73 9660
TEST_TIME 74 9574
TEST_TIME 75 9548
TEST_TIME 76 9515
TEST_TIME 77 9556
TEST_TIME 78 9493
TEST_TIME 79 9475
TEST_TIME 80 9492
TEST_TIME 81 9455
TEST_TIME 82 9443
TEST_TIME 83 9563
TEST_TIME 84 9613
TEST_TIME 85 9607
TEST_TIME 86 9631
TEST_TIME 87 9626
TEST_TIME 88 9567
TEST_TIME 89 9493
TEST_TIME 90 9428
TEST_TIME 91 9373
TEST_TIME 92 9360
TEST_TIME 93 9364
TEST_TIME 94 9371
TEST_TIME 95 9335
TEST_TIME 96 9325
TEST_TIME 97 9378
TEST_TIME 98 9358
TEST_TIME 99 9375
TEST_TIME 100 9335
TEST_TIME 101 9373
TEST_TIME 102 9431
TEST_TIME 103 9396
TEST_TIME 104 9395
TEST_TIME 105 9438
TEST_TIME 106 9444
TEST_TIME 107 9361
TEST_TIME 108 9309
TEST_TIME 109 9245
TEST_TIME 110 9201
TEST_TIME 111 9209
TEST_TIME 112 9195
TEST_TIME 113 9177
TEST_TIME 114 9225
TEST_TIME 115 9205
TEST_TIME 116 9197
TEST_TIME 117 9192
TEST_TIME 118 9181
TEST_TIME 119 9201
TEST_TIME 120 9159

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2020 10:27:59 to 19-10-2020 10:28:02.

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 1520957 1520957 0 0.00 0 13578 7 0 0
doc_list_biud 1520957 1520957 0 0.00 0 28304 31 0 0
sp_add_invoice_to_stock 5089 4960 129 2.53 25 173491 17098 0 129
sp_cancel_adding_invoice 5689 5689 0 0.00 5 54631 814 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 8856 323 0 0
sp_cancel_customer_reserve 557635 557635 0 0.00 1 22292 69 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 4766 79 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 6961 106 0 0
sp_cancel_supplier_invoice 6364 6359 5 0.07 2 25065 871 0 5
sp_cancel_supplier_order 403 403 0 0.00 5 1026739 118835 0 0
sp_cancel_write_off 9807 9807 0 0.00 1 11354 127 0 0
sp_client_order 20113 20113 0 0.00 4 34236 364 0 0
sp_customer_reserve 456132 456132 0 0.00 0 112285 169 0 0
sp_fill_shopping_cart 31038 31038 0 0.00 1 12256 42 0 0
sp_fill_shopping_cart_clo_res 456126 456126 0 0.00 0 25324 17 0 0
sp_get_clo_for_invoice 4946 4946 0 0.00 0 40765 372 0 0
sp_kill_cost_storno 16609 16609 0 0.00 0 9736 57 0 0
sp_kill_qstorno_ret_qs2qd 1131839 1131839 0 0.00 0 25060 37 0 0
sp_kill_qty_storno 596396 596396 0 0.00 0 28304 79 0 0
sp_lock_selected_doc 20480 20480 0 0.00 0 9965 7 0 0
sp_make_cost_storno 27549 27549 0 0.00 0 13568 54 0 0
sp_multiply_rows_for_pdistr 27549 27549 0 0.00 0 6571 29 0 0
sp_multiply_rows_for_qdistr 20113 20113 0 0.00 0 28578 191 0 0
sp_pay_from_customer 6973 6973 0 0.00 4 15086 103 0 0
sp_pay_to_supplier 5802 5802 0 0.00 5 17640 128 0 0
sp_payment_common 12775 12775 0 0.00 1 12276 20 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 8818 286 0 0
sp_qd_handle_on_invoice_upd_sts 10633 10633 0 0.00 0 28304 344 0 0
sp_qd_handle_on_reserve_upd_sts 9830 9830 0 0.00 0 14793 59 0 0
sp_reserve_write_off 9830 9830 0 0.00 6 14817 184 0 0
sp_supplier_invoice 5468 5468 0 0.00 7 103882 3514 0 0
sp_supplier_order 5466 5466 0 0.00 5 68304 1388 0 0
srv_aggregate_perf_data 118 118 0 0.00 2677 108241 13749 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1664 144420 48570 0 0
srv_find_qd_qs_mism 1051739 1051739 0 0.00 0 12286 6 0 0
srv_make_invnt_saldo 511 511 0 0.00 44 85903 2743 0 0
srv_make_money_saldo 692 692 0 0.00 1 12834 544 0 0
srv_recalc_idx_stat 5 5 0 0.00 120425 527532 272845 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4961 4961 0 0.00 0 11578 153 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 1771 71 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 6423 16 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 536 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 386 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1996 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 2337 21 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 14083 1510 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 15824 33 0 0
t$perf-norm:sp_client_order 20113 20113 0 0.00 0 8430 16 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1326 4 0 0
t$perf-norm:sp_pay_from_customer 6973 6973 0 0.00 0 6561 8 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 3071 6 0 0
t$perf-norm:sp_reserve_write_off 9830 9830 0 0.00 0 4419 6 0 0
t$perf-norm:sp_supplier_invoice 5468 5468 0 0.00 0 9457 18 0 0
t$perf-norm:sp_supplier_order 5466 5466 0 0.00 0 8841 15 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 257 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 410 7 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 968 10 0 0
t$perf-norm:srv_make_money_saldo 692 692 0 0.00 0 2134 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10146 9888 258 2.54 0 4434 15 0 258
v_all_customers 36258 36258 0 0.00 0 84 1 0 0
v_all_suppliers 30020 30020 0 0.00 0 18 1 0 0
v_all_wares 40226 40226 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3757 15 0 0
v_cancel_client_order 3458 3458 0 0.00 0 5255 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 3619 8 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 1206 4 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3668 9 0 0
v_cancel_supplier_invoice 790 780 10 1.26 0 339 11 0 10
v_cancel_supplier_order 806 806 0 0.00 0 3660 10 0 0
v_cancel_write_off 2014 2014 0 0.00 0 1421 14 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 704 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 4827 19 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 4647 5 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 1264 2 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 5260 5 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 4007 6 0 0
v_min_id_clo_res 974 974 0 0.00 0 1383 4 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 6368 11 0 0
v_min_non_paid_invoice 5802 5802 0 0.00 0 3655 11 0 0
v_min_non_paid_realizn 6973 6973 0 0.00 0 6429 13 0 0
v_our_firm 10172 10172 0 0.00 0 13 1 0 0
v_reserve_write_off 19660 19660 0 0.00 0 3650 5 0 0
x_lock_depdocs_on_canc_invoice 11658 11658 0 0.00 0 12022 105 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 11171 337 0 0
x_make_qstorno_1000_1200 5466 5466 0 0.00 2 68274 1316 0 0
x_make_qstorno_1200_2000 5468 5468 0 0.00 3 103682 3349 0 0
x_make_qstorno_2100_3300 456132 456132 0 0.00 0 112284 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2020 10:28:03 to 19-10-2020 10:28:07.

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
07:56:58 7 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:59 25977 8192000000 9742793824 10042871808 816890240 9.067 100 87 174 0 846056832 7999344 21167248
07:59:30 24232 8192000000 9783858960 10093080576 855404032 9.454 100 91 182 0 889686832 13125456 21157344
08:01:05 26007 8192000000 9829054384 10148421632 885242384 9.752 100 89 178 0 933876960 28006528 20628048
08:02:39 12145 8192000000 9872077216 10204966912 924934416 10.145 100 76 154 0 980128128 38390480 16803232
08:03:55 7179 8192000000 9934503376 10280075264 968021504 10.567 100 80 160 0 1043435872 59219568 16194800
08:05:04 3516 8192000000 9983197104 10340851712 1002436064 10.902 100 79 156 2 1097021600 78717840 15867696
08:06:09 1774 8192000000 10007323600 10369855488 1032463632 11.192 100 86 172 0 1125150144 75312352 17374160
08:07:12 1938 8192000000 10045548288 10418151424 1057685440 11.434 100 89 177 2 1152265680 76138176 18442064
08:08:14 15478 8192000000 10001417056 10382794752 1067724608 11.530 100 60 118 0 1113011760 33361824 11925328
08:09:30 9062 8192000000 9989847792 10381533184 1074811712 11.598 100 30 56 3 1107425648 26768640 5845296
08:10:40 5732 8192000000 10013910784 10409906176 1079953696 11.647 100 37 73 0 1130043760 42789536 7300528
08:11:48 72534 8192000000 10002911168 10412011520 1084574944 11.691 100 25 50 2 1105836560 15537904 5723712
08:14:02 4340 8192000000 10037101168 10443431936 1087953456 11.723 100 41 82 0 1139169232 42105664 9110112
08:15:07 4942 8192000000 10000483120 10399158272 1075626384 11.606 99 43 87 4 1113403392 28799072 8977936
08:16:14 5624 8192000000 10008872944 10393993216 1082139264 11.668 100 47 93 1 1113016448 20732528 10144656
08:17:22 30361 8192000000 9973088240 10369179648 1021113824 11.083 95 39 78 1 1047081664 16454864 9512976
08:18:54 3039 8192000000 9933091760 10297540608 971802576 10.604 95 53 106 1 1005930768 22360512 11767680
08:19:59 6266 8192000000 9932619184 10310520832 1015157504 11.025 100 50 99 2 1044315472 17952272 11205696
08:21:06 69389 8192000000 9942211344 10340712448 987036704 10.753 95 28 56 2 1008569600 15129904 6402992
08:23:18 5844 8192000000 9964544976 10359472128 1056958864 11.427 100 46 92 2 1080474976 13688784 9827328
08:24:25 7052 8192000000 9991856320 10378784768 1072111344 11.572 100 46 94 1 1103447616 20620480 10715792
08:25:33 13260 8192000000 9982112576 10385498112 1077130256 11.620 100 34 68 2 1109778240 25689984 6958000
08:26:46 22507 8192000000 10013218192 10420649984 1093124832 11.772 100 25 50 2 1123011296 24837424 5049040
08:28:16 9069 8192000000 10084553408 10478608384 1117246560 12.001 100 62 124 1 1188546384 58104704 13195120
08:29:30 28566 8192000000 10109696592 10513485824 1127770384 12.100 100 52 104 3 1214166320 75728080 10667856
08:31:03 10304 8192000000 10114248816 10517508096 1141753136 12.232 100 59 118 3 1221372992 66609280 13010576
08:32:18 17375 8192000000 10149906672 10564112384 1146175264 12.274 100 55 108 2 1268240368 110804704 11260400
08:33:41 10554 8192000000 10171486608 10584346624 1156902368 12.374 100 49 98 2 1280343776 112530960 10910448
08:34:55 10905 8192000000 10217211216 10633986048 1173387632 12.528 100 55 110 1 1321418544 134917248 13113664
08:36:11 3398 8192000000 10266398800 10668355584 1176043296 12.553 100 71 142 0 1371293056 179543424 15706336
08:37:19 3039 8192000000 10287770864 10695274496 1172774432 12.523 100 67 133 0 1397545328 210576944 14193952
08:38:29 11235 8192000000 10270586064 10688688128 1172383632 12.519 100 56 112 3 1374408496 189143552 12881312
08:39:45 1784 8192000000 10316019600 10714005504 1167494688 12.473 99 80 162 1 1419433152 233343616 18594848
08:40:53 3955 8192000000 10376884192 10773557248 1171132304 12.507 100 89 177 0 1472709904 280797296 20780304
08:42:00 3227 8192000000 10412271200 10810007552 1136905728 12.186 98 75 151 2 1501163392 346295840 17961824
08:43:06 1662 8192000000 10452388096 10847338496 1166271136 12.462 100 92 181 0 1551115824 363511792 21332896
08:44:14 4031 8192000000 10460735184 10858450944 1160919264 12.412 100 77 153 2 1558757472 378456128 19382080
08:45:25 5539 8192000000 10511784048 10919956480 1149646752 12.306 100 57 114 0 1607213440 443088576 14478112
08:46:35 11736 8192000000 10481549056 10902601728 1153102352 12.339 100 42 84 1 1590247040 425697040 11447648
08:48:16 18008 8192000000 10485519312 10932572160 1156475920 12.370 100 34 68 1 1588014480 421455232 10083328
08:49:42 15281 8192000000 10430230608 10983022592 1145587536 12.268 100 23 46 2 1558658032 406254528 6815968
08:51:02 15444 8192000000 10396130528 10888835072 1124646560 12.071 100 19 38 0 1525353936 394724816 5982560
08:52:26 61322 8192000000 10081601872 10576125952 1129413120 12.116 99 20 40 0 1247861296 114194912 4253264
08:54:28 40221 8192000000 10088999536 10522845184 1127382960 12.097 100 40 77 0 1171778992 35621872 8774160
08:56:20 42376 8192000000 10078988848 10512306176 1108186960 11.915 98 45 90 0 1159546080 41033984 10325136
08:58:02 144420 8192000000 10140319616 10561372160 1059855504 11.455 93 19 38 2 1131498960 67621696 4021760
09:01:36 34783 8192000000 10112540384 10625953792 1114530480 11.975 99 9 18 1 1212809248 96599936 1678832
09:03:16 87555 8192000000 10118304288 10577281024 1115285136 11.982 99 18 37 0 1195350480 76634448 3430896
09:06:24 40038 8192000000 10041889408 10474450944 1110566368 11.938 98 24 48 3 1150059008 34259440 5233200
09:08:06 45154 8192000000 10070899808 10531721216 1135521376 12.173 100 31 62 1 1193918960 52261904 6135680
09:09:54 26227 8192000000 10088060128 10520055808 1141303232 12.228 100 17 34 0 1172346256 27700128 3342896
09:11:24 42471 8192000000 10134850224 10581540864 1145498128 12.267 100 27 54 0 1229144368 78283376 5362864
09:13:30 69789 8192000000 10118018544 10568568832 1138748096 12.204 99 16 32 2 1221365856 79203696 3414064
09:15:40 47440 8192000000 10114316048 10573295616 1153176592 12.339 100 14 28 2 1201676832 45556928 2943312
09:17:29 85182 8192000000 10057097792 10488442880 1123030400 12.056 98 22 44 0 1164107024 36461952 4614672
09:20:59 43580 8192000000 10067488624 10519146496 1130672224 12.128 99 9 18 0 1167387904 34880896 1834784
09:22:43 76552 8192000000 10052356304 10488090624 1060954064 11.466 93 12 24 0 1105881152 42142720 2784368
09:25:00 69949 8192000000 10050851344 10497257472 1084767792 11.693 96 15 31 0 1114382048 26258880 3355376
09:27:10 34165 8192000000 10090610752 10520735744 1084897264 11.694 96 18 36 2 1131408064 42694656 3816144
09:28:45 39970 8192000000 10100366304 10533883904 1104965936 11.885 98 23 45 0 1143039488 33208816 4864736
09:30:25 26844 8192000000 10113666080 10559819776 1110585760 11.938 98 26 51 1 1206892432 91250992 5055680
09:32:10 66188 8192000000 10089011760 10553389056 1090073904 11.743 97 24 48 3 1186962944 91366832 5522208
09:34:17 55223 8192000000 10077623472 10495864832 1101895520 11.856 98 36 72 2 1172923680 63870896 7157264
09:36:29 94585 8192000000 10072956016 10506608640 1079270816 11.641 95 25 48 2 1137203520 51959936 5972768
09:39:31 93061 8192000000 10052156416 10497581056 1087196352 11.716 97 13 25 1 1142474304 52724384 2553568
09:42:04 83097 8192000000 10089765664 10551738368 1117239104 12.001 99 12 24 2 1177547680 57565040 2743536
09:44:30 70183 8192000000 10103832688 10562228224 1107019488 11.904 98 15 30 0 1184378944 73924688 3434768
09:46:54 104539 8192000000 10173533824 10611916800 1115871280 11.988 98 20 40 0 1224645488 104499280 4274928
09:49:39 38884 8192000000 10078482960 10525151232 1096546352 11.805 97 28 56 2 1168324080 65792160 5985568
09:51:18 98666 8192000000 10081626624 10537263104 1064998352 11.504 95 17 34 1 1145311408 76839520 3473536
09:54:04 103734 8192000000 10037293008 10492420096 1098569104 11.824 99 25 50 3 1144544160 40873408 5101648
09:57:24 92698 8192000000 10099488752 10602909696 1100219600 11.840 98 10 20 0 1200959616 98632768 2107248
10:00:38 82943 8192000000 10083092032 10533535744 1138635392 12.203 100 19 36 0 1202566544 59899264 4031888
10:03:03 67842 8192000000 10078112048 10536140800 1124607632 12.070 98 11 22 0 1163300816 36543920 2149264
10:05:15 42190 8192000000 10085090656 10519117824 1123949024 12.064 98 25 50 1 1176979696 47233680 5796992
10:06:57 87178 8192000000 10097107296 10607484928 1137304592 12.190 99 15 30 0 1212294048 72168336 2821120
10:09:34 23110 8192000000 10094766608 10523889664 1146617408 12.278 100 37 74 2 1180925280 26686144 7621728
10:11:00 138354 8192000000 10046278352 10525609984 1033168688 11.199 90 14 28 0 1090582032 54477920 2935424
10:14:48 102198 8192000000 10080055248 10550464512 1125126384 12.075 100 8 16 0 1181384032 54465296 1792352
10:17:57 100644 8192000000 10113422304 10549903360 1100693920 11.844 97 21 42 0 1152134784 47165088 4275776
10:20:39 71196 8192000000 10043465264 10470268928 1068679360 11.539 95 14 28 1 1106662464 34986144 2996960
10:22:51 42784 8192000000 10064317760 10492542976 1100812432 11.845 99 28 56 1 1133226608 26508976 5905200
10:24:41 67011 8192000000 10133904000 10606084096 1094330816 11.784 98 23 46 1 1161538464 62277856 4929792
10:26:49 19335 8192000000 8909252080 9138794496 59097200 0.716 6 4 4 2 73814640 13769520 947920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-10-2020 10:28:09 to 19-10-2020 10:28:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 129 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2020 10:28:24 to 19-10-2020 10:28:26.

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          239136
MON$OLDEST_ACTIVE               239137
MON$OLDEST_SNAPSHOT             239137
MON$NEXT_TRANSACTION            239137
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                       2091456
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2228 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.2228 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 ---
--- end of diff output ---

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

19.10.20 10:28:27. 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-10-19 10:28:29 No severe PSQL-related problems occured
19.10.20 10:28:31. End of report.