Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.11.2020 13:27
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 15518864 kB
MemAvailable 31460132 kB
Buffers 0 kB
Cached 16086792 kB
SwapCached 4856 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-19 13:26:54
DTS_END                         2020-11-19 13:26:54.7550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46314
TRN_ID                          225040
ATT_ID                          615
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: 8961.53 20455 1075383
customer order: creation 168.39 410 20207
customer order: refuse 14.54 311 1745
order to supplier: creation 44.77 1247 5372
order to supplier: removal 3.49 115229 419
invoice (draft): creation 43.38 3314 5205
invoice (draft): removal 50.31 937 6037
invoice accept: apply 40.48 18259 4857
invoice accept: cancel 46.48 943 5577
customer reserve: creation 3702.51 182 444301
customer reserve: removal 4564.96 70 547795
realization accept: apply 80.16 164 9619
realization accept: cancel 79.03 120 9484
payment from customer: creation 57.47 110 6896
payment from customer: removal 5.48 80 657
payment to supplier: creation 46.65 113 5598
payment to supplier: removal 2.98 102 357
service: total inventory turnovers 3.65 3552 438
service: total monetary turnovers 5.88 644 705
service: aggregate perf. data 0.91 13089 109
service: refresh index statistics 0.04 250232 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-11-2020 13:27:43 to 19-11-2020 13:27:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3652
TEST_TIME 2 3340
TEST_TIME 3 4184
TEST_TIME 4 4424
TEST_TIME 5 4881
TEST_TIME 6 4613
TEST_TIME 7 4862
TEST_TIME 8 5213
TEST_TIME 9 5374
TEST_TIME 10 5521
TEST_TIME 11 5615
TEST_TIME 12 5597
TEST_TIME 13 5794
TEST_TIME 14 6026
TEST_TIME 15 6040
TEST_TIME 16 6288
TEST_TIME 17 6704
TEST_TIME 18 7137
TEST_TIME 19 7729
TEST_TIME 20 7632
TEST_TIME 21 8162
TEST_TIME 22 9126
TEST_TIME 23 9475
TEST_TIME 24 9343
TEST_TIME 25 9734
TEST_TIME 26 9894
TEST_TIME 27 10337
TEST_TIME 28 10344
TEST_TIME 29 10551
TEST_TIME 30 10781
TEST_TIME 31 10635
TEST_TIME 32 10414
TEST_TIME 33 10151
TEST_TIME 34 9985
TEST_TIME 35 9820
TEST_TIME 36 9797
TEST_TIME 37 9685
TEST_TIME 38 9797
TEST_TIME 39 9671
TEST_TIME 40 9725
TEST_TIME 41 9567
TEST_TIME 42 9490
TEST_TIME 43 9554
TEST_TIME 44 9665
TEST_TIME 45 9591
TEST_TIME 46 9695
TEST_TIME 47 9665
TEST_TIME 48 9680
TEST_TIME 49 9714
TEST_TIME 50 9773
TEST_TIME 51 9684
TEST_TIME 52 9742
TEST_TIME 53 9776
TEST_TIME 54 9714
TEST_TIME 55 9583
TEST_TIME 56 9460
TEST_TIME 57 9383
TEST_TIME 58 9313
TEST_TIME 59 9299
TEST_TIME 60 9220
TEST_TIME 61 9197
TEST_TIME 62 9164
TEST_TIME 63 9197
TEST_TIME 64 9241
TEST_TIME 65 9209
TEST_TIME 66 9227
TEST_TIME 67 9267
TEST_TIME 68 9260
TEST_TIME 69 9297
TEST_TIME 70 9310
TEST_TIME 71 9321
TEST_TIME 72 9366
TEST_TIME 73 9373
TEST_TIME 74 9327
TEST_TIME 75 9233
TEST_TIME 76 9217
TEST_TIME 77 9157
TEST_TIME 78 9092
TEST_TIME 79 9077
TEST_TIME 80 9047
TEST_TIME 81 9005
TEST_TIME 82 9028
TEST_TIME 83 8988
TEST_TIME 84 8980
TEST_TIME 85 9101
TEST_TIME 86 9100
TEST_TIME 87 9271
TEST_TIME 88 9247
TEST_TIME 89 9294
TEST_TIME 90 9301
TEST_TIME 91 9311
TEST_TIME 92 9224
TEST_TIME 93 9151
TEST_TIME 94 9094
TEST_TIME 95 9052
TEST_TIME 96 9080
TEST_TIME 97 9047
TEST_TIME 98 9069
TEST_TIME 99 9084
TEST_TIME 100 9091
TEST_TIME 101 9104
TEST_TIME 102 9203
TEST_TIME 103 9152
TEST_TIME 104 9212
TEST_TIME 105 9198
TEST_TIME 106 9207
TEST_TIME 107 9208
TEST_TIME 108 9133
TEST_TIME 109 9084
TEST_TIME 110 9094
TEST_TIME 111 9049
TEST_TIME 112 9005
TEST_TIME 113 8989
TEST_TIME 114 8955
TEST_TIME 115 8992
TEST_TIME 116 8971
TEST_TIME 117 8961
TEST_TIME 118 8975
TEST_TIME 119 8973
TEST_TIME 120 8967

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-11-2020 13:27:47 to 19-11-2020 13:27:50.

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 1487835 1487835 0 0.00 0 27029 7 0 0
doc_list_biud 1487835 1487835 0 0.00 0 40919 32 0 0
sp_add_invoice_to_stock 4996 4857 139 2.78 41 173247 18259 0 139
sp_cancel_adding_invoice 5577 5577 0 0.00 6 94258 943 0 0
sp_cancel_client_order 1745 1745 0 0.00 5 8264 312 0 0
sp_cancel_customer_reserve 547795 547795 0 0.00 1 40921 71 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 5569 81 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 3 8270 103 0 0
sp_cancel_supplier_invoice 6052 6037 15 0.24 2 28357 937 0 15
sp_cancel_supplier_order 419 419 0 0.00 6 1162558 115230 0 0
sp_cancel_write_off 9484 9484 0 0.00 1 15653 120 0 0
sp_client_order 20207 20207 0 0.00 4 24420 411 0 0
sp_customer_reserve 444301 444301 0 0.00 0 136918 183 0 0
sp_fill_shopping_cart 30773 30773 0 0.00 1 12565 39 0 0
sp_fill_shopping_cart_clo_res 444291 444291 0 0.00 0 29314 19 0 0
sp_get_clo_for_invoice 4841 4841 0 0.00 0 45671 369 0 0
sp_kill_cost_storno 16075 16075 0 0.00 0 11534 58 0 0
sp_kill_qstorno_ret_qs2qd 1111530 1111530 0 0.00 0 40912 38 0 0
sp_kill_qty_storno 585516 585516 0 0.00 0 40917 82 0 0
sp_lock_selected_doc 19939 19939 0 0.00 0 8682 6 0 0
sp_make_cost_storno 26953 26953 0 0.00 0 15276 47 0 0
sp_multiply_rows_for_pdistr 26953 26953 0 0.00 0 7817 30 0 0
sp_multiply_rows_for_qdistr 20207 20207 0 0.00 0 24411 249 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 14663 110 0 0
sp_pay_to_supplier 5598 5598 0 0.00 5 9855 114 0 0
sp_payment_common 12494 12494 0 0.00 1 8687 18 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 8259 274 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 33597 386 0 0
sp_qd_handle_on_reserve_upd_sts 9619 9619 0 0.00 0 20482 46 0 0
sp_reserve_write_off 9619 9619 0 0.00 7 20533 165 0 0
sp_supplier_invoice 5205 5205 0 0.00 6 69350 3315 0 0
sp_supplier_order 5372 5372 0 0.00 6 30742 1248 0 0
srv_aggregate_perf_data 109 109 0 0.00 2353 84650 13090 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1295 156491 44668 0 0
srv_find_qd_qs_mism 1029930 1029930 0 0.00 0 27029 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 34 99394 3552 0 0
srv_make_money_saldo 705 705 0 0.00 1 17946 645 0 0
srv_recalc_idx_stat 5 5 0 0.00 83852 411297 250233 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 0 18460 156 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 10401 97 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 3904 9 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 1645 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 1493 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 1602 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 346 346 0 0.00 0 324 7 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 22722 1647 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 1111 4 0 0
t$perf-norm:sp_client_order 20207 20207 0 0.00 0 5251 16 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 551 5 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 6893 10 0 0
t$perf-norm:sp_pay_to_supplier 5598 5598 0 0.00 0 4103 8 0 0
t$perf-norm:sp_reserve_write_off 9619 9619 0 0.00 0 6651 10 0 0
t$perf-norm:sp_supplier_invoice 5205 5205 0 0.00 0 5287 16 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 1752 11 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 146 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 613 20 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 453 4 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 3476 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 9958 9680 278 2.79 0 11304 17 0 278
v_all_customers 37078 37078 0 0.00 0 37 1 0 0
v_all_suppliers 29346 29346 0 0.00 0 34 1 0 0
v_all_wares 40414 40414 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 2706 9 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3664 8 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 5438 14 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 2795 9 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 586 4 0 0
v_cancel_supplier_invoice 722 692 30 4.15 0 3662 29 0 30
v_cancel_supplier_order 838 838 0 0.00 0 349 3 0 0
v_cancel_write_off 1848 1848 0 0.00 0 8345 20 0 0
v_max_id_clo_ord 5372 5372 0 0.00 0 2862 2 0 0
v_max_id_clo_res 959 959 0 0.00 0 4453 15 0 0
v_max_id_ord_sup 5194 5194 0 0.00 0 6307 5 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 150 2 0 0
v_max_non_paid_realizn 3535 3535 0 0.00 0 2407 4 0 0
v_min_id_clo_ord 5372 5372 0 0.00 0 2174 5 0 0
v_min_id_clo_res 959 959 0 0.00 0 19376 36 0 0
v_min_id_ord_sup 5194 5194 0 0.00 0 5742 7 0 0
v_min_non_paid_invoice 5598 5598 0 0.00 0 3943 11 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 4596 11 0 0
v_our_firm 10058 10058 0 0.00 0 13 1 0 0
v_reserve_write_off 19238 19238 0 0.00 0 7042 6 0 0
x_lock_depdocs_on_canc_invoice 11268 11268 0 0.00 0 17805 112 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5689 317 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 2 30731 1173 0 0
x_make_qstorno_1200_2000 5205 5205 0 0.00 3 68231 3159 0 0
x_make_qstorno_2100_3300 444301 444301 0 0.00 0 136916 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-11-2020 13:27:51 to 19-11-2020 13:27:55.

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
10:56:58 980 8192000000 8963538592 9027203072 11279040 0.137 3 2 4 0 11693232 126176 288016
10:58:02 36529 8192000000 9737564720 10033340416 799097328 8.887 100 88 175 1 828502928 8173264 21232336
10:59:47 14919 8192000000 9761607904 10068295680 829028736 9.189 100 90 179 0 864429584 14314112 21086736
11:01:15 13218 8192000000 9798061120 10114887680 852206240 9.422 100 82 164 0 896211840 24797392 19208208
11:02:31 17993 8192000000 9852504448 10184421376 897678976 9.875 100 78 156 0 960134896 45822512 16633408
11:03:51 4106 8192000000 9918940992 10262278144 947441216 10.366 100 80 159 0 1024221440 59914864 16865360
11:04:57 2521 8192000000 9963341504 10318340096 983801744 10.721 100 80 155 1 1067513008 67627904 16083360
11:06:01 2771 8192000000 9982390384 10341400576 1006571536 10.942 100 82 164 0 1093309104 70345488 16392080
11:07:07 6117 8192000000 9977783760 10346065920 1015883360 11.032 100 62 122 1 1090888560 62875472 12129728
11:08:14 3204 8192000000 9996618128 10367451136 1030537936 11.174 100 76 148 2 1100471936 54502272 15431728
11:09:20 2605 8192000000 10032439392 10407489536 1043654368 11.300 100 76 151 1 1136030448 77504784 14871296
11:10:26 3144 8192000000 10019229472 10398392320 1053782256 11.397 100 71 142 0 1137100640 68548192 14770192
11:11:30 3210 8192000000 9981010496 10372698112 1064853488 11.503 100 42 84 0 1091993104 18043328 9096288
11:12:34 7598 8192000000 9992427104 10395697152 1067896160 11.532 100 19 38 2 1118211008 46643904 3670944
11:13:42 30736 8192000000 10021979152 10435702784 1072125072 11.572 100 32 65 2 1089031088 10236224 6669792
11:15:14 6422 8192000000 10027598080 10426281984 1083890544 11.685 100 39 78 3 1131295920 40377888 7027488
11:16:21 6191 8192000000 10024977168 10424500224 1089956416 11.742 100 46 92 1 1128576560 28969744 9650400
11:17:30 10894 8192000000 9996900128 10395734016 1067998912 11.533 98 41 82 2 1093730976 15477712 10254352
11:18:41 12255 8192000000 9982549776 10390065152 1047363488 11.335 97 19 39 2 1084564128 33056496 4144144
11:19:54 4657 8192000000 9996090272 10394759168 1076108000 11.610 100 24 48 3 1099338256 18073120 5157136
11:21:01 4866 8192000000 9970636704 10350551040 1021469648 11.086 97 51 100 1 1050314256 17322912 11521696
11:22:07 62147 8192000000 9967075600 10375258112 993894880 10.819 96 35 72 2 1031116752 30293200 6928672
11:24:10 5205 8192000000 9960915392 10341781504 1002971008 10.907 98 48 94 2 1055912304 43024240 9917056
11:25:15 5370 8192000000 9963958704 10356584448 1041569728 11.280 100 46 92 2 1092812736 41694384 9548624
11:26:25 5915 8192000000 9979837584 10376634368 1064470032 11.499 100 34 68 0 1090720064 18425568 7824464
11:27:34 23016 8192000000 9991981072 10391388160 1076943024 11.618 100 43 86 1 1102964256 15412400 10608832
11:29:01 11965 8192000000 10062129936 10461736960 1098263712 11.821 100 41 81 0 1167195232 60540336 8391184
11:30:20 8831 8192000000 10077036880 10478080000 1115488544 11.984 100 40 80 0 1184902240 60417136 8996560
11:31:35 12312 8192000000 10119276864 10533842944 1126907216 12.092 100 47 90 3 1228063760 91215056 9941488
11:32:59 6298 8192000000 10196639280 10598944768 1148260608 12.293 100 66 129 1 1306553792 144112144 14181040
11:34:09 16725 8192000000 10207298000 10620141568 1155754688 12.363 100 53 106 0 1304230912 135796976 12679248
11:35:34 14885 8192000000 10259603424 10670129152 1166922784 12.468 100 60 117 2 1369621136 189422352 13276000
11:36:51 6241 8192000000 10286047312 10711982080 1175112560 12.545 100 52 104 0 1391125744 204330176 11683008
11:38:00 3525 8192000000 10312283136 10729336832 1178263664 12.574 100 58 116 2 1416254128 224612912 13377552
11:39:07 9266 8192000000 10346994256 10759016448 1174634224 12.540 100 61 118 1 1446866192 258383776 13848192
11:40:21 2293 8192000000 10374767408 10799067136 1183074752 12.619 100 59 116 1 1477055664 280435472 13545440
11:41:29 1293 8192000000 10429506240 10845204480 1189119760 12.675 100 77 151 2 1532298880 325051664 18127456
11:42:37 4985 8192000000 10446839312 10888314880 1190281680 12.686 100 70 138 0 1554614096 347258784 17073632
11:43:47 4961 8192000000 10438140144 10861400064 1173678336 12.531 99 76 150 0 1527894608 337028720 17187552
11:44:58 1304 8192000000 10465899312 10873528320 1188065184 12.665 100 93 181 1 1553805568 344915456 20824928
11:46:02 12281 8192000000 10363151088 10792878080 1134978768 12.168 98 30 58 1 1458168640 315284928 7904944
11:47:20 3600 8192000000 10437588736 10857345024 1150638624 12.315 100 65 130 1 1535688976 369309712 15740640
11:48:31 16109 8192000000 10279593024 10752995328 1137887648 12.196 99 33 66 0 1405532368 258988288 8656432
11:49:53 17451 8192000000 10265412752 10741817344 1143803200 12.251 100 18 36 0 1368015552 219613168 4599184
11:51:11 13023 8192000000 10290775456 10740936704 1126718288 12.090 99 25 50 1 1386227872 253096080 6413504
11:52:27 5389 8192000000 10215416736 10661736448 1108927664 11.922 99 37 74 1 1310104880 192886896 8290320
11:53:34 44407 8192000000 10086433840 10542706688 1088757584 11.731 97 17 35 1 1198970544 106637312 3575648
11:55:32 61812 8192000000 10064820688 10600341504 1083805232 11.684 97 5 10 0 1128558064 43992048 760784
11:57:59 104561 8192000000 10087743392 10513465344 1077689088 11.625 96 33 66 0 1110567504 25495200 7383216
12:00:45 156489 8192000000 10122222928 10555645952 1103588528 11.872 97 38 76 0 1181543344 69833344 8121472
12:04:23 73770 8192000000 10103543872 10549657600 1112806512 11.959 98 30 60 1 1201166096 81744752 6614832
12:06:44 119237 8192000000 10138382224 10580078592 1103593008 11.872 97 20 40 1 1184431840 76669440 4169392
12:09:46 59099 8192000000 10091113472 10598572032 1108059776 11.914 98 10 20 1 1201891216 91797952 2033488
12:11:45 17792 8192000000 10082746624 10505592832 1126766208 12.091 100 62 123 3 1179798016 39063872 13967936
12:13:12 42946 8192000000 10098596480 10538233856 1134831808 12.167 100 26 52 0 1198469792 58220720 5417264
12:14:59 19562 8192000000 10093604320 10529718272 1120005408 12.027 99 25 50 1 1189626208 64398976 5221824
12:16:21 65635 8192000000 10079382288 10539757568 1116179696 11.991 98 15 31 1 1167030704 47771872 3079136
12:18:28 49260 8192000000 10072701520 10501754880 1123057584 12.056 99 22 44 2 1159645136 32377168 4210384
12:20:32 26580 8192000000 10074739504 10504671232 1129083840 12.113 99 17 34 1 1165368560 33048880 3235840
12:22:01 125698 8192000000 10080949104 10556076032 1142826032 12.242 100 24 48 1 1206088848 57889568 5373248
12:25:10 26011 8192000000 10075458560 10589765632 1138774992 12.204 100 4 8 0 1178251184 38662320 813872
12:26:36 36585 8192000000 10097923168 10550644736 1153294048 12.340 100 21 42 0 1188914336 31635120 3985168
12:28:13 64421 8192000000 10104567216 10599694336 1150359792 12.313 99 22 44 1 1231093872 76284016 4450064
12:30:18 65989 8192000000 10106023968 10552020992 1164825568 12.448 100 14 28 0 1214783168 47395104 2562496
12:32:25 54425 8192000000 10102616960 10556030976 1103299632 11.869 96 25 50 0 1140021680 31188784 5533264
12:34:25 60974 8192000000 10060368272 10540965888 1062587200 11.481 93 20 40 1 1140385264 72824208 4973856
12:36:39 32939 8192000000 10086636624 10515095552 1083366848 11.680 96 38 76 0 1134415184 43554736 7493600
12:38:12 53070 8192000000 10049982064 10478981120 1106688864 11.901 99 23 46 2 1172686976 61092336 4905776
12:40:09 18106 8192000000 10074037760 10501939200 1103115312 11.867 98 40 76 2 1136193232 24320976 8756944
12:41:29 100495 8192000000 10036488800 10506158080 1088564816 11.729 97 18 36 0 1156228704 63773168 3890720
12:44:14 33271 8192000000 10044933824 10495275008 1090224576 11.745 98 6 12 0 1118920960 27619280 1077104
12:45:47 61840 8192000000 10052931824 10483683328 1072863280 11.579 96 17 34 0 1105231120 29165536 3202304
12:47:49 58951 8192000000 10124414704 10595676160 1098350416 11.822 99 41 82 2 1214261872 107075072 8836384
12:50:12 47309 8192000000 10199099728 10660114432 1084310064 11.689 96 20 40 3 1238821632 149987888 4523680
12:52:00 89875 8192000000 10137620512 10638663680 1103123808 11.867 99 17 33 1 1271359712 163830864 4405040
12:54:34 43323 8192000000 10113306224 10551021568 1108246400 11.916 99 22 45 2 1178212320 65350112 4615808
12:56:29 48848 8192000000 10105212848 10539307008 1111876176 11.950 99 19 37 1 1161074496 45138576 4059744
13:00:24 77963 8192000000 10123024976 10601607168 1132464432 12.145 100 11 22 1 1210327200 75684432 2178336
13:02:56 80773 8192000000 10111347184 10587602944 1099133984 11.829 96 24 48 0 1185541536 80996208 5411344
13:05:22 111020 8192000000 10104513264 10570342400 1104360944 11.879 97 15 30 2 1157600576 50022688 3216944
13:08:46 49480 8192000000 10090179392 10550546432 1128715216 12.109 99 32 63 2 1200377744 64743472 6919056
13:10:36 38252 8192000000 10117609024 10581950464 1144681712 12.260 100 36 72 1 1219656656 67131088 7843856
13:12:19 53313 8192000000 10090465088 10557128704 1105357888 11.888 97 38 76 0 1158151552 45284368 7509296
13:14:13 124430 8192000000 10108188864 10599022592 1138588384 12.202 100 21 42 1 1223455616 80389648 4477584
13:17:33 56617 8192000000 10070890448 10540433408 1146817520 12.280 100 11 22 1 1179022976 29553648 2651808
13:19:34 72667 8192000000 10094554976 10558337024 1151221120 12.321 100 14 28 0 1187333360 33315072 2797168
13:21:47 42364 8192000000 10089532624 10562453504 1122246896 12.048 97 16 32 0 1178733232 53086512 3399824
13:23:31 59138 8192000000 10114949856 10568200192 1136074880 12.179 99 22 43 3 1189139952 48522848 4542224
13:25:38 33600 8192000000 10099358064 10544005120 1122646080 12.052 99 34 68 0 1189272768 59679168 6947520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-11-2020 13:27:57 to 19-11-2020 13:28:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-11-2020 13:28:13 to 19-11-2020 13:28:15.

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          222988
MON$OLDEST_ACTIVE               225176
MON$OLDEST_SNAPSHOT             225176
MON$NEXT_TRANSACTION            225176
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                       2076064
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             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2269 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2269 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2269 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.2269 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 ---
:3359: 
:3360: mscs-test-161.budzdorov.ru    Thu Nov 19 13:26:59 2020
:3361:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:3362: 
:3363: 
:3364: mscs-test-161.budzdorov.ru    Thu Nov 19 13:26:59 2020
:3365:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:3366: 
--- end of diff output ---

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

19.11.20 13:28:16. 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-19 13:28:19 No severe PSQL-related problems occured
19.11.20 13:28:20. End of report.