Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.01.2021 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 16504788 kB
MemAvailable 31217016 kB
Buffers 20 kB
Cached 15048580 kB
SwapCached 4864 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 2021-01-20 04:26:57
DTS_END                         2021-01-20 04:26:57.0070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55570
TRN_ID                          228064
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: 9001.42 24210 1080170
customer order: creation 163.86 390 19663
customer order: refuse 14.30 300 1716
order to supplier: creation 42.20 1252 5064
order to supplier: removal 3.41 111860 409
invoice (draft): creation 42.19 3230 5063
invoice (draft): removal 51.20 876 6144
invoice accept: apply 39.82 18328 4778
invoice accept: cancel 46.56 858 5587
customer reserve: creation 3727.19 179 447263
customer reserve: removal 4589.31 66 550717
realization accept: apply 78.93 163 9472
realization accept: cancel 83.36 123 10003
payment from customer: creation 56.03 111 6723
payment from customer: removal 5.41 116 649
payment to supplier: creation 44.79 104 5375
payment to supplier: removal 2.88 90 345
service: total inventory turnovers 4.08 3142 489
service: total monetary turnovers 5.05 726 606
service: aggregate perf. data 0.83 13657 100
service: refresh index statistics 0.03 328628 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-01-2021 04:28:41 to 20-01-2021 04:28:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3149
TEST_TIME 2 2693
TEST_TIME 3 2822
TEST_TIME 4 3880
TEST_TIME 5 4797
TEST_TIME 6 4539
TEST_TIME 7 4941
TEST_TIME 8 4937
TEST_TIME 9 5000
TEST_TIME 10 4910
TEST_TIME 11 4994
TEST_TIME 12 5200
TEST_TIME 13 5429
TEST_TIME 14 5518
TEST_TIME 15 5722
TEST_TIME 16 6011
TEST_TIME 17 6113
TEST_TIME 18 6700
TEST_TIME 19 7578
TEST_TIME 20 7723
TEST_TIME 21 8064
TEST_TIME 22 8258
TEST_TIME 23 8132
TEST_TIME 24 8388
TEST_TIME 25 9160
TEST_TIME 26 9291
TEST_TIME 27 10346
TEST_TIME 28 10994
TEST_TIME 29 11029
TEST_TIME 30 11185
TEST_TIME 31 10994
TEST_TIME 32 10872
TEST_TIME 33 11125
TEST_TIME 34 10840
TEST_TIME 35 10610
TEST_TIME 36 10482
TEST_TIME 37 10301
TEST_TIME 38 10284
TEST_TIME 39 10315
TEST_TIME 40 10206
TEST_TIME 41 10045
TEST_TIME 42 10114
TEST_TIME 43 10088
TEST_TIME 44 9978
TEST_TIME 45 9789
TEST_TIME 46 9734
TEST_TIME 47 9673
TEST_TIME 48 9977
TEST_TIME 49 9873
TEST_TIME 50 9891
TEST_TIME 51 9852
TEST_TIME 52 9848
TEST_TIME 53 9794
TEST_TIME 54 9864
TEST_TIME 55 9826
TEST_TIME 56 9824
TEST_TIME 57 9828
TEST_TIME 58 9763
TEST_TIME 59 9697
TEST_TIME 60 9692
TEST_TIME 61 9555
TEST_TIME 62 9588
TEST_TIME 63 9558
TEST_TIME 64 9541
TEST_TIME 65 9487
TEST_TIME 66 9454
TEST_TIME 67 9478
TEST_TIME 68 9455
TEST_TIME 69 9523
TEST_TIME 70 9561
TEST_TIME 71 9553
TEST_TIME 72 9556
TEST_TIME 73 9550
TEST_TIME 74 9566
TEST_TIME 75 9531
TEST_TIME 76 9585
TEST_TIME 77 9598
TEST_TIME 78 9575
TEST_TIME 79 9579
TEST_TIME 80 9462
TEST_TIME 81 9395
TEST_TIME 82 9335
TEST_TIME 83 9273
TEST_TIME 84 9193
TEST_TIME 85 9152
TEST_TIME 86 9111
TEST_TIME 87 9183
TEST_TIME 88 9190
TEST_TIME 89 9151
TEST_TIME 90 9137
TEST_TIME 91 9141
TEST_TIME 92 9109
TEST_TIME 93 9135
TEST_TIME 94 9286
TEST_TIME 95 9258
TEST_TIME 96 9290
TEST_TIME 97 9263
TEST_TIME 98 9246
TEST_TIME 99 9323
TEST_TIME 100 9245
TEST_TIME 101 9179
TEST_TIME 102 9118
TEST_TIME 103 9099
TEST_TIME 104 9072
TEST_TIME 105 9051
TEST_TIME 106 9039
TEST_TIME 107 9055
TEST_TIME 108 9048
TEST_TIME 109 9019
TEST_TIME 110 9006
TEST_TIME 111 9027
TEST_TIME 112 8998
TEST_TIME 113 9079
TEST_TIME 114 9143
TEST_TIME 115 9131
TEST_TIME 116 9183
TEST_TIME 117 9117
TEST_TIME 118 9056
TEST_TIME 119 9032
TEST_TIME 120 9006

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-01-2021 04:28:44 to 20-01-2021 04:28:48.

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 1491857 1491857 0 0.00 0 43414 7 0 0
doc_list_biud 1491857 1491857 0 0.00 0 45190 31 0 0
sp_add_invoice_to_stock 4857 4778 79 1.62 41 193042 18329 0 79
sp_cancel_adding_invoice 5587 5587 0 0.00 4 77102 859 0 0
sp_cancel_client_order 1716 1716 0 0.00 5 8946 300 0 0
sp_cancel_customer_reserve 550717 550717 0 0.00 1 37336 67 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 15960 117 0 0
sp_cancel_pay_to_supplier 345 345 0 0.00 3 7616 91 0 0
sp_cancel_supplier_invoice 6149 6144 5 0.08 2 26546 877 0 5
sp_cancel_supplier_order 409 409 0 0.00 7 1293157 111860 0 0
sp_cancel_write_off 10003 10003 0 0.00 1 10341 123 0 0
sp_client_order 19663 19663 0 0.00 4 18008 391 0 0
sp_customer_reserve 447263 447263 0 0.00 0 117591 180 0 0
sp_fill_shopping_cart 29789 29789 0 0.00 1 17324 40 0 0
sp_fill_shopping_cart_clo_res 447257 447257 0 0.00 0 18199 16 0 0
sp_get_clo_for_invoice 4772 4772 0 0.00 0 30702 378 0 0
sp_kill_cost_storno 16584 16584 0 0.00 0 15633 58 0 0
sp_kill_qstorno_ret_qs2qd 1117990 1117990 0 0.00 0 37330 36 0 0
sp_kill_qty_storno 588820 588820 0 0.00 0 45190 77 0 0
sp_lock_selected_doc 19764 19764 0 0.00 0 6875 4 0 0
sp_make_cost_storno 26342 26342 0 0.00 0 17291 54 0 0
sp_multiply_rows_for_pdistr 26342 26342 0 0.00 0 17047 28 0 0
sp_multiply_rows_for_qdistr 19663 19663 0 0.00 0 17990 218 0 0
sp_pay_from_customer 6723 6723 0 0.00 4 17244 111 0 0
sp_pay_to_supplier 5375 5375 0 0.00 5 17261 104 0 0
sp_payment_common 12098 12098 0 0.00 1 17207 23 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 8941 273 0 0
sp_qd_handle_on_invoice_upd_sts 10359 10359 0 0.00 0 45189 349 0 0
sp_qd_handle_on_reserve_upd_sts 9472 9472 0 0.00 0 17530 48 0 0
sp_reserve_write_off 9472 9472 0 0.00 6 17592 164 0 0
sp_supplier_invoice 5063 5063 0 0.00 7 80024 3231 0 0
sp_supplier_order 5064 5064 0 0.00 5 24014 1253 0 0
srv_aggregate_perf_data 100 100 0 0.00 2865 123572 13658 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2248 152118 51999 0 0
srv_find_qd_qs_mism 1033793 1033793 0 0.00 0 43410 7 0 0
srv_make_invnt_saldo 489 489 0 0.00 40 137933 3143 0 0
srv_make_money_saldo 606 606 0 0.00 1 19111 727 0 0
srv_recalc_idx_stat 4 4 0 0.00 88471 484340 328629 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 4779 4779 0 0.00 0 14809 178 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 11539 107 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 7165 18 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 3974 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 647 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 345 345 0 0.00 0 1393 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1160 18 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 56787 1557 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 944 3 0 0
t$perf-norm:sp_client_order 19663 19663 0 0.00 0 46486 19 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 2467 7 0 0
t$perf-norm:sp_pay_from_customer 6723 6723 0 0.00 0 61743 21 0 0
t$perf-norm:sp_pay_to_supplier 5375 5375 0 0.00 0 7995 10 0 0
t$perf-norm:sp_reserve_write_off 9472 9472 0 0.00 0 5848 11 0 0
t$perf-norm:sp_supplier_invoice 5063 5063 0 0.00 0 4778 14 0 0
t$perf-norm:sp_supplier_order 5064 5064 0 0.00 0 4818 21 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 356 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 6445 116 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 2841 13 0 0
t$perf-norm:srv_make_money_saldo 606 606 0 0.00 0 3184 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 6 0 0
v_add_invoice_to_stock 9702 9544 158 1.62 0 6732 17 0 158
v_all_customers 35940 35940 0 0.00 0 85 1 0 0
v_all_suppliers 28148 28148 0 0.00 0 121 1 0 0
v_all_wares 39326 39326 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1727 8 0 0
v_cancel_client_order 3432 3432 0 0.00 0 1840 5 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2020 7 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 220 3 0 0
v_cancel_payment_to_supplier 690 690 0 0.00 0 1678 8 0 0
v_cancel_supplier_invoice 798 788 10 1.25 0 2113 16 0 10
v_cancel_supplier_order 818 818 0 0.00 0 1188 5 0 0
v_cancel_write_off 1824 1824 0 0.00 0 1321 11 0 0
v_max_id_clo_ord 5064 5064 0 0.00 0 3742 3 0 0
v_max_id_clo_res 937 937 0 0.00 0 2119 20 0 0
v_max_id_ord_sup 5062 5062 0 0.00 0 6694 9 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 326 2 0 0
v_max_non_paid_realizn 3456 3456 0 0.00 0 764 3 0 0
v_min_id_clo_ord 5064 5064 0 0.00 0 17238 9 0 0
v_min_id_clo_res 937 937 0 0.00 0 3324 11 0 0
v_min_id_ord_sup 5062 5062 0 0.00 0 6320 6 0 0
v_min_non_paid_invoice 5375 5375 0 0.00 0 5873 8 0 0
v_min_non_paid_realizn 6723 6723 0 0.00 0 17203 24 0 0
v_our_firm 9920 9920 0 0.00 0 20 1 0 0
v_reserve_write_off 18944 18944 0 0.00 0 2426 5 0 0
x_lock_depdocs_on_canc_invoice 11337 11337 0 0.00 0 15835 106 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6933 307 0 0
x_make_qstorno_1000_1200 5064 5064 0 0.00 1 23995 1182 0 0
x_make_qstorno_1200_2000 5063 5063 0 0.00 4 79923 3074 0 0
x_make_qstorno_2100_3300 447263 447263 0 0.00 0 117591 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-01-2021 04:28:49 to 20-01-2021 04:28:53.

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:59 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
01:58:00 35996 8192000000 9720687248 10012708864 793377520 8.829 100 94 188 0 824811952 7892992 23541440
01:59:40 28098 8192000000 9751007168 10052149248 824864272 9.148 100 88 176 0 860372048 14131792 21375984
02:01:23 12614 8192000000 9816941008 10136358912 874037552 9.640 100 82 164 1 928611840 35563520 19010768
02:02:39 15170 8192000000 9867146848 10201264128 916969008 10.066 100 76 151 2 973604144 40182704 16452432
02:04:04 9383 8192000000 9929447936 10277789696 966839360 10.556 100 77 153 0 1045936816 62624320 16473136
02:05:16 4446 8192000000 9979505216 10333921280 1003212032 10.910 100 83 166 0 1093143488 73211984 16719472
02:06:22 4015 8192000000 10004994208 10372653056 1037718064 11.243 100 80 160 0 1119601632 65626864 16256704
02:07:28 2030 8192000000 10006780672 10378514432 1060681120 11.463 100 79 160 0 1113352000 36698576 15972304
02:08:34 15463 8192000000 9993566048 10395459584 1067397232 11.527 100 40 78 2 1138420800 62561120 8462448
02:09:50 8474 8192000000 9995423216 10382655488 1079734784 11.645 100 28 56 1 1108111248 22828768 5547696
02:10:59 6174 8192000000 9996635744 10389389312 1083195184 11.678 100 50 99 1 1117961760 24754384 10012192
02:12:09 54327 8192000000 10001638896 10409476096 1087299024 11.717 100 43 86 1 1115169344 18744592 9125728
02:14:06 10949 8192000000 10005894496 10411126784 1087289584 11.717 100 30 60 2 1121808960 28328528 6190848
02:15:20 7904 8192000000 9991317344 10403057664 1093838240 11.779 100 31 60 3 1115317280 14253312 7225728
02:16:29 17361 8192000000 10004454448 10412658688 1053080400 11.390 96 19 38 0 1079448560 22173344 4194816
02:17:49 37768 8192000000 9978455600 10383011840 1020875248 11.080 94 28 55 2 1041580192 14977344 5727600
02:19:28 7681 8192000000 9974875888 10354065408 1023019104 11.101 97 34 71 0 1061994416 31175760 7799552
02:20:37 8658 8192000000 9943012432 10322448384 1016098960 11.034 99 26 49 1 1045155216 23089312 5966944
02:21:51 8152 8192000000 9902396800 10281680896 963271360 10.521 95 41 82 0 983869472 10730992 9867120
02:23:01 42812 8192000000 9957138672 10354307072 1031705600 11.185 100 34 68 2 1072193328 33485984 7001744
02:24:51 8443 8192000000 9978197856 10377805824 1048655040 11.348 100 55 109 1 1082578816 21207632 12716144
02:26:01 10436 8192000000 9991785584 10394157056 1070304832 11.555 100 33 66 3 1093681488 16249840 7126816
02:27:16 8744 8192000000 10012302448 10406445056 1093002528 11.771 100 43 86 1 1118858800 16325888 9530384
02:28:29 82975 8192000000 10068689488 10480660480 1102835120 11.865 100 36 72 1 1172363632 61893744 7634768
02:30:56 18346 8192000000 10112188928 10522849280 1122964528 12.055 100 44 86 0 1207819024 75106768 9747728
02:32:18 6370 8192000000 10128930560 10537951232 1138755840 12.204 100 60 119 2 1240249296 88852896 12640560
02:33:31 3134 8192000000 10214927392 10609856512 1152067392 12.329 100 81 158 0 1319303824 150418912 16817520
02:34:39 17335 8192000000 10226370000 10638114816 1162388288 12.426 100 54 107 3 1324836496 150373824 12074384
02:36:04 4913 8192000000 10247577104 10661367808 1174526528 12.539 100 50 99 1 1351564624 165356000 11682096
02:37:12 6109 8192000000 10310807360 10721230848 1182259728 12.611 100 54 108 0 1413074800 218615872 12199200
02:38:25 6777 8192000000 10326090864 10741764096 1177037888 12.563 100 60 119 2 1433556176 243149024 13369264
02:39:37 3406 8192000000 10345334544 10759593984 1186830080 12.654 100 65 129 0 1447545520 246101248 14614192
02:40:45 2701 8192000000 10385694096 10798870528 1184720224 12.634 100 61 123 1 1486483856 287166960 14596672
02:41:52 5069 8192000000 10430991344 10839220224 1187350464 12.659 100 78 157 0 1530950880 324581232 19019184
02:43:05 2247 8192000000 10437298336 10847846400 1172656800 12.522 100 72 143 4 1541855536 352531280 16667456
02:44:12 3181 8192000000 10407319312 10838269952 1159421680 12.398 100 67 132 1 1504908976 329396784 16090512
02:45:23 11888 8192000000 10418549888 10847133696 1157061280 12.376 100 60 120 1 1524543600 353321840 14160480
02:46:41 14294 8192000000 10404576448 10842021888 1146259456 12.274 99 43 87 0 1495262768 338308704 10694608
02:48:16 18569 8192000000 10405502480 10856087552 1156820592 12.373 100 41 82 1 1521676480 354560304 10295584
02:49:42 11957 8192000000 10441176864 10864312320 1124820544 12.073 99 37 74 0 1528212528 392944736 10447248
02:51:25 22924 8192000000 10409536112 10908962816 1089531952 11.738 97 30 60 0 1497862144 400194448 8135744
02:53:13 35196 8192000000 10171741696 10660642816 1095163632 11.792 98 16 30 2 1342925024 243545072 4216320
02:54:50 46873 8192000000 10118276640 10594365440 1107416560 11.908 99 12 24 1 1183377104 73009712 2950832
02:57:48 41340 8192000000 10092965024 10506461184 1108580880 11.919 99 39 71 2 1171707344 56120320 7006144
02:59:33 109619 8192000000 10040957024 10475823104 1108093312 11.914 98 26 52 0 1150047200 36622528 5331360
03:02:28 18877 8192000000 10077381232 10511249408 1136880720 12.186 100 30 60 0 1176753968 34132432 5740816
03:03:47 44426 8192000000 10120321760 10576904192 1139738576 12.213 100 31 61 3 1215020528 68962480 6319472
03:05:48 131771 8192000000 10092195120 10535723008 1079353344 11.641 95 19 38 0 1124780352 41150240 4276768
03:09:39 116823 8192000000 10103809936 10556977152 1109961200 11.932 97 12 24 0 1171102384 58438048 2703136
03:12:36 48469 8192000000 10157200464 10644619264 1141363024 12.228 100 13 26 0 1255679376 111297152 3019200
03:14:26 48092 8192000000 10081618192 10641920000 1135716496 12.175 100 8 16 1 1242398352 105119152 1562704
03:16:14 36880 8192000000 10120198224 10571304960 1133017184 12.150 99 48 96 0 1176477840 33561632 9899024
03:18:08 56471 8192000000 10098855376 10537197568 1139258256 12.209 99 19 38 0 1172003600 29021184 3724160
03:20:05 60496 8192000000 10112441888 10579881984 1141820368 12.233 99 28 56 2 1221056496 73419296 5816832
03:22:06 38319 8192000000 10077618288 10519736320 1098646336 11.825 96 24 48 1 1138963760 35652256 4665168
03:23:45 30694 8192000000 10091734752 10523369472 1119029760 12.018 98 30 58 1 1184006880 58797696 6179424
03:25:16 136954 8192000000 10137146288 10640711680 1123107712 12.056 98 19 38 0 1219071424 91660624 4303088
03:28:44 60972 8192000000 10080459744 10573000704 1137194112 12.189 100 9 18 0 1172408112 33192816 2021184
03:30:53 83677 8192000000 10064660704 10505224192 1076011248 11.609 94 14 28 0 1120426080 41201392 3213440
03:33:17 58538 8192000000 10084734496 10527739904 1052731120 11.387 93 20 40 0 1084696160 27719232 4245808
03:35:34 100947 8192000000 10067741152 10540929024 1087753264 11.721 97 19 37 1 1189289968 97270496 4266208
03:38:21 50394 8192000000 10095229328 10556575744 1105302880 11.888 99 37 73 3 1185900048 72803120 7794048
03:40:14 46158 8192000000 10095836496 10542039040 1098192272 11.820 98 42 84 2 1166421792 59921648 8307872
03:42:03 41459 8192000000 10136402080 10609012736 1114864656 11.978 99 47 94 1 1182014736 57972368 9177712
03:43:46 152118 8192000000 10070286736 10573180928 1074283616 11.593 95 7 14 0 1138085120 62717680 1083824
03:47:19 89286 8192000000 10032534464 10483892224 1087146768 11.716 97 14 28 1 1114144592 24739072 2258752
03:49:50 104646 8192000000 10093171040 10555420672 1124583392 12.070 100 14 28 0 1166967200 39506032 2877776
03:52:40 74855 8192000000 10074190928 10607104000 1107202928 11.906 98 15 30 0 1191692848 81155536 3334384
03:54:57 51088 8192000000 10087127552 10528391168 1122198224 12.048 99 18 36 0 1164132688 38056176 3878288
03:56:49 58146 8192000000 10112214352 10563448832 1141889392 12.233 100 16 32 0 1221691168 76579520 3222256
03:59:02 66107 8192000000 10198145664 10698690560 1148818848 12.298 100 30 60 0 1272943984 117990800 6134336
04:02:06 62630 8192000000 10083125632 10529263616 1138620032 12.203 100 26 52 1 1176075136 32689472 4765632
04:04:24 38838 8192000000 10109443344 10532405248 1116793456 11.997 99 47 94 1 1180301744 54499776 9008512
04:06:04 101536 8192000000 10063944944 10533441536 1114221024 11.972 99 15 30 0 1176058240 58694160 3143056
04:09:09 105022 8192000000 10080149584 10556878848 1111692976 11.948 98 11 22 0 1154910544 41017616 2199952
04:11:54 97311 8192000000 10072013152 10551582720 1141213824 12.227 100 20 40 0 1220560704 75360464 3986416
04:14:32 47530 8192000000 10070400560 10519138304 1106806880 11.902 97 10 20 1 1134916416 26206000 1903536
04:16:25 81939 8192000000 10079486288 10537025536 1065034656 11.505 94 17 34 0 1110767456 41996816 3735984
04:18:47 77046 8192000000 10134340800 10639847424 1120835888 12.035 99 22 44 0 1249123792 123530304 4757600
04:21:05 102080 8192000000 10058415648 10540564480 1071312864 11.565 95 17 34 2 1111542064 36640608 3588592
04:23:54 87492 8192000000 10065999232 10515668992 1113509520 11.966 99 19 38 1 1164906128 47585888 3810720
04:26:34 27850 8192000000 9902940704 10362023936 982327104 10.707 87 4 8 0 1009756560 27027680 401776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-01-2021 04:28:55 to 20-01-2021 04:29:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-01-2021 04:29:10 to 20-01-2021 04:29:12.

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          227081
MON$OLDEST_ACTIVE               228198
MON$OLDEST_SNAPSHOT             228198
MON$NEXT_TRANSACTION            228198
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                       2102336
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             784
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 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.2325 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 ---
:342: 
:343: mscs-test-161.budzdorov.ru    Wed Jan 20 04:27:12 2021
:344:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:345: 
:346: 
:347: mscs-test-161.budzdorov.ru    Wed Jan 20 04:27:12 2021
:348:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:349: 
--- end of diff output ---

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

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