builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-081 starttime: 1496352294.05 results: success (0) revision: cb4a4275b365b126ae56b1df5521756b693d8ff9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.051857) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.052320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.052615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.183571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.183975) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-01 14:24:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 80.9M=0s 2017-06-01 14:24:54 (80.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123971 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.379323) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.379660) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015704 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.411464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:54.411820) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cb4a4275b365b126ae56b1df5521756b693d8ff9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cb4a4275b365b126ae56b1df5521756b693d8ff9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-01 14:24:54,681 truncating revision to first 12 chars 2017-06-01 14:24:54,681 Setting DEBUG logging. 2017-06-01 14:24:54,681 attempt 1/10 2017-06-01 14:24:54,681 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cb4a4275b365?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 14:24:54,999 unpacking tar archive at: mozilla-beta-cb4a4275b365/testing/mozharness/ program finished with exit code 0 elapsedTime=0.629414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:55.057272) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:55.060645) ========= script_repo_revision: cb4a4275b365b126ae56b1df5521756b693d8ff9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:55.061043) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:55.061334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 14:24:55.070330) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-06-01 14:24:55.070647) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:24:55 INFO - MultiFileLogger online at 20170601 14:24:55 in /builds/slave/test 14:24:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 14:24:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:24:55 INFO - {'append_to_log': False, 14:24:55 INFO - 'base_work_dir': '/builds/slave/test', 14:24:55 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 14:24:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:24:55 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 14:24:55 INFO - 'buildbot_json_path': 'buildprops.json', 14:24:55 INFO - 'config_files': ('talos/linux_config.py',), 14:24:55 INFO - 'default_actions': ('clobber', 14:24:55 INFO - 'read-buildbot-config', 14:24:55 INFO - 'download-and-extract', 14:24:55 INFO - 'populate-webroot', 14:24:55 INFO - 'create-virtualenv', 14:24:55 INFO - 'install', 14:24:55 INFO - 'run-tests'), 14:24:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:24:55 INFO - 'download_minidump_stackwalk': True, 14:24:55 INFO - 'download_symbols': 'ondemand', 14:24:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:24:55 INFO - 'tooltool.py': '/tools/tooltool.py', 14:24:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:24:55 INFO - '/tools/misc-python/virtualenv.py')}, 14:24:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:24:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:24:55 INFO - 'gecko_profile': False, 14:24:55 INFO - 'gecko_profile_interval': 0, 14:24:55 INFO - 'installer_path': 'installer.exe', 14:24:55 INFO - 'log_level': 'info', 14:24:55 INFO - 'log_name': 'talos', 14:24:55 INFO - 'log_to_console': True, 14:24:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:24:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:24:55 INFO - 'opt_config_files': (), 14:24:55 INFO - 'pip_index': False, 14:24:55 INFO - 'suite': 'dromaeojs', 14:24:55 INFO - 'system_bits': '32', 14:24:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:24:55 INFO - 'title': 'talos-linux64-ix-081', 14:24:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:24:55 INFO - 'use_talos_json': True, 14:24:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:24:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:24:55 INFO - 'work_dir': 'build'} 14:24:55 INFO - [mozharness: 2017-06-01 21:24:55.437897Z] Running clobber step. 14:24:55 INFO - Running pre-action listener: _resource_record_pre_action 14:24:55 INFO - Running main action method: clobber 14:24:55 INFO - rmtree: /builds/slave/test/build 14:24:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:24:56 INFO - Running post-action listener: _resource_record_post_action 14:24:56 INFO - [mozharness: 2017-06-01 21:24:56.457944Z] Finished clobber step (success) 14:24:56 INFO - [mozharness: 2017-06-01 21:24:56.458044Z] Running read-buildbot-config step. 14:24:56 INFO - Running pre-action listener: _resource_record_pre_action 14:24:56 INFO - Running main action method: read_buildbot_config 14:24:56 INFO - Using buildbot properties: 14:24:56 INFO - { 14:24:56 INFO - "project": "", 14:24:56 INFO - "product": "firefox", 14:24:56 INFO - "who": "ryanvm@gmail.com", 14:24:56 INFO - "installer_path": "public/build/target.tar.bz2", 14:24:56 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:24:56 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 14:24:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 14:24:56 INFO - "stage_platform": "linux64", 14:24:56 INFO - "basedir": "/builds/slave/test", 14:24:56 INFO - "buildnumber": 67, 14:24:56 INFO - "platform": "ubuntu64_hw", 14:24:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:24:56 INFO - "slavebuilddir": "test", 14:24:56 INFO - "taskId": "YBOcCxJWQAu_amZWSp7S0Q", 14:24:56 INFO - "branch": "mozilla-beta", 14:24:56 INFO - "script_repo_revision": "production", 14:24:56 INFO - "revision": "cb4a4275b365b126ae56b1df5521756b693d8ff9", 14:24:56 INFO - "slavename": "talos-linux64-ix-081", 14:24:56 INFO - "repo_path": "releases/mozilla-beta" 14:24:56 INFO - } 14:24:56 INFO - Finding installer, test and symbols from parent task. 14:24:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YBOcCxJWQAu_amZWSp7S0Q'}, attempt #1 14:24:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YBOcCxJWQAu_amZWSp7S0Q'}, attempt #1 14:24:57 INFO - Task dependencies: EIXi-LmPRU24R9h6LiuzMA 14:24:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA'}, attempt #1 14:24:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 14:24:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/test_packages.json 14:24:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.crashreporter-symbols.zip 14:24:57 INFO - Running post-action listener: _resource_record_post_action 14:24:57 INFO - [mozharness: 2017-06-01 21:24:57.794206Z] Finished read-buildbot-config step (success) 14:24:57 INFO - [mozharness: 2017-06-01 21:24:57.794424Z] Running download-and-extract step. 14:24:57 INFO - Running pre-action listener: _resource_record_pre_action 14:24:57 INFO - Running main action method: download_and_extract 14:24:57 INFO - mkdir: /builds/slave/test/build/tests 14:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:24:57 INFO - trying https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.test_packages.json 14:24:57 INFO - Downloading https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:24:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:24:58 INFO - Downloaded 986 bytes. 14:24:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:24:58 INFO - Using the following test package requirements: 14:24:58 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:24:58 INFO - u'common': [u'target.common.tests.zip'], 14:24:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:24:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:24:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:24:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:24:58 INFO - u'mozbase': [u'target.common.tests.zip'], 14:24:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:24:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:24:58 INFO - u'web-platform': [u'target.common.tests.zip', 14:24:58 INFO - u'target.web-platform.tests.zip'], 14:24:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:24:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:24:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip 14:24:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:24:58 INFO - Fetch https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip into memory 14:24:59 INFO - Expected file size: 59712698 14:24:59 INFO - Obtained file size: 59712698 14:25:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:25:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip 14:25:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:25:09 INFO - Fetch https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.common.tests.zip into memory 14:25:10 INFO - Expected file size: 59712698 14:25:10 INFO - Obtained file size: 59712698 14:25:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.talos.tests.zip 14:25:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:25:20 INFO - Fetch https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.talos.tests.zip into memory 14:25:21 INFO - Expected file size: 14404461 14:25:21 INFO - Obtained file size: 14404461 14:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:21 INFO - trying https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 14:25:21 INFO - Downloading https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:25:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:25:22 INFO - Downloaded 59732317 bytes. 14:25:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 14:25:22 INFO - mkdir: /builds/slave/test/properties 14:25:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:25:22 INFO - Writing to file /builds/slave/test/properties/build_url 14:25:22 INFO - Contents: 14:25:22 INFO - build_url:https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 14:25:22 INFO - Running post-action listener: _resource_record_post_action 14:25:22 INFO - Running post-action listener: set_extra_try_arguments 14:25:22 INFO - [mozharness: 2017-06-01 21:25:22.872570Z] Finished download-and-extract step (success) 14:25:22 INFO - [mozharness: 2017-06-01 21:25:22.872666Z] Running populate-webroot step. 14:25:22 INFO - Running pre-action listener: _resource_record_pre_action 14:25:22 INFO - Running main action method: populate_webroot 14:25:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:25:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:25:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:25:22 INFO - '--tppagecycles', 14:25:22 INFO - '7'], 14:25:22 INFO - 'tests': ['tsvgm']}}, 14:25:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:25:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:25:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:25:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:25:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['damp', 'tps']}, 14:25:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'tests': ['damp', 'tps']}, 14:25:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['dromaeo_dom']}, 14:25:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:25:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['basic_compositor_video']}, 14:25:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:25:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'other-e10s': {'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['a11yr', 14:25:22 INFO - 'ts_paint', 14:25:22 INFO - 'tpaint', 14:25:22 INFO - 'sessionrestore', 14:25:22 INFO - 'sessionrestore_no_auto_restore', 14:25:22 INFO - 'tabpaint']}, 14:25:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['tsvgx', 14:25:22 INFO - 'tsvgr_opacity', 14:25:22 INFO - 'tart', 14:25:22 INFO - 'tscrollx', 14:25:22 INFO - 'cart', 14:25:22 INFO - 'tsvg_static']}, 14:25:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:25:22 INFO - 'tsvgr_opacity', 14:25:22 INFO - 'tart', 14:25:22 INFO - 'tscrollx', 14:25:22 INFO - 'cart', 14:25:22 INFO - 'tsvg_static']}, 14:25:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'talos_options': ['--disable-e10s'], 14:25:22 INFO - 'tests': ['tp5o']}, 14:25:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:25:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'talos_options': ['--disable-e10s', 14:25:22 INFO - '--xperf_path', 14:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:22 INFO - 'tests': ['tp5n']}, 14:25:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:25:22 INFO - 'talos_options': ['--xperf_path', 14:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:22 INFO - 'tests': ['tp5n']}}, 14:25:22 INFO - 'talos.zip': {'path': '', 14:25:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:25:22 INFO - Running post-action listener: _resource_record_post_action 14:25:22 INFO - [mozharness: 2017-06-01 21:25:22.885751Z] Finished populate-webroot step (success) 14:25:22 INFO - [mozharness: 2017-06-01 21:25:22.885842Z] Running create-virtualenv step. 14:25:22 INFO - Running pre-action listener: _resource_record_pre_action 14:25:22 INFO - Running main action method: create_virtualenv 14:25:22 INFO - Creating virtualenv /builds/slave/test/build/venv 14:25:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:25:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:25:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:25:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:25:22 INFO - Using real prefix '/usr' 14:25:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:25:23 INFO - Installing distribute.............................................................................................................................................................................................done. 14:25:25 INFO - Installing pip.................done. 14:25:25 INFO - Return code: 0 14:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:25:25 INFO - Reading from file tmpfile_stdout 14:25:25 INFO - Output received: 14:25:25 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:25:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:25:25 INFO - Using env: {'DISPLAY': ':0', 14:25:25 INFO - 'HOME': '/home/cltbld', 14:25:25 INFO - 'LANG': 'en_US.UTF-8', 14:25:25 INFO - 'LANGUAGE': 'en_US:en', 14:25:25 INFO - 'LOGNAME': 'cltbld', 14:25:25 INFO - 'MAIL': '/var/mail/cltbld', 14:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:25 INFO - 'MOZ_NO_REMOTE': '1', 14:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:25 INFO - 'NO_EM_RESTART': '1', 14:25:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:25 INFO - 'PWD': '/builds/slave/test', 14:25:25 INFO - 'SHELL': '/bin/bash', 14:25:25 INFO - 'SHLVL': '1', 14:25:25 INFO - 'TERM': 'linux', 14:25:25 INFO - 'TMOUT': '86400', 14:25:25 INFO - 'USER': 'cltbld', 14:25:25 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:25 INFO - '_': '/tools/buildbot/bin/python'} 14:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:25:25 INFO - Cleaning up... 14:25:25 INFO - Return code: 0 14:25:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:25:25 INFO - Using env: {'DISPLAY': ':0', 14:25:25 INFO - 'HOME': '/home/cltbld', 14:25:25 INFO - 'LANG': 'en_US.UTF-8', 14:25:25 INFO - 'LANGUAGE': 'en_US:en', 14:25:25 INFO - 'LOGNAME': 'cltbld', 14:25:25 INFO - 'MAIL': '/var/mail/cltbld', 14:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:25 INFO - 'MOZ_NO_REMOTE': '1', 14:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:25 INFO - 'NO_EM_RESTART': '1', 14:25:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:25 INFO - 'PWD': '/builds/slave/test', 14:25:25 INFO - 'SHELL': '/bin/bash', 14:25:25 INFO - 'SHLVL': '1', 14:25:25 INFO - 'TERM': 'linux', 14:25:25 INFO - 'TMOUT': '86400', 14:25:25 INFO - 'USER': 'cltbld', 14:25:25 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:25 INFO - '_': '/tools/buildbot/bin/python'} 14:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:26 INFO - Downloading/unpacking psutil>=3.1.1 14:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:25:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:27 INFO - Installing collected packages: psutil 14:25:27 INFO - Running setup.py install for psutil 14:25:27 INFO - building 'psutil._psutil_linux' extension 14:25:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:25:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:25:27 INFO - building 'psutil._psutil_posix' extension 14:25:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:25:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:25:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:27 INFO - Successfully installed psutil 14:25:27 INFO - Cleaning up... 14:25:27 INFO - Return code: 0 14:25:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:25:27 INFO - Using env: {'DISPLAY': ':0', 14:25:27 INFO - 'HOME': '/home/cltbld', 14:25:27 INFO - 'LANG': 'en_US.UTF-8', 14:25:27 INFO - 'LANGUAGE': 'en_US:en', 14:25:27 INFO - 'LOGNAME': 'cltbld', 14:25:27 INFO - 'MAIL': '/var/mail/cltbld', 14:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:27 INFO - 'MOZ_NO_REMOTE': '1', 14:25:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:27 INFO - 'NO_EM_RESTART': '1', 14:25:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:27 INFO - 'PWD': '/builds/slave/test', 14:25:27 INFO - 'SHELL': '/bin/bash', 14:25:27 INFO - 'SHLVL': '1', 14:25:27 INFO - 'TERM': 'linux', 14:25:27 INFO - 'TMOUT': '86400', 14:25:27 INFO - 'USER': 'cltbld', 14:25:27 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:27 INFO - '_': '/tools/buildbot/bin/python'} 14:25:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:25:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:25:29 INFO - Installing collected packages: mozsystemmonitor 14:25:29 INFO - Running setup.py install for mozsystemmonitor 14:25:29 INFO - Successfully installed mozsystemmonitor 14:25:29 INFO - Cleaning up... 14:25:29 INFO - Return code: 0 14:25:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:25:29 INFO - Using env: {'DISPLAY': ':0', 14:25:29 INFO - 'HOME': '/home/cltbld', 14:25:29 INFO - 'LANG': 'en_US.UTF-8', 14:25:29 INFO - 'LANGUAGE': 'en_US:en', 14:25:29 INFO - 'LOGNAME': 'cltbld', 14:25:29 INFO - 'MAIL': '/var/mail/cltbld', 14:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:29 INFO - 'MOZ_NO_REMOTE': '1', 14:25:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:29 INFO - 'NO_EM_RESTART': '1', 14:25:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:29 INFO - 'PWD': '/builds/slave/test', 14:25:29 INFO - 'SHELL': '/bin/bash', 14:25:29 INFO - 'SHLVL': '1', 14:25:29 INFO - 'TERM': 'linux', 14:25:29 INFO - 'TMOUT': '86400', 14:25:29 INFO - 'USER': 'cltbld', 14:25:29 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:29 INFO - '_': '/tools/buildbot/bin/python'} 14:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:29 INFO - Downloading/unpacking jsonschema==2.5.1 14:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:25:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:30 INFO - Downloading functools32-3.2.3-2.tar.gz 14:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:25:30 INFO - warning: no files found matching '*.txt' 14:25:30 INFO - no previously-included directories found matching 'build' 14:25:30 INFO - no previously-included directories found matching 'dist' 14:25:30 INFO - no previously-included directories found matching '.git*' 14:25:30 INFO - Installing collected packages: jsonschema, functools32 14:25:30 INFO - Running setup.py install for functools32 14:25:30 INFO - warning: no files found matching '*.txt' 14:25:30 INFO - no previously-included directories found matching 'build' 14:25:30 INFO - no previously-included directories found matching 'dist' 14:25:30 INFO - no previously-included directories found matching '.git*' 14:25:30 INFO - Successfully installed jsonschema functools32 14:25:30 INFO - Cleaning up... 14:25:31 INFO - Return code: 0 14:25:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:25:31 INFO - Using env: {'DISPLAY': ':0', 14:25:31 INFO - 'HOME': '/home/cltbld', 14:25:31 INFO - 'LANG': 'en_US.UTF-8', 14:25:31 INFO - 'LANGUAGE': 'en_US:en', 14:25:31 INFO - 'LOGNAME': 'cltbld', 14:25:31 INFO - 'MAIL': '/var/mail/cltbld', 14:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:31 INFO - 'MOZ_NO_REMOTE': '1', 14:25:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:31 INFO - 'NO_EM_RESTART': '1', 14:25:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:31 INFO - 'PWD': '/builds/slave/test', 14:25:31 INFO - 'SHELL': '/bin/bash', 14:25:31 INFO - 'SHLVL': '1', 14:25:31 INFO - 'TERM': 'linux', 14:25:31 INFO - 'TMOUT': '86400', 14:25:31 INFO - 'USER': 'cltbld', 14:25:31 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:31 INFO - '_': '/tools/buildbot/bin/python'} 14:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:25:31 INFO - Cleaning up... 14:25:31 INFO - Return code: 0 14:25:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:25:31 INFO - Using env: {'DISPLAY': ':0', 14:25:31 INFO - 'HOME': '/home/cltbld', 14:25:31 INFO - 'LANG': 'en_US.UTF-8', 14:25:31 INFO - 'LANGUAGE': 'en_US:en', 14:25:31 INFO - 'LOGNAME': 'cltbld', 14:25:31 INFO - 'MAIL': '/var/mail/cltbld', 14:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:31 INFO - 'MOZ_NO_REMOTE': '1', 14:25:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:31 INFO - 'NO_EM_RESTART': '1', 14:25:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:31 INFO - 'PWD': '/builds/slave/test', 14:25:31 INFO - 'SHELL': '/bin/bash', 14:25:31 INFO - 'SHLVL': '1', 14:25:31 INFO - 'TERM': 'linux', 14:25:31 INFO - 'TMOUT': '86400', 14:25:31 INFO - 'USER': 'cltbld', 14:25:31 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:31 INFO - '_': '/tools/buildbot/bin/python'} 14:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:31 INFO - Downloading/unpacking blobuploader==1.2.4 14:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:25:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:25:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:33 INFO - Downloading docopt-0.6.1.tar.gz 14:25:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:25:33 INFO - Installing collected packages: blobuploader, requests, docopt 14:25:33 INFO - Running setup.py install for blobuploader 14:25:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:25:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:25:33 INFO - Running setup.py install for requests 14:25:33 INFO - Running setup.py install for docopt 14:25:33 INFO - Successfully installed blobuploader requests docopt 14:25:33 INFO - Cleaning up... 14:25:33 INFO - Return code: 0 14:25:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:25:33 INFO - Using env: {'DISPLAY': ':0', 14:25:33 INFO - 'HOME': '/home/cltbld', 14:25:33 INFO - 'LANG': 'en_US.UTF-8', 14:25:33 INFO - 'LANGUAGE': 'en_US:en', 14:25:33 INFO - 'LOGNAME': 'cltbld', 14:25:33 INFO - 'MAIL': '/var/mail/cltbld', 14:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:33 INFO - 'MOZ_NO_REMOTE': '1', 14:25:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:33 INFO - 'NO_EM_RESTART': '1', 14:25:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:33 INFO - 'PWD': '/builds/slave/test', 14:25:33 INFO - 'SHELL': '/bin/bash', 14:25:33 INFO - 'SHLVL': '1', 14:25:33 INFO - 'TERM': 'linux', 14:25:33 INFO - 'TMOUT': '86400', 14:25:33 INFO - 'USER': 'cltbld', 14:25:33 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:33 INFO - '_': '/tools/buildbot/bin/python'} 14:25:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:25:33 INFO - Running setup.py (path:/tmp/pip-Nwpj6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:25:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:25:34 INFO - Running setup.py (path:/tmp/pip-SWrQa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:25:34 INFO - Running setup.py (path:/tmp/pip-sz8u_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:25:34 INFO - Running setup.py (path:/tmp/pip-xi6A1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:25:34 INFO - Running setup.py (path:/tmp/pip-TWgpQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:25:34 INFO - Running setup.py (path:/tmp/pip-xaFiOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:25:34 INFO - Running setup.py (path:/tmp/pip-JuOax5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:25:34 INFO - Running setup.py (path:/tmp/pip-4uCJVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:25:34 INFO - Running setup.py (path:/tmp/pip-zYhvp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:25:34 INFO - Running setup.py (path:/tmp/pip-GlpGHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:25:34 INFO - Running setup.py (path:/tmp/pip-AGlnO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:25:34 INFO - Running setup.py (path:/tmp/pip-CXAmbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:25:34 INFO - Running setup.py (path:/tmp/pip-llLHUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:25:34 INFO - Running setup.py (path:/tmp/pip-zfW972-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:25:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:34 INFO - Running setup.py (path:/tmp/pip-78IQFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:25:35 INFO - Running setup.py (path:/tmp/pip-GtZGnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:25:35 INFO - Running setup.py (path:/tmp/pip-PYqt1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:25:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:25:35 INFO - Running setup.py install for manifestparser 14:25:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Running setup.py install for mozcrash 14:25:35 INFO - Running setup.py install for mozdebug 14:25:35 INFO - Running setup.py install for mozdevice 14:25:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Running setup.py install for mozfile 14:25:35 INFO - Running setup.py install for mozhttpd 14:25:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Running setup.py install for mozinfo 14:25:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Running setup.py install for mozInstall 14:25:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:25:35 INFO - Running setup.py install for mozleak 14:25:36 INFO - Running setup.py install for mozlog 14:25:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Running setup.py install for moznetwork 14:25:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Running setup.py install for mozprocess 14:25:36 INFO - Running setup.py install for mozprofile 14:25:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Running setup.py install for mozrunner 14:25:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Running setup.py install for mozscreenshot 14:25:36 INFO - Running setup.py install for moztest 14:25:36 INFO - Running setup.py install for mozversion 14:25:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:25:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:25:36 INFO - Cleaning up... 14:25:36 INFO - Return code: 0 14:25:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:25:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:25:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:25:36 INFO - Using env: {'DISPLAY': ':0', 14:25:36 INFO - 'HOME': '/home/cltbld', 14:25:36 INFO - 'LANG': 'en_US.UTF-8', 14:25:36 INFO - 'LANGUAGE': 'en_US:en', 14:25:36 INFO - 'LOGNAME': 'cltbld', 14:25:36 INFO - 'MAIL': '/var/mail/cltbld', 14:25:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:36 INFO - 'MOZ_NO_REMOTE': '1', 14:25:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:36 INFO - 'NO_EM_RESTART': '1', 14:25:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:36 INFO - 'PWD': '/builds/slave/test', 14:25:36 INFO - 'SHELL': '/bin/bash', 14:25:36 INFO - 'SHLVL': '1', 14:25:36 INFO - 'TERM': 'linux', 14:25:36 INFO - 'TMOUT': '86400', 14:25:36 INFO - 'USER': 'cltbld', 14:25:36 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:36 INFO - '_': '/tools/buildbot/bin/python'} 14:25:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:25:37 INFO - Running setup.py (path:/tmp/pip-eQQ84i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:25:37 INFO - Running setup.py (path:/tmp/pip-q0qfap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:25:37 INFO - Running setup.py (path:/tmp/pip-8QVle0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:25:37 INFO - Running setup.py (path:/tmp/pip-nElVDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:25:37 INFO - Running setup.py (path:/tmp/pip-lLSSfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:25:37 INFO - Running setup.py (path:/tmp/pip-3D5cBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:25:37 INFO - Running setup.py (path:/tmp/pip-UeRcc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:25:37 INFO - Running setup.py (path:/tmp/pip-TZTahj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:25:37 INFO - Running setup.py (path:/tmp/pip-tukCWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:25:37 INFO - Running setup.py (path:/tmp/pip-XB3eEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:25:37 INFO - Running setup.py (path:/tmp/pip-G7ylgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:25:37 INFO - Running setup.py (path:/tmp/pip-GVW8pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:25:38 INFO - Running setup.py (path:/tmp/pip-cYZzY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:25:38 INFO - Running setup.py (path:/tmp/pip-tslvMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:38 INFO - Running setup.py (path:/tmp/pip-S44LSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:25:38 INFO - Running setup.py (path:/tmp/pip-M6wEln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:25:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:25:38 INFO - Running setup.py (path:/tmp/pip-1yMv2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:25:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:25:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:39 INFO - Downloading blessings-1.6.tar.gz 14:25:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:25:39 INFO - Installing collected packages: blessings 14:25:39 INFO - Running setup.py install for blessings 14:25:39 INFO - Successfully installed blessings 14:25:39 INFO - Cleaning up... 14:25:39 INFO - Return code: 0 14:25:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:25:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:25:39 INFO - Reading from file tmpfile_stdout 14:25:39 INFO - Current package versions: 14:25:39 INFO - argparse == 1.2.1 14:25:39 INFO - blessings == 1.6 14:25:39 INFO - blobuploader == 1.2.4 14:25:39 INFO - docopt == 0.6.1 14:25:39 INFO - functools32 == 3.2.3-2 14:25:39 INFO - jsonschema == 2.5.1 14:25:39 INFO - manifestparser == 1.1 14:25:39 INFO - mozInstall == 1.12 14:25:39 INFO - mozcrash == 1.0 14:25:39 INFO - mozdebug == 0.1 14:25:39 INFO - mozdevice == 0.49 14:25:39 INFO - mozfile == 1.2 14:25:39 INFO - mozhttpd == 0.7 14:25:39 INFO - mozinfo == 0.9 14:25:39 INFO - mozleak == 0.1 14:25:39 INFO - mozlog == 3.4 14:25:39 INFO - moznetwork == 0.27 14:25:39 INFO - mozprocess == 0.25 14:25:39 INFO - mozprofile == 0.28 14:25:39 INFO - mozrunner == 6.13 14:25:39 INFO - mozscreenshot == 0.1 14:25:39 INFO - mozsystemmonitor == 0.3 14:25:39 INFO - moztest == 0.8 14:25:39 INFO - mozversion == 1.4 14:25:39 INFO - psutil == 3.1.1 14:25:39 INFO - requests == 1.2.3 14:25:39 INFO - wsgiref == 0.1.2 14:25:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:25:39 INFO - Using env: {'DISPLAY': ':0', 14:25:39 INFO - 'HOME': '/home/cltbld', 14:25:39 INFO - 'LANG': 'en_US.UTF-8', 14:25:39 INFO - 'LANGUAGE': 'en_US:en', 14:25:39 INFO - 'LOGNAME': 'cltbld', 14:25:39 INFO - 'MAIL': '/var/mail/cltbld', 14:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:39 INFO - 'MOZ_NO_REMOTE': '1', 14:25:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:39 INFO - 'NO_EM_RESTART': '1', 14:25:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:39 INFO - 'PWD': '/builds/slave/test', 14:25:39 INFO - 'SHELL': '/bin/bash', 14:25:39 INFO - 'SHLVL': '1', 14:25:39 INFO - 'TERM': 'linux', 14:25:39 INFO - 'TMOUT': '86400', 14:25:39 INFO - 'USER': 'cltbld', 14:25:39 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:39 INFO - '_': '/tools/buildbot/bin/python'} 14:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:25:40 INFO - Cleaning up... 14:25:40 INFO - Return code: 0 14:25:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x130e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:25:40 INFO - Using env: {'DISPLAY': ':0', 14:25:40 INFO - 'HOME': '/home/cltbld', 14:25:40 INFO - 'LANG': 'en_US.UTF-8', 14:25:40 INFO - 'LANGUAGE': 'en_US:en', 14:25:40 INFO - 'LOGNAME': 'cltbld', 14:25:40 INFO - 'MAIL': '/var/mail/cltbld', 14:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:40 INFO - 'MOZ_NO_REMOTE': '1', 14:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:40 INFO - 'NO_EM_RESTART': '1', 14:25:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:40 INFO - 'PWD': '/builds/slave/test', 14:25:40 INFO - 'SHELL': '/bin/bash', 14:25:40 INFO - 'SHLVL': '1', 14:25:40 INFO - 'TERM': 'linux', 14:25:40 INFO - 'TMOUT': '86400', 14:25:40 INFO - 'USER': 'cltbld', 14:25:40 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:40 INFO - '_': '/tools/buildbot/bin/python'} 14:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:25:40 INFO - Cleaning up... 14:25:40 INFO - Return code: 0 14:25:40 INFO - Running post-action listener: _resource_record_post_action 14:25:40 INFO - Running post-action listener: _start_resource_monitoring 14:25:40 INFO - Starting resource monitoring. 14:25:40 INFO - [mozharness: 2017-06-01 21:25:40.383495Z] Finished create-virtualenv step (success) 14:25:40 INFO - [mozharness: 2017-06-01 21:25:40.384011Z] Running install step. 14:25:40 INFO - Running pre-action listener: _resource_record_pre_action 14:25:40 INFO - Running main action method: install 14:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:25:40 INFO - Reading from file tmpfile_stdout 14:25:40 INFO - Detecting whether we're running mozinstall >=1.0... 14:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:25:40 INFO - Reading from file tmpfile_stdout 14:25:40 INFO - Output received: 14:25:40 INFO - Usage: mozinstall [options] installer 14:25:40 INFO - Options: 14:25:40 INFO - -h, --help show this help message and exit 14:25:40 INFO - -d DEST, --destination=DEST 14:25:40 INFO - Directory to install application into. [default: 14:25:40 INFO - "/builds/slave/test"] 14:25:40 INFO - --app=APP Application being installed. [default: firefox] 14:25:40 INFO - mkdir: /builds/slave/test/build/application 14:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:25:54 INFO - Reading from file tmpfile_stdout 14:25:54 INFO - Output received: 14:25:54 INFO - /builds/slave/test/build/application/firefox/firefox 14:25:54 INFO - Running post-action listener: _resource_record_post_action 14:25:54 INFO - [mozharness: 2017-06-01 21:25:54.760641Z] Finished install step (success) 14:25:54 INFO - [mozharness: 2017-06-01 21:25:54.760784Z] Running run-tests step. 14:25:54 INFO - Running pre-action listener: _resource_record_pre_action 14:25:54 INFO - Running main action method: run_tests 14:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:25:54 INFO - Python 2.7.3 14:25:54 INFO - Return code: 0 14:25:54 INFO - grabbing minidump binary from tooltool 14:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:54 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130b400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x130c840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:25:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:25:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:25:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:25:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:25:54 INFO - Return code: 0 14:25:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:25:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:25:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:25:54 INFO - ENV: RUST_BACKTRACE is now 1 14:25:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:25:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:25:54 INFO - Using env: {'DISPLAY': ':0', 14:25:54 INFO - 'HOME': '/home/cltbld', 14:25:54 INFO - 'LANG': 'en_US.UTF-8', 14:25:54 INFO - 'LANGUAGE': 'en_US:en', 14:25:54 INFO - 'LOGNAME': 'cltbld', 14:25:54 INFO - 'MAIL': '/var/mail/cltbld', 14:25:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:25:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:54 INFO - 'MOZ_NO_REMOTE': '1', 14:25:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:25:54 INFO - 'NO_EM_RESTART': '1', 14:25:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:54 INFO - 'PWD': '/builds/slave/test', 14:25:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:25:54 INFO - 'RUST_BACKTRACE': '1', 14:25:54 INFO - 'SHELL': '/bin/bash', 14:25:54 INFO - 'SHLVL': '1', 14:25:54 INFO - 'TERM': 'linux', 14:25:54 INFO - 'TMOUT': '86400', 14:25:54 INFO - 'USER': 'cltbld', 14:25:54 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1496352293.598577-517852501', 14:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:54 INFO - '_': '/tools/buildbot/bin/python'} 14:25:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:25:55 INFO - mozversion application_buildid: 20170601203257 14:25:55 INFO - mozversion application_changeset: cb4a4275b365b126ae56b1df5521756b693d8ff9 14:25:55 INFO - mozversion application_display_name: Firefox 14:25:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:25:55 INFO - mozversion application_name: Firefox 14:25:55 INFO - mozversion application_remotingname: firefox 14:25:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:25:55 INFO - mozversion application_vendor: Mozilla 14:25:55 INFO - mozversion application_version: 54.0 14:25:55 INFO - mozversion platform_buildid: 20170601203257 14:25:55 INFO - mozversion platform_changeset: cb4a4275b365b126ae56b1df5521756b693d8ff9 14:25:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:25:55 INFO - mozversion platform_version: 54.0 14:25:55 INFO - using testdate: 1496352355 14:25:55 INFO - actual date: 1496352355 14:25:55 INFO - starting webserver on 'localhost:53601' 14:25:55 INFO - SUITE-START | Running 2 tests 14:25:55 INFO - TEST-START | dromaeo_css 14:25:55 INFO - operating with platform_type : linux_ 14:25:55 INFO - Initialising browser for dromaeo_css test... 14:25:55 INFO - TEST-INFO | started process 32139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjNd3pS/profile http://localhost:53601/getInfo.html) 14:25:59 INFO - PROCESS | 32139 | __metrics Screen width/height:1600/1200 14:25:59 INFO - PROCESS | 32139 | colorDepth:24 14:25:59 INFO - PROCESS | 32139 | Browser inner width/height: 1024/697 14:25:59 INFO - PROCESS | 32139 | __metrics 14:26:00 INFO - PROCESS | 32139 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:26:00 INFO - PROCESS | 32139 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:26:01 INFO - PROCESS | 32139 | console.error: PushService: 14:26:01 INFO - PROCESS | 32139 | stateChangeProcessEnqueue: Error transitioning state 14:26:01 INFO - PROCESS | 32139 | UnknownError 14:26:01 INFO - TEST-INFO | 32139: exit 0 14:26:01 INFO - Browser initialized. 14:26:01 INFO - Running cycle 1/1 for dromaeo_css test... 14:26:01 INFO - TEST-INFO | started process 32231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjNd3pS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:13 INFO - PROCESS | 32231 | RSS: Main: 141164544 14:26:13 INFO - PROCESS | 32231 | 14:29:14 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53601/tests/dromaeo/cssquery-ext.html) 14:29:16 INFO - PROCESS | 32231 | RSS: Main: 268820480 14:29:16 INFO - PROCESS | 32231 | 14:32:18 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-ext.html (next: http://localhost:53601/tests/dromaeo/cssquery-jquery.html) 14:32:19 INFO - PROCESS | 32231 | RSS: Main: 227045376 14:32:19 INFO - PROCESS | 32231 | 14:35:20 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53601/tests/dromaeo/cssquery-mootools.html) 14:35:21 INFO - PROCESS | 32231 | RSS: Main: 176361472 14:35:21 INFO - PROCESS | 32231 | 14:38:23 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53601/tests/dromaeo/cssquery-prototype.html) 14:38:23 INFO - PROCESS | 32231 | RSS: Main: 173916160 14:38:23 INFO - PROCESS | 32231 | 14:41:26 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53601/tests/dromaeo/cssquery-yui.html) 14:41:27 INFO - PROCESS | 32231 | RSS: Main: 175534080 14:41:27 INFO - PROCESS | 32231 | 14:44:29 INFO - PROCESS | 32231 | Cycle 1(1): loaded http://localhost:53601/tests/dromaeo/cssquery-yui.html (next: http://localhost:53601/tests/dromaeo/cssquery-dojo.html) 14:44:30 INFO - PROCESS | 32231 | RSS: Main: 264957952 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | __start_tp_report 14:44:30 INFO - PROCESS | 32231 | _x_x_mozilla_page_load 14:44:30 INFO - PROCESS | 32231 | _x_x_mozilla_page_load_details 14:44:30 INFO - PROCESS | 32231 | |i|pagename|runs| 14:44:30 INFO - PROCESS | 32231 | |0;dojo.html;3412.539307193529;3445.5693038370205;3448.517207590937;3467.2545402738438;3481.878134265301;5753.683547404891;5841.299044114701;5844.444777746118;5852.414758524145;5863.413658634135;5843.883122337551;5887.499562537189;5889.646621202731;5892;5906.173135760997;5308.150695888649;5328.573714102882;5342.572594192475;5359.919601205966;5440.401555828856;5162.561182299509;5207.791688332462;5419.972900135494;5420.051490989081;5420.268263784378;5274.683518988873;5286.814961476349;5319;5328.413874473805;5331.546818520431;5374.7043912584795;5402.35171779385;5428.104362780136;5428.891422171574;5434.483724046209;5313.07021271276;5332.573394128461;5392.083345831179;5393.919091213635;5414.485623865727;5628.831135065954;5656.858578535528;5664.348599911022;5734.913976290359;5849.7660093596205;6256.593321434092;6258.43674069336;6259.655718935457;6261.0295404211965;6262.091996660504;6303.401176888188;6304.432601065926;6313.558050936436;6316.684165791692;6323.9683801580695;5609.074502707008;5693.800716974886;5698.458646428583;5863.882722345529;5869.148973398876;6278.30938596754;6286.685665716695;6301.6219026858535;6314.400131987454;6332.715027823759;6180.351063138306;6184;6189.659568723763;6192.349803270682;6198.411150940653;5996;6186.133941248138;6256.374362563707;6263.404976527223;6268.561200715906;6089.025755879038;6122.103333532645;6157.107219453154;6158.876822463526;6174.012158054689;6033.577649564489;6075.058365953284;6082.1789058477425;6088.634681919099;6237.282712488031;4193.706440549132;4223.429836972031;4226.598473145046;4227.281362168439;4229.196452673982;3849.653531182207;3884.941725874114;3903.4535165076913;3908.5895980921596;3915.960840391617;5974.581779275496;5983.820485385445;5995.010823214208;6020;6022.337542870281;6297.779577714758;6320.936790632127;6325.873482530324;6333.081703153017;6333.588316759396;5679.574031947538;5716.685582293014;5720.599558030898;5735.11105778605;5806.04200306953;5218.869528261824;5221.869453263623;5222.373315202124;5229.294045303911;5232.712200828991;6303.2751233607805;6393.904091438632;6402.135711678957;6441.613503189824;6486.708098135689;6316.589421687576;6322.272938612118;6325.683715814099;6330.240371155491;6340.207474065742;6231.75072997075;6578.243501997332;6581.605103693793;6590.736370545124;6592.736290548324;6088.939110609014;6107.219158344477;6145.324014358506;6145.463634091477;6161.106639537332;6312.684365781784;6325.652089135142;6342.809715708536;6349.68251587428;6353.205849268841;6156.569040167145;6161.229846269216;6175.166352542529;6179.938200618116;6181.907271390843;5867.677277749594;5913;6020.4280593343565;6070.332263450929;6079.574429789872;6070.757169713339;6077.939220607915;6084.11780291864;6092.299385570716;6122.204113465399;6169.198004259597;6170.598911070766;6173.753049877954;6179.783707570214;6180.3201647817805;5806.767729290781;5859.091840764689;5875.118732190034;5927.170196172626;5943.762249509971 14:44:30 INFO - PROCESS | 32231 | |1;ext.html;773.4856320546837;843.3127001493782;859.4542465534388;924.043614858621;931.2038207332727;30905.536416953735;31200.687993120064;33528.83235583825;33609.49585756213;49703.75148124263;45923.54076459234;46361.53638463614;46381.76809115958;46415.30377044347;46437.07125857481;9128.269738420944;9252.814943701122;9263.444193348387;9264.814703705923;9268.582913768863;7515.286047825475;7662.885056724153;9131.863022054675;9249.768755781091;10690.839637405445;10859.185561082912;10899.182561307895;10899.891001090007;10920;11015.449227538591;10937.83593246102;10961.56153753849;10963.671089867317;10973.945130274378;10978.505967231455;10677.41274229917;10746.140308775279;10747.516361763739;10753.085987691056;10764.192685548614;42546.57453425457;42685.786571067256;42728.14543709139;42730.35904461436;42794;4346.717463364871;4401.163778882003;4409.047785489595;4518.299663552155;4547.931781023287;30635.54046689279;31135.22161945947;31241.843790780902;31391.529127063113;32055.839720801247;4370.737755734634;4419.889502762425;4470.597646211824;4529.058315098994;4533.252013417815;239486;240993;241274;241534;241545;55598;55767.44232557622;55806.44193558094;55822.72088639612;55841.441585584435;58828.70585647044;58877;58941.11588326179;58999.41000590025;59007.40992590105;16514.091724955004;16535.751963720555;16553.420630277884;16673;16673.83326166747;14632.414703411958;14635.414583416545;14635.634109147357;14638;14652.267386630625;12448.81326780081;12699.68250793719;13952.232627205602;14176.929115354564;14306.85693143076;5502.5597952163735;5590.161475778585;5672.517835983987;5673.063944449203;5771.105478650816;1013.2147585621202;1013.6756238003768;1021.5454122915231;1025.1388833379997;1025.2464438637596;29149.708502915124;29300.706992930223;29355.55966660502;29650.258743531154;29713.85143074314;1083.5340803454433;1083.544911137324;1088.6407485529755;1105.9944700276446;1107.070061148639;14989.775153372708;15679;15740.763888541682;15786.289616967264;15806.20968951548;300041;301339;302271;302952;303255;57171.71414142986;59280;59282.703586482654;59285.40714592885;59375.70312148412;1492.4627134231669;1496.423876807437;1503.8496150384874;1510.6298956755666;1524.4892960858015;28167.71832281733;29730.702692972794;30064.699353006188;30258.697413025588;30415.39169216559;38970.4154437678;39245.803770980965;39315;39348.80325598468;39391.80304098461;322551;323687;324118;324739;324754;29852.850735746182;30477.54283685791;30593.54109688312;30671.53992690156;34137;34423.65576344305;34977.825110874284;39242.411363830135;42182.78908605437;46520;32041.198970025005;32486.512702309956;32632.99865128185;32922.67077329196;33376;32762.672373276917;33087.338253235284;33280.500792488616;33343.49984750182;33703.66296337103;37193;37538.436923445624;38023;38570;38636.80681596574;31602.683973160892;31841.363172735953;31968.36063278675;32060;32103.518447223778;24784;25298.241052768448;25673.229803105936;25693.61459578145;25815.870920645277 14:44:30 INFO - PROCESS | 32231 | |2;jquery.html;471.8461840298214;486.58883243659096;488.7214287855919;498.2526210683974;499.22619939094403;21609.783902160994;22580.887095564518;23498.060077596896;23537.058517659272;23642.763572364314;22577.322680319576;22631.321060368173;22836.314910552668;23088;23376.76623233767;6499.3175716549795;6928.2725313842075;6971;6977.33715297046;6983;6904.10246667935;6965.547239429447;7232.421406287484;7495.287947644965;7541.585212813293;7399.482036257464;7641.23587641237;7683;7691.269329413724;7701.3453856422275;7670.769876903674;7728;7750.379969602418;7772.261635144652;7772.999999999972;7356.227596102386;7559;7592.582407967559;7598.544087354777;7604.353629941406;24892.3776905577;25060.749392506023;25651.743482565307;26501.602475962874;26985.32536686579;3087.4596945534668;3107.64262109858;3206.1343437271807;3207.3905957867933;3560.412531932228;16701.582460438465;16855.325786968508;16936.3225470981;17154;17557.736633950502;2320.872352020638;2330.4057465346395;2353.364591560286;2372.098602531027;2436.5857804173334;103772;108721.9330743507;112627;115350;115559;18167.273309067754;18388.724169137473;18458.63082738338;18977;19169.712454313198;32299.192520186712;32311;32362;32380.190495237337;32521.837390812896;10436.37381757097;10512.57949682004;10622.725272967138;10631.712726457114;11028.558857645605;9039.322050846213;9088.591013404413;9110.134537218953;9283.860742088875;9315.39449935752;9278.72163835086;9279.448151758186;9304.8604270936;9624.615015399446;9698.903010969942;3167.920801979923;3363.9495407568907;3418.1112910642914;3569.607343192247;3644.526211592476;967.0938828819353;967.7000129959735;970.7670159161892;999.8300288950895;1003.8494225866178;19688.803111968697;21101.68347474789;21146.15415383398;21162.576748465253;21238.150473980866;986.9703908882746;989.6338354808623;994.8855881573567;1001.5593139018808;1010.6058637131524;11198.496067676973;11690.590829320932;11880.762384752257;11900.345480998629;11920.165588408729;458863;479409;480873;654515;672191;100343.49828250812;102679.48660256651;108912;115301.423492882;119536;1331.4407948661585;1378.1111183286923;1385.4250486048177;1394.679223778557;1409.302395314326;19006.429807105807;19222.423327300203;19234.8076519233;19306.517337066685;19449.902750486723;53263.733681331345;53325.73337133289;54236.728816355666;54249.72875135599;54549.45450545602;73047;73237.26762732517;74073.6296318515;74240;74753.25246747462;12405.689857753629;12770.808437873626;12924.353782311035;12970.546030888874;13060.151090179108;13521.526746563823;13771.449142034206;14061.36723847409;14087.366068527146;14340.498082567061;11910.166288359731;12413.627591172279;12493.500259989498;12674.493020279086;12748.936255319035;11735.76528469443;11852.762944740884;11933.343666098448;12002.579909703118;12085.939570302093;12290.446929887998;12331.321777302335;12520.74958500843;12674;13029.218246905217;12715.93642031784;12862.67843303925;12911.612651620466;12941.870581294066;12973.740525189633;12399.380030998234;12471.688207794878;12512.436940337524;12639.936800316307;12777.488900443877 14:44:30 INFO - PROCESS | 32231 | |3;mootools.html;1477.4828809916537;1587.3809214406383;1595.9760603590946;1615.806103267608;1625.561098503404;9496.905030949689;11385.715357116076;11614.070874330055;11982.460789264504;12029.21810082344;11736.29582225065;11812.468438920248;11818.58634947777;11818.645440636794;11828.704282392944;2067.6795096760025;2125.5217576045343;2455.5211733711935;2496.2386472125995;2498.7001559812848;2580.6903171619433;2588.987055064722;2599.3111825366295;2600.9739902601013;2608.843469391833;2477.541654793869;2492.0654754467073;2500.087468074158;2503.198976327576;2504.186139504659;2330.2193765088714;2339.6607491913664;2350.9647355289685;2410.529946660402;2452.49723806621;2446.4617784087473;2459.7417271186628;2466.728659847415;2476.232367965936;2485.142625794116;11699.532018719241;11915.046796256365;11915.523379064827;11918.404079795975;11922.940385298105;6023.307319658252;6180.5982611130585;6185.536084793614;6185.938140618581;6189.319174890758;3628.11111277736;3638.4542318652148;3711.109333759878;3753.474513568103;3784.829682664287;1355.9932200339033;1374.2510331869055;1432.1478720161592;1464.9194294313809;1480.674251664632;17851.642967140586;17878.731819022723;17986.640267194714;17998;18034.27862885483;4923.433805112386;5039;5083.6187285952965;5131.948680513222;5154.14956532168;13339.599812005656;13386.062975591614;13403.061785674909;13408.463661453432;13421.664458388423;6285.654289014148;6290.433860952537;6293.685315734195;6293.811185664437;6296.0555916612875;4551.590356867898;5098.133317336062;5659.179418984307;5747.425257474303;5811.186433899257;4132;4219.324908014764;4227.894302642459;4260.8508702195895;4272.487301523837;1067.765091679829;1074.2694967422228;1079.093561408405;1084.1651928015383;1094.2176343914139;985.6500942165428;1077.2620754783;1113.4154568851418;1115.9442027898572;1126.4649291586431;7404.407647388196;7510.84978300431;7567.583782891884;7599.4680372374505;7717.112532058773;1121.2095472691733;1131.3890499130378;1133.6259034518755;1162.593092417647;1171.3557543351124;4637.582617564435;4968.701877887338;5065.113605119163;5124.590032797368;5150.536451719363;14765;15272.541823745305;15317;15352.846471535142;15433.151176685391;14628.268586570628;15146.545603631688;15177;15221.847781522265;15296.617584560252;11043.282186657842;11249.550017999352;11260.943695281636;11302;11304.71738206535;7244.782656520313;7511.849763004819;7536.171021187555;7591.316781489692;7600.125985511737;13321.600351989457;13410.865891340962;13537.187768733907;13811.51659691906;13814.102083364549;13754.862451375759;13819.239941803296;13897.027208095336;13903.235322057384;14040.227787471787;9438.811223775348;9708.902910970799;9772.462514561767;9799.412035277906;9814.312998090065;11082.944585277022;11246.212765106691;11288.43557822122;11360.431978401211;11427.428628568705;8616.396852220285;8721.694740684046;8863.867041994246;9088;9104.726858194264;9004.459732416077;9014.504202268938;9038.502882341534;9135.908640913505;9352.906470935204;8263.793405164919;8309.83380332402;8387.161283871565;8388.874166887379;8393.412461127908;6573.1783527059115;6677.232118306262;6736.4274036705465;6747.493937954575;6805.6937437816405;6594.538382313384;6757.391834734897;6763.391294783296;6783.402589100239;6848.486363522656 14:44:30 INFO - PROCESS | 32231 | |4;prototype.html;430.16762564437795;446.4976900986392;449.1869715814376;450.2098816576907;452.1070885002121;5291.28567643368;5304.628675992677;5308.628396012281;5309.097453432916;5359.437259087788;5176.66351687141;5279.340082489689;5283.867903302419;5292.523672869441;5318.654287471311;4265.8080386382535;4267.935980960288;4270.97864510677;4292.377605247238;4335.284678028122;4338.891527711817;4350.36919646651;4354.433923589945;4357.782110894458;4362.760048197348;4254.212970600449;4311.477785495489;4345.565443455661;4354.17270718564;4378.985666593371;4303.65570754342;4306.913861722748;4320.891977700567;4323.805428755713;4344.500382456011;3911.4523966644692;3935.4293627423863;3947.46709194258;3972.2254160438724;3996.6403023728003;5185.196294574347;5223.589630799663;5240.502152295526;5250;5259.973700131475;4793.616510679138;4889.535494128052;4948.78754705096;4957.02578452929;4967.7019378837385;5472;5525.76206636313;5550.47270509301;5561.805336813192;5562.527185189264;4323.870283891489;4328.718633288826;4334.956650433487;4338.175746608134;4341.261985462479;5447.836564903059;5468;5528.502434780889;5646.632968857052;5700.201971723962;4675.742834144155;4719.315699223594;4734.739589322551;4778.761061946889;4797.784099715521;4784.712917224978;4796.832110876103;4810.254410566367;4866.975665121722;4868.2210846264425;5013.24801279811;5067.695938243717;5140.383154021467;5141.151709967889;5170.37955445349;5528.944710552843;5539.473749993744;5541.229385307346;5547.645098597582;5559.833205003856;1617.6036870966689;1634.4442889417655;1658.7642206555952;1679.1520282257381;1687.2829047654698;1272.1858010872863;1290.8317972235143;1301.3383924469158;1303.7066660001424;1328.734253149374;5423.830467812906;5425.810096646677;5455.181722741541;5460.590455715837;5484.643498172606;5567.331920169606;5642.351129620064;5679.166562570249;5708.372079071299;5731.936502858967;5038.899222015539;5361.785528578813;5372.865678358073;5383.838484845461;5406.135018397038;2966.4363770883465;2982.8806847726282;2985.5820185174093;2998.460277150134;2998.4752668282963;5531.3362396511875;5834.474897259182;5855.4730074292665;5871.858555672695;5879.676617785977;5714.142878568248;5769.336526299529;5804.970975145097;5833.7540047082175;5847.853803654858;5854.414558544111;5890.970545147246;5910.734016969161;5914.822555323347;5980.342162362137;5545.500904918576;5583.413741557078;5680.517156041617;5692.6299790513485;5718.79984200551;5464.644798088111;5641.633293835887;5657.8019769307875;5687.971560142173;5688.77244910199;5815.41845815415;5845.269341332333;5921.407859214044;5933.347331793413;5955.034838503197;4552.314134660566;4638.814447422065;4643.3963584733765;4749.242780171358;4765.69023013503;5551.278333816578;5587.55299576041;5651.915221271602;5703.258576385209;5718.971405142947;5521.337439507124;5533.335999680065;5540.69526176048;5552.416996215339;5585;5360;5495.862603434786;5528.834134975958;5587.497125258747;5592.3848376677715;5508.91736623959;5532.834014979557;5535.501804837423;5536.2249285099315;5583.609147359808;5401.648892821954;5568.081266591129;5578.972105139448;5589.888202236014;5641.69960573053;5487.176923461513;5495.697736624365;5497.725113744217;5503.477169668875;5510.641808282448 14:44:30 INFO - PROCESS | 32231 | |5;yui.html;803.7789607857835;889.2574700125394;921.8617207418887;926.1386910173541;947.1049857884299;16014.83985160148;17234.65530689387;17265.13674316284;17469.912650436763;19317.903410482962;14450.494232701858;15654.060756354627;15877.761833572506;15878.52364429066;16022.118783466905;680.0411419897945;688.1432616392573;880.6345366672823;883.2536506651901;909.5315912305168;895.3956079646213;903.9548022598876;904.159132007233;907.791208022152;909.1136142261308;955.8231727130468;958.3770549143043;959.5633986536109;960.4861399151455;962.1821451766012;947.4883562876039;947.734634302393;948.7059011706324;951.9619215231382;956.8564715292692;695.7982185166296;703.3318347569829;703.3669697272445;703.6341102626657;703.6727921516468;16639.916800416042;16768.832311676848;16869.24088416024;17820.10899455022;17927.83469074506;17315.82684173155;17928.193231304624;18205.362812301504;18283.542911427186;18487.2605095796;13401.329933503383;13552.796708049387;13601.979851511176;13641.727165456734;13822.585322440344;4012.478377810866;4054.7567145971334;4061.776602286873;4071.124708187724;4136.648384887289;87526.5623671884;87953;88161.55919220363;89204;91238.08761912429;13360.271761198675;13364.131331463424;13463.259520726455;13517.526886558922;13676.93161534186;26663.866680666473;26687.866560667073;26761.464770704475;26777.86611066971;27263.591046134323;8848.1151884811;8983.77540561478;8984.281257499384;9002;9219.124183202584;5194.27280180775;5199.818006369759;5257.947420525822;5283;5360.249565060894;3570.8750193743094;3685.834137463767;3691.8707845225827;3695.279420512974;3743.756655817363;608.6834845880118;611.6634456376687;618.6350053468475;620.0853740732383;627.1439485102802;833.6373677450329;844.6241422566899;860.5310105992249;874.8993865705398;878.7934835313696;10883.748368937515;11641.301521908712;11705;11773.705657358463;12206.633800985985;745.8172747676854;752.6387334079564;768.1205020251781;775.5230533222141;775.6587101675245;5136.178211486144;5342.813001544927;5364.409914909357;5444.727763611803;5447.073997420448;62634;64058;78193.21806781973;79199;80572.59713701511;73023.26976730165;73228.63385683038;75127.62436187784;77070.22929770742;77630;52457.2131418029;65807.67096164488;66384;66765;66833;11634.185607007428;11776.175667703179;11797.823032654345;11938.52245910154;12127.332996684914;41677.58322416737;42129.157416852104;42268.788656056524;42334.36498452464;43260;51463;51668;51757.482425175265;51924.22113668222;52083.479165207864;12707.682307942376;12733.172343797623;12822.102452828212;12847;13653.931730341284;16271.674566508367;16541;16774.66450671004;17238.827611723722;17490;14330.856691432953;14545.854541454873;14591.416343346147;15338.38646454174;15397.307121179798;14572.708545829237;14588.416463341347;15005.399784008518;15240.923795380952;15277.847221528087;14072.226027568582;14632.487862925173;14758.262086895398;15459.536213913601;15494.302756376215;10939.343639381663;11362.602308919148;11472.598459054227;11662.358570278377;11812.763744724885;12708.682282943;12863.678408039874;12922.289274089639;13124.409401577142;13126.14680045794 14:44:30 INFO - PROCESS | 32231 | __end_tp_report 14:44:30 INFO - PROCESS | 32231 | __start_cc_report 14:44:30 INFO - PROCESS | 32231 | _x_x_mozilla_cycle_collect,1213 14:44:30 INFO - PROCESS | 32231 | __end_cc_report 14:44:30 INFO - PROCESS | 32231 | __startTimestamp1496353470093__endTimestamp 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | ------- Summary: start ------- 14:44:30 INFO - PROCESS | 32231 | Number of tests: 6 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#0] dojo.html Cycles:165 Average:5766.87 Median:6020.43 stddev:710.80 (11.8%) stddev-sans-first:688.57 14:44:30 INFO - PROCESS | 32231 | Values: 3412.5 3445.6 3448.5 3467.3 3481.9 5753.7 5841.3 5844.4 5852.4 5863.4 5843.9 5887.5 5889.6 5892.0 5906.2 5308.2 5328.6 5342.6 5359.9 5440.4 5162.6 5207.8 5420.0 5420.1 5420.3 5274.7 5286.8 5319.0 5328.4 5331.5 5374.7 5402.4 5428.1 5428.9 5434.5 5313.1 5332.6 5392.1 5393.9 5414.5 5628.8 5656.9 5664.3 5734.9 5849.8 6256.6 6258.4 6259.7 6261.0 6262.1 6303.4 6304.4 6313.6 6316.7 6324.0 5609.1 5693.8 5698.5 5863.9 5869.1 6278.3 6286.7 6301.6 6314.4 6332.7 6180.4 6184.0 6189.7 6192.3 6198.4 5996.0 6186.1 6256.4 6263.4 6268.6 6089.0 6122.1 6157.1 6158.9 6174.0 6033.6 6075.1 6082.2 6088.6 6237.3 4193.7 4223.4 4226.6 4227.3 4229.2 3849.7 3884.9 3903.5 3908.6 3916.0 5974.6 5983.8 5995.0 6020.0 6022.3 6297.8 6320.9 6325.9 6333.1 6333.6 5679.6 5716.7 5720.6 5735.1 5806.0 5218.9 5221.9 5222.4 5229.3 5232.7 6303.3 6393.9 6402.1 6441.6 6486.7 6316.6 6322.3 6325.7 6330.2 6340.2 6231.8 6578.2 6581.6 6590.7 6592.7 6088.9 6107.2 6145.3 6145.5 6161.1 6312.7 6325.7 6342.8 6349.7 6353.2 6156.6 6161.2 6175.2 6179.9 6181.9 5867.7 5913.0 6020.4 6070.3 6079.6 6070.8 6077.9 6084.1 6092.3 6122.2 6169.2 6170.6 6173.8 6179.8 6180.3 5806.8 5859.1 5875.1 5927.2 5943.8 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#1] ext.html Cycles:180 Average:46027.08 Median:32057.92 stddev:76024.05 (237.1%) stddev-sans-first:76161.39 14:44:30 INFO - PROCESS | 32231 | Values: 773.5 843.3 859.5 924.0 931.2 30905.5 31200.7 33528.8 33609.5 49703.8 45923.5 46361.5 46381.8 46415.3 46437.1 9128.3 9252.8 9263.4 9264.8 9268.6 7515.3 7662.9 9131.9 9249.8 10690.8 10859.2 10899.2 10899.9 10920.0 11015.4 10937.8 10961.6 10963.7 10973.9 10978.5 10677.4 10746.1 10747.5 10753.1 10764.2 42546.6 42685.8 42728.1 42730.4 42794.0 4346.7 4401.2 4409.0 4518.3 4547.9 30635.5 31135.2 31241.8 31391.5 32055.8 4370.7 4419.9 4470.6 4529.1 4533.3 239486.0 240993.0 241274.0 241534.0 241545.0 55598.0 55767.4 55806.4 55822.7 55841.4 58828.7 58877.0 58941.1 58999.4 59007.4 16514.1 16535.8 16553.4 16673.0 16673.8 14632.4 14635.4 14635.6 14638.0 14652.3 12448.8 12699.7 13952.2 14176.9 14306.9 5502.6 5590.2 5672.5 5673.1 5771.1 1013.2 1013.7 1021.5 1025.1 1025.2 29149.7 29300.7 29355.6 29650.3 29713.9 1083.5 1083.5 1088.6 1106.0 1107.1 14989.8 15679.0 15740.8 15786.3 15806.2 300041.0 301339.0 302271.0 302952.0 303255.0 57171.7 59280.0 59282.7 59285.4 59375.7 1492.5 1496.4 1503.8 1510.6 1524.5 28167.7 29730.7 30064.7 30258.7 30415.4 38970.4 39245.8 39315.0 39348.8 39391.8 322551.0 323687.0 324118.0 324739.0 324754.0 29852.9 30477.5 30593.5 30671.5 34137.0 34423.7 34977.8 39242.4 42182.8 46520.0 32041.2 32486.5 32633.0 32922.7 33376.0 32762.7 33087.3 33280.5 33343.5 33703.7 37193.0 37538.4 38023.0 38570.0 38636.8 31602.7 31841.4 31968.4 32060.0 32103.5 24784.0 25298.2 25673.2 25693.6 25815.9 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#2] jquery.html Cycles:180 Average:34927.58 Median:12853.59 stddev:92346.57 (718.4%) stddev-sans-first:92569.39 14:44:30 INFO - PROCESS | 32231 | Values: 471.8 486.6 488.7 498.3 499.2 21609.8 22580.9 23498.1 23537.1 23642.8 22577.3 22631.3 22836.3 23088.0 23376.8 6499.3 6928.3 6971.0 6977.3 6983.0 6904.1 6965.5 7232.4 7495.3 7541.6 7399.5 7641.2 7683.0 7691.3 7701.3 7670.8 7728.0 7750.4 7772.3 7773.0 7356.2 7559.0 7592.6 7598.5 7604.4 24892.4 25060.7 25651.7 26501.6 26985.3 3087.5 3107.6 3206.1 3207.4 3560.4 16701.6 16855.3 16936.3 17154.0 17557.7 2320.9 2330.4 2353.4 2372.1 2436.6 103772.0 108721.9 112627.0 115350.0 115559.0 18167.3 18388.7 18458.6 18977.0 19169.7 32299.2 32311.0 32362.0 32380.2 32521.8 10436.4 10512.6 10622.7 10631.7 11028.6 9039.3 9088.6 9110.1 9283.9 9315.4 9278.7 9279.4 9304.9 9624.6 9698.9 3167.9 3363.9 3418.1 3569.6 3644.5 967.1 967.7 970.8 999.8 1003.8 19688.8 21101.7 21146.2 21162.6 21238.2 987.0 989.6 994.9 1001.6 1010.6 11198.5 11690.6 11880.8 11900.3 11920.2 458863.0 479409.0 480873.0 654515.0 672191.0 100343.5 102679.5 108912.0 115301.4 119536.0 1331.4 1378.1 1385.4 1394.7 1409.3 19006.4 19222.4 19234.8 19306.5 19449.9 53263.7 53325.7 54236.7 54249.7 54549.5 73047.0 73237.3 74073.6 74240.0 74753.3 12405.7 12770.8 12924.4 12970.5 13060.2 13521.5 13771.4 14061.4 14087.4 14340.5 11910.2 12413.6 12493.5 12674.5 12748.9 11735.8 11852.8 11933.3 12002.6 12085.9 12290.4 12331.3 12520.7 12674.0 13029.2 12715.9 12862.7 12911.6 12941.9 12973.7 12399.4 12471.7 12512.4 12639.9 12777.5 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#3] mootools.html Cycles:180 Average:7376.35 Median:2481.34 stddev:4724.09 (190.4%) stddev-sans-first:4716.54 14:44:30 INFO - PROCESS | 32231 | Values: 1477.5 1587.4 1596.0 1615.8 1625.6 9496.9 11385.7 11614.1 11982.5 12029.2 11736.3 11812.5 11818.6 11818.6 11828.7 2067.7 2125.5 2455.5 2496.2 2498.7 2580.7 2589.0 2599.3 2601.0 2608.8 2477.5 2492.1 2500.1 2503.2 2504.2 2330.2 2339.7 2351.0 2410.5 2452.5 2446.5 2459.7 2466.7 2476.2 2485.1 11699.5 11915.0 11915.5 11918.4 11922.9 6023.3 6180.6 6185.5 6185.9 6189.3 3628.1 3638.5 3711.1 3753.5 3784.8 1356.0 1374.3 1432.1 1464.9 1480.7 17851.6 17878.7 17986.6 17998.0 18034.3 4923.4 5039.0 5083.6 5131.9 5154.1 13339.6 13386.1 13403.1 13408.5 13421.7 6285.7 6290.4 6293.7 6293.8 6296.1 4551.6 5098.1 5659.2 5747.4 5811.2 4132.0 4219.3 4227.9 4260.9 4272.5 1067.8 1074.3 1079.1 1084.2 1094.2 985.7 1077.3 1113.4 1115.9 1126.5 7404.4 7510.8 7567.6 7599.5 7717.1 1121.2 1131.4 1133.6 1162.6 1171.4 4637.6 4968.7 5065.1 5124.6 5150.5 14765.0 15272.5 15317.0 15352.8 15433.2 14628.3 15146.5 15177.0 15221.8 15296.6 11043.3 11249.6 11260.9 11302.0 11304.7 7244.8 7511.8 7536.2 7591.3 7600.1 13321.6 13410.9 13537.2 13811.5 13814.1 13754.9 13819.2 13897.0 13903.2 14040.2 9438.8 9708.9 9772.5 9799.4 9814.3 11082.9 11246.2 11288.4 11360.4 11427.4 8616.4 8721.7 8863.9 9088.0 9104.7 9004.5 9014.5 9038.5 9135.9 9352.9 8263.8 8309.8 8387.2 8388.9 8393.4 6573.2 6677.2 6736.4 6747.5 6805.7 6594.5 6757.4 6763.4 6783.4 6848.5 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#4] prototype.html Cycles:180 Average:4807.18 Median:5359.72 stddev:1288.61 (24.0%) stddev-sans-first:1249.65 14:44:30 INFO - PROCESS | 32231 | Values: 430.2 446.5 449.2 450.2 452.1 5291.3 5304.6 5308.6 5309.1 5359.4 5176.7 5279.3 5283.9 5292.5 5318.7 4265.8 4267.9 4271.0 4292.4 4335.3 4338.9 4350.4 4354.4 4357.8 4362.8 4254.2 4311.5 4345.6 4354.2 4379.0 4303.7 4306.9 4320.9 4323.8 4344.5 3911.5 3935.4 3947.5 3972.2 3996.6 5185.2 5223.6 5240.5 5250.0 5260.0 4793.6 4889.5 4948.8 4957.0 4967.7 5472.0 5525.8 5550.5 5561.8 5562.5 4323.9 4328.7 4335.0 4338.2 4341.3 5447.8 5468.0 5528.5 5646.6 5700.2 4675.7 4719.3 4734.7 4778.8 4797.8 4784.7 4796.8 4810.3 4867.0 4868.2 5013.2 5067.7 5140.4 5141.2 5170.4 5528.9 5539.5 5541.2 5547.6 5559.8 1617.6 1634.4 1658.8 1679.2 1687.3 1272.2 1290.8 1301.3 1303.7 1328.7 5423.8 5425.8 5455.2 5460.6 5484.6 5567.3 5642.4 5679.2 5708.4 5731.9 5038.9 5361.8 5372.9 5383.8 5406.1 2966.4 2982.9 2985.6 2998.5 2998.5 5531.3 5834.5 5855.5 5871.9 5879.7 5714.1 5769.3 5805.0 5833.8 5847.9 5854.4 5891.0 5910.7 5914.8 5980.3 5545.5 5583.4 5680.5 5692.6 5718.8 5464.6 5641.6 5657.8 5688.0 5688.8 5815.4 5845.3 5921.4 5933.3 5955.0 4552.3 4638.8 4643.4 4749.2 4765.7 5551.3 5587.6 5651.9 5703.3 5719.0 5521.3 5533.3 5540.7 5552.4 5585.0 5360.0 5495.9 5528.8 5587.5 5592.4 5508.9 5532.8 5535.5 5536.2 5583.6 5401.6 5568.1 5579.0 5589.9 5641.7 5487.2 5495.7 5497.7 5503.5 5510.6 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - PROCESS | 32231 | [#5] yui.html Cycles:180 Average:18725.47 Median:41903.37 stddev:23031.38 (55.0%) stddev-sans-first:23056.67 14:44:30 INFO - PROCESS | 32231 | Values: 803.8 889.3 921.9 926.1 947.1 16014.8 17234.7 17265.1 17469.9 19317.9 14450.5 15654.1 15877.8 15878.5 16022.1 680.0 688.1 880.6 883.3 909.5 895.4 904.0 904.2 907.8 909.1 955.8 958.4 959.6 960.5 962.2 947.5 947.7 948.7 952.0 956.9 695.8 703.3 703.4 703.6 703.7 16639.9 16768.8 16869.2 17820.1 17927.8 17315.8 17928.2 18205.4 18283.5 18487.3 13401.3 13552.8 13602.0 13641.7 13822.6 4012.5 4054.8 4061.8 4071.1 4136.6 87526.6 87953.0 88161.6 89204.0 91238.1 13360.3 13364.1 13463.3 13517.5 13676.9 26663.9 26687.9 26761.5 26777.9 27263.6 8848.1 8983.8 8984.3 9002.0 9219.1 5194.3 5199.8 5257.9 5283.0 5360.2 3570.9 3685.8 3691.9 3695.3 3743.8 608.7 611.7 618.6 620.1 627.1 833.6 844.6 860.5 874.9 878.8 10883.7 11641.3 11705.0 11773.7 12206.6 745.8 752.6 768.1 775.5 775.7 5136.2 5342.8 5364.4 5444.7 5447.1 62634.0 64058.0 78193.2 79199.0 80572.6 73023.3 73228.6 75127.6 77070.2 77630.0 52457.2 65807.7 66384.0 66765.0 66833.0 11634.2 11776.2 11797.8 11938.5 12127.3 41677.6 42129.2 42268.8 42334.4 43260.0 51463.0 51668.0 51757.5 51924.2 52083.5 12707.7 12733.2 12822.1 12847.0 13653.9 16271.7 16541.0 16774.7 17238.8 17490.0 14330.9 14545.9 14591.4 15338.4 15397.3 14572.7 14588.4 15005.4 15240.9 15277.8 14072.2 14632.5 14758.3 15459.5 15494.3 10939.3 11362.6 11472.6 11662.4 11812.8 12708.7 12863.7 12922.3 13124.4 13126.1 14:44:30 INFO - PROCESS | 32231 | -------- Summary: end -------- 14:44:30 INFO - PROCESS | 32231 | 14:44:30 INFO - TEST-INFO | 32231: exit 0 14:44:30 INFO - TEST-OK | dromaeo_css | took 1115706ms 14:44:30 INFO - TEST-START | kraken 14:44:30 INFO - operating with platform_type : linux_ 14:44:30 INFO - Initialising browser for kraken test... 14:44:30 INFO - TEST-INFO | started process 32538 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2PBA9A/profile http://localhost:53601/getInfo.html) 14:44:34 INFO - PROCESS | 32538 | __metrics Screen width/height:1600/1200 14:44:34 INFO - PROCESS | 32538 | colorDepth:24 14:44:34 INFO - PROCESS | 32538 | Browser inner width/height: 1024/697 14:44:34 INFO - PROCESS | 32538 | __metrics 14:44:35 INFO - PROCESS | 32538 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:44:35 INFO - PROCESS | 32538 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:44:36 INFO - TEST-INFO | 32538: exit 0 14:44:36 INFO - Browser initialized. 14:44:36 INFO - Running cycle 1/1 for kraken test... 14:44:36 INFO - TEST-INFO | started process 32634 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2PBA9A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:44:48 INFO - PROCESS | 32634 | RSS: Main: 141606912 14:44:48 INFO - PROCESS | 32634 | 14:44:50 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53601/tests/kraken/driver.html?testName=audio-beat-detection) 14:44:51 INFO - PROCESS | 32634 | RSS: Main: 183775232 14:44:51 INFO - PROCESS | 32634 | 14:44:54 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53601/tests/kraken/driver.html?testName=audio-dft) 14:44:54 INFO - PROCESS | 32634 | RSS: Main: 173699072 14:44:54 INFO - PROCESS | 32634 | 14:44:57 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53601/tests/kraken/driver.html?testName=audio-fft) 14:44:58 INFO - PROCESS | 32634 | RSS: Main: 265318400 14:44:58 INFO - PROCESS | 32634 | 14:45:00 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53601/tests/kraken/driver.html?testName=audio-oscillator) 14:45:00 INFO - PROCESS | 32634 | RSS: Main: 171520000 14:45:00 INFO - PROCESS | 32634 | 14:45:02 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53601/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:45:03 INFO - PROCESS | 32634 | RSS: Main: 174714880 14:45:03 INFO - PROCESS | 32634 | 14:45:06 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53601/tests/kraken/driver.html?testName=imaging-darkroom) 14:45:06 INFO - PROCESS | 32634 | RSS: Main: 211337216 14:45:06 INFO - PROCESS | 32634 | 14:45:10 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53601/tests/kraken/driver.html?testName=imaging-desaturate) 14:45:10 INFO - PROCESS | 32634 | RSS: Main: 212811776 14:45:10 INFO - PROCESS | 32634 | 14:45:13 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53601/tests/kraken/driver.html?testName=json-parse-financial) 14:45:14 INFO - PROCESS | 32634 | RSS: Main: 211808256 14:45:14 INFO - PROCESS | 32634 | 14:45:15 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53601/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:45:16 INFO - PROCESS | 32634 | RSS: Main: 173932544 14:45:16 INFO - PROCESS | 32634 | 14:45:18 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:45:18 INFO - PROCESS | 32634 | RSS: Main: 180338688 14:45:18 INFO - PROCESS | 32634 | 14:45:20 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:45:21 INFO - PROCESS | 32634 | RSS: Main: 185098240 14:45:21 INFO - PROCESS | 32634 | 14:45:23 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:45:24 INFO - PROCESS | 32634 | RSS: Main: 181096448 14:45:24 INFO - PROCESS | 32634 | 14:45:27 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:45:27 INFO - PROCESS | 32634 | RSS: Main: 185053184 14:45:27 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | Cycle 1(1): loaded http://localhost:53601/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53601/tests/kraken/driver.html?testName=ai-astar) 14:45:29 INFO - PROCESS | 32634 | RSS: Main: 238227456 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | __start_tp_report 14:45:29 INFO - PROCESS | 32634 | _x_x_mozilla_page_load 14:45:29 INFO - PROCESS | 32634 | _x_x_mozilla_page_load_details 14:45:29 INFO - PROCESS | 32634 | |i|pagename|runs| 14:45:29 INFO - PROCESS | 32634 | |0;ai-astar;105;113;112;113;113;113;112;112;111;112 14:45:29 INFO - PROCESS | 32634 | |1;audio-beat-detection;146;116;116;148;115;115;114;114;138;115 14:45:29 INFO - PROCESS | 32634 | |2;audio-dft;161;160;162;162;160;162;160;160;162;161 14:45:29 INFO - PROCESS | 32634 | |3;audio-fft;75;75;75;113;76;75;75;75;90;74 14:45:29 INFO - PROCESS | 32634 | |4;audio-oscillator;83;83;103;82;82;83;101;82;82;100 14:45:29 INFO - PROCESS | 32634 | |5;imaging-gaussian-blur;112;113;112;113;112;113;112;113;112;113 14:45:29 INFO - PROCESS | 32634 | |6;imaging-darkroom;146;145;147;146;147;147;147;146;147;146 14:45:29 INFO - PROCESS | 32634 | |7;imaging-desaturate;94;94;94;94;93;93;94;94;93;94 14:45:29 INFO - PROCESS | 32634 | |8;json-parse-financial;54;54;56;54;54;55;56;55;55;54 14:45:29 INFO - PROCESS | 32634 | |9;json-stringify-tinderbox;54;52;52;52;52;51;54;52;51;52 14:45:29 INFO - PROCESS | 32634 | |10;stanford-crypto-aes;85;74;78;76;79;79;79;81;80;109 14:45:29 INFO - PROCESS | 32634 | |11;stanford-crypto-ccm;137;121;136;129;119;130;118;118;120;118 14:45:29 INFO - PROCESS | 32634 | |12;stanford-crypto-pbkdf2;163;159;158;157;166;157;159;166;160;156 14:45:29 INFO - PROCESS | 32634 | |13;stanford-crypto-sha256-iterative;60;60;63;60;60;59;60;62;58;58 14:45:29 INFO - PROCESS | 32634 | __end_tp_report 14:45:29 INFO - PROCESS | 32634 | __start_cc_report 14:45:29 INFO - PROCESS | 32634 | _x_x_mozilla_cycle_collect,1647 14:45:29 INFO - PROCESS | 32634 | __end_cc_report 14:45:29 INFO - PROCESS | 32634 | __startTimestamp1496353529704__endTimestamp 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | ------- Summary: start ------- 14:45:29 INFO - PROCESS | 32634 | Number of tests: 14 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.41 (2.1%) stddev-sans-first:0.71 14:45:29 INFO - PROCESS | 32634 | Values: 105.0 113.0 112.0 113.0 113.0 113.0 112.0 112.0 111.0 112.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:116.00 stddev:14.24 (12.3%) stddev-sans-first:12.62 14:45:29 INFO - PROCESS | 32634 | Values: 146.0 116.0 116.0 148.0 115.0 115.0 114.0 114.0 138.0 115.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:0.94 (0.6%) stddev-sans-first:1.00 14:45:29 INFO - PROCESS | 32634 | Values: 161.0 160.0 162.0 162.0 160.0 162.0 160.0 160.0 162.0 161.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#3] audio-fft Cycles:10 Average:80.30 Median:75.00 stddev:12.43 (16.6%) stddev-sans-first:13.03 14:45:29 INFO - PROCESS | 32634 | Values: 75.0 75.0 75.0 113.0 76.0 75.0 75.0 75.0 90.0 74.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:82.00 stddev:9.17 (11.2%) stddev-sans-first:9.54 14:45:29 INFO - PROCESS | 32634 | Values: 83.0 83.0 103.0 82.0 82.0 83.0 101.0 82.0 82.0 100.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#5] imaging-gaussian-blur Cycles:10 Average:112.50 Median:113.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:45:29 INFO - PROCESS | 32634 | Values: 112.0 113.0 112.0 113.0 112.0 113.0 112.0 113.0 112.0 113.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:45:29 INFO - PROCESS | 32634 | Values: 146.0 145.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 146.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:45:29 INFO - PROCESS | 32634 | Values: 94.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 93.0 94.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 14:45:29 INFO - PROCESS | 32634 | Values: 54.0 54.0 56.0 54.0 54.0 55.0 56.0 55.0 55.0 54.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#9] json-stringify-tinderbox Cycles:10 Average:52.20 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:0.87 14:45:29 INFO - PROCESS | 32634 | Values: 54.0 52.0 52.0 52.0 52.0 51.0 54.0 52.0 51.0 52.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:79.00 stddev:9.92 (12.6%) stddev-sans-first:10.46 14:45:29 INFO - PROCESS | 32634 | Values: 85.0 74.0 78.0 76.0 79.0 79.0 79.0 81.0 80.0 109.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:125.00 stddev:7.66 (6.1%) stddev-sans-first:6.69 14:45:29 INFO - PROCESS | 32634 | Values: 137.0 121.0 136.0 129.0 119.0 130.0 118.0 118.0 120.0 118.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.10 Median:159.50 stddev:3.67 (2.3%) stddev-sans-first:3.73 14:45:29 INFO - PROCESS | 32634 | Values: 163.0 159.0 158.0 157.0 166.0 157.0 159.0 166.0 160.0 156.0 14:45:29 INFO - PROCESS | 32634 | 14:45:29 INFO - PROCESS | 32634 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:1.56 (2.6%) stddev-sans-first:1.66 14:45:29 INFO - PROCESS | 32634 | Values: 60.0 60.0 63.0 60.0 60.0 59.0 60.0 62.0 58.0 58.0 14:45:29 INFO - PROCESS | 32634 | -------- Summary: end -------- 14:45:29 INFO - PROCESS | 32634 | 14:45:30 INFO - TEST-INFO | 32634: exit 0 14:45:30 INFO - TEST-OK | kraken | took 59975ms 14:45:30 INFO - SUITE-END | took 1175s 14:45:30 INFO - Completed test suite (00:19:35) 14:45:30 INFO - javascript benchmark 14:45:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7952.314671170338, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3412.539307193529, 3445.5693038370205, 3448.517207590937, 3467.2545402738438, 3481.878134265301, 5753.683547404891, 5841.299044114701, 5844.444777746118, 5852.414758524145, 5863.413658634135, 5843.883122337551, 5887.499562537189, 5889.646621202731, 5892.0, 5906.173135760997, 5308.150695888649, 5328.573714102882, 5342.572594192475, 5359.919601205966, 5440.401555828856, 5162.561182299509, 5207.791688332462, 5419.972900135494, 5420.051490989081, 5420.268263784378, 5274.683518988873, 5286.814961476349, 5319.0, 5328.413874473805, 5331.546818520431, 5374.7043912584795, 5402.35171779385, 5428.104362780136, 5428.891422171574, 5434.483724046209, 5313.07021271276, 5332.573394128461, 5392.083345831179, 5393.919091213635, 5414.485623865727, 5628.831135065954, 5656.858578535528, 5664.348599911022, 5734.913976290359, 5849.7660093596205, 6256.593321434092, 6258.43674069336, 6259.655718935457, 6261.0295404211965, 6262.091996660504, 6303.401176888188, 6304.432601065926, 6313.558050936436, 6316.684165791692, 6323.9683801580695, 5609.074502707008, 5693.800716974886, 5698.458646428583, 5863.882722345529, 5869.148973398876, 6278.30938596754, 6286.685665716695, 6301.6219026858535, 6314.400131987454, 6332.715027823759, 6180.351063138306, 6184.0, 6189.659568723763, 6192.349803270682, 6198.411150940653, 5996.0, 6186.133941248138, 6256.374362563707, 6263.404976527223, 6268.561200715906, 6089.025755879038, 6122.103333532645, 6157.107219453154, 6158.876822463526, 6174.012158054689, 6033.577649564489, 6075.058365953284, 6082.1789058477425, 6088.634681919099, 6237.282712488031, 4193.706440549132, 4223.429836972031, 4226.598473145046, 4227.281362168439, 4229.196452673982, 3849.653531182207, 3884.941725874114, 3903.4535165076913, 3908.5895980921596, 3915.960840391617, 5974.581779275496, 5983.820485385445, 5995.010823214208, 6020.0, 6022.337542870281, 6297.779577714758, 6320.936790632127, 6325.873482530324, 6333.081703153017, 6333.588316759396, 5679.574031947538, 5716.685582293014, 5720.599558030898, 5735.11105778605, 5806.04200306953, 5218.869528261824, 5221.869453263623, 5222.373315202124, 5229.294045303911, 5232.712200828991, 6303.2751233607805, 6393.904091438632, 6402.135711678957, 6441.613503189824, 6486.708098135689, 6316.589421687576, 6322.272938612118, 6325.683715814099, 6330.240371155491, 6340.207474065742, 6231.75072997075, 6578.243501997332, 6581.605103693793, 6590.736370545124, 6592.736290548324, 6088.939110609014, 6107.219158344477, 6145.324014358506, 6145.463634091477, 6161.106639537332, 6312.684365781784, 6325.652089135142, 6342.809715708536, 6349.68251587428, 6353.205849268841, 6156.569040167145, 6161.229846269216, 6175.166352542529, 6179.938200618116, 6181.907271390843, 5867.677277749594, 5913.0, 6020.4280593343565, 6070.332263450929, 6079.574429789872, 6070.757169713339, 6077.939220607915, 6084.11780291864, 6092.299385570716, 6122.204113465399, 6169.198004259597, 6170.598911070766, 6173.753049877954, 6179.783707570214, 6180.3201647817805, 5806.767729290781, 5859.091840764689, 5875.118732190034, 5927.170196172626, 5943.762249509971], "value": 5714.719325181012, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4856320546837, 843.3127001493782, 859.4542465534388, 924.043614858621, 931.2038207332727, 30905.536416953735, 31200.687993120064, 33528.83235583825, 33609.49585756213, 49703.75148124263, 45923.54076459234, 46361.53638463614, 46381.76809115958, 46415.30377044347, 46437.07125857481, 9128.269738420944, 9252.814943701122, 9263.444193348387, 9264.814703705923, 9268.582913768863, 7515.286047825475, 7662.885056724153, 9131.863022054675, 9249.768755781091, 10690.839637405445, 10859.185561082912, 10899.182561307895, 10899.891001090007, 10920.0, 11015.449227538591, 10937.83593246102, 10961.56153753849, 10963.671089867317, 10973.945130274378, 10978.505967231455, 10677.41274229917, 10746.140308775279, 10747.516361763739, 10753.085987691056, 10764.192685548614, 42546.57453425457, 42685.786571067256, 42728.14543709139, 42730.35904461436, 42794.0, 4346.717463364871, 4401.163778882003, 4409.047785489595, 4518.299663552155, 4547.931781023287, 30635.54046689279, 31135.22161945947, 31241.843790780902, 31391.529127063113, 32055.839720801247, 4370.737755734634, 4419.889502762425, 4470.597646211824, 4529.058315098994, 4533.252013417815, 239486.0, 240993.0, 241274.0, 241534.0, 241545.0, 55598.0, 55767.44232557622, 55806.44193558094, 55822.72088639612, 55841.441585584435, 58828.70585647044, 58877.0, 58941.11588326179, 58999.41000590025, 59007.40992590105, 16514.091724955004, 16535.751963720555, 16553.420630277884, 16673.0, 16673.83326166747, 14632.414703411958, 14635.414583416545, 14635.634109147357, 14638.0, 14652.267386630625, 12448.81326780081, 12699.68250793719, 13952.232627205602, 14176.929115354564, 14306.85693143076, 5502.5597952163735, 5590.161475778585, 5672.517835983987, 5673.063944449203, 5771.105478650816, 1013.2147585621202, 1013.6756238003768, 1021.5454122915231, 1025.1388833379997, 1025.2464438637596, 29149.708502915124, 29300.706992930223, 29355.55966660502, 29650.258743531154, 29713.85143074314, 1083.5340803454433, 1083.544911137324, 1088.6407485529755, 1105.9944700276446, 1107.070061148639, 14989.775153372708, 15679.0, 15740.763888541682, 15786.289616967264, 15806.20968951548, 300041.0, 301339.0, 302271.0, 302952.0, 303255.0, 57171.71414142986, 59280.0, 59282.703586482654, 59285.40714592885, 59375.70312148412, 1492.4627134231669, 1496.423876807437, 1503.8496150384874, 1510.6298956755666, 1524.4892960858015, 28167.71832281733, 29730.702692972794, 30064.699353006188, 30258.697413025588, 30415.39169216559, 38970.4154437678, 39245.803770980965, 39315.0, 39348.80325598468, 39391.80304098461, 322551.0, 323687.0, 324118.0, 324739.0, 324754.0, 29852.850735746182, 30477.54283685791, 30593.54109688312, 30671.53992690156, 34137.0, 34423.65576344305, 34977.825110874284, 39242.411363830135, 42182.78908605437, 46520.0, 32041.198970025005, 32486.512702309956, 32632.99865128185, 32922.67077329196, 33376.0, 32762.672373276917, 33087.338253235284, 33280.500792488616, 33343.49984750182, 33703.66296337103, 37193.0, 37538.436923445624, 38023.0, 38570.0, 38636.80681596574, 31602.683973160892, 31841.363172735953, 31968.36063278675, 32060.0, 32103.518447223778, 24784.0, 25298.241052768448, 25673.229803105936, 25693.61459578145, 25815.870920645277], "value": 19078.499076424705, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.8461840298214, 486.58883243659096, 488.7214287855919, 498.2526210683974, 499.22619939094403, 21609.783902160994, 22580.887095564518, 23498.060077596896, 23537.058517659272, 23642.763572364314, 22577.322680319576, 22631.321060368173, 22836.314910552668, 23088.0, 23376.76623233767, 6499.3175716549795, 6928.2725313842075, 6971.0, 6977.33715297046, 6983.0, 6904.10246667935, 6965.547239429447, 7232.421406287484, 7495.287947644965, 7541.585212813293, 7399.482036257464, 7641.23587641237, 7683.0, 7691.269329413724, 7701.3453856422275, 7670.769876903674, 7728.0, 7750.379969602418, 7772.261635144652, 7772.999999999972, 7356.227596102386, 7559.0, 7592.582407967559, 7598.544087354777, 7604.353629941406, 24892.3776905577, 25060.749392506023, 25651.743482565307, 26501.602475962874, 26985.32536686579, 3087.4596945534668, 3107.64262109858, 3206.1343437271807, 3207.3905957867933, 3560.412531932228, 16701.582460438465, 16855.325786968508, 16936.3225470981, 17154.0, 17557.736633950502, 2320.872352020638, 2330.4057465346395, 2353.364591560286, 2372.098602531027, 2436.5857804173334, 103772.0, 108721.9330743507, 112627.0, 115350.0, 115559.0, 18167.273309067754, 18388.724169137473, 18458.63082738338, 18977.0, 19169.712454313198, 32299.192520186712, 32311.0, 32362.0, 32380.190495237337, 32521.837390812896, 10436.37381757097, 10512.57949682004, 10622.725272967138, 10631.712726457114, 11028.558857645605, 9039.322050846213, 9088.591013404413, 9110.134537218953, 9283.860742088875, 9315.39449935752, 9278.72163835086, 9279.448151758186, 9304.8604270936, 9624.615015399446, 9698.903010969942, 3167.920801979923, 3363.9495407568907, 3418.1112910642914, 3569.607343192247, 3644.526211592476, 967.0938828819353, 967.7000129959735, 970.7670159161892, 999.8300288950895, 1003.8494225866178, 19688.803111968697, 21101.68347474789, 21146.15415383398, 21162.576748465253, 21238.150473980866, 986.9703908882746, 989.6338354808623, 994.8855881573567, 1001.5593139018808, 1010.6058637131524, 11198.496067676973, 11690.590829320932, 11880.762384752257, 11900.345480998629, 11920.165588408729, 458863.0, 479409.0, 480873.0, 654515.0, 672191.0, 100343.49828250812, 102679.48660256651, 108912.0, 115301.423492882, 119536.0, 1331.4407948661585, 1378.1111183286923, 1385.4250486048177, 1394.679223778557, 1409.302395314326, 19006.429807105807, 19222.423327300203, 19234.8076519233, 19306.517337066685, 19449.902750486723, 53263.733681331345, 53325.73337133289, 54236.728816355666, 54249.72875135599, 54549.45450545602, 73047.0, 73237.26762732517, 74073.6296318515, 74240.0, 74753.25246747462, 12405.689857753629, 12770.808437873626, 12924.353782311035, 12970.546030888874, 13060.151090179108, 13521.526746563823, 13771.449142034206, 14061.36723847409, 14087.366068527146, 14340.498082567061, 11910.166288359731, 12413.627591172279, 12493.500259989498, 12674.493020279086, 12748.936255319035, 11735.76528469443, 11852.762944740884, 11933.343666098448, 12002.579909703118, 12085.939570302093, 12290.446929887998, 12331.321777302335, 12520.74958500843, 12674.0, 13029.218246905217, 12715.93642031784, 12862.67843303925, 12911.612651620466, 12941.870581294066, 12973.740525189633, 12399.380030998234, 12471.688207794878, 12512.436940337524, 12639.936800316307, 12777.488900443877], "value": 11752.253752517034, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.4828809916537, 1587.3809214406383, 1595.9760603590946, 1615.806103267608, 1625.561098503404, 9496.905030949689, 11385.715357116076, 11614.070874330055, 11982.460789264504, 12029.21810082344, 11736.29582225065, 11812.468438920248, 11818.58634947777, 11818.645440636794, 11828.704282392944, 2067.6795096760025, 2125.5217576045343, 2455.5211733711935, 2496.2386472125995, 2498.7001559812848, 2580.6903171619433, 2588.987055064722, 2599.3111825366295, 2600.9739902601013, 2608.843469391833, 2477.541654793869, 2492.0654754467073, 2500.087468074158, 2503.198976327576, 2504.186139504659, 2330.2193765088714, 2339.6607491913664, 2350.9647355289685, 2410.529946660402, 2452.49723806621, 2446.4617784087473, 2459.7417271186628, 2466.728659847415, 2476.232367965936, 2485.142625794116, 11699.532018719241, 11915.046796256365, 11915.523379064827, 11918.404079795975, 11922.940385298105, 6023.307319658252, 6180.5982611130585, 6185.536084793614, 6185.938140618581, 6189.319174890758, 3628.11111277736, 3638.4542318652148, 3711.109333759878, 3753.474513568103, 3784.829682664287, 1355.9932200339033, 1374.2510331869055, 1432.1478720161592, 1464.9194294313809, 1480.674251664632, 17851.642967140586, 17878.731819022723, 17986.640267194714, 17998.0, 18034.27862885483, 4923.433805112386, 5039.0, 5083.6187285952965, 5131.948680513222, 5154.14956532168, 13339.599812005656, 13386.062975591614, 13403.061785674909, 13408.463661453432, 13421.664458388423, 6285.654289014148, 6290.433860952537, 6293.685315734195, 6293.811185664437, 6296.0555916612875, 4551.590356867898, 5098.133317336062, 5659.179418984307, 5747.425257474303, 5811.186433899257, 4132.0, 4219.324908014764, 4227.894302642459, 4260.8508702195895, 4272.487301523837, 1067.765091679829, 1074.2694967422228, 1079.093561408405, 1084.1651928015383, 1094.2176343914139, 985.6500942165428, 1077.2620754783, 1113.4154568851418, 1115.9442027898572, 1126.4649291586431, 7404.407647388196, 7510.84978300431, 7567.583782891884, 7599.4680372374505, 7717.112532058773, 1121.2095472691733, 1131.3890499130378, 1133.6259034518755, 1162.593092417647, 1171.3557543351124, 4637.582617564435, 4968.701877887338, 5065.113605119163, 5124.590032797368, 5150.536451719363, 14765.0, 15272.541823745305, 15317.0, 15352.846471535142, 15433.151176685391, 14628.268586570628, 15146.545603631688, 15177.0, 15221.847781522265, 15296.617584560252, 11043.282186657842, 11249.550017999352, 11260.943695281636, 11302.0, 11304.71738206535, 7244.782656520313, 7511.849763004819, 7536.171021187555, 7591.316781489692, 7600.125985511737, 13321.600351989457, 13410.865891340962, 13537.187768733907, 13811.51659691906, 13814.102083364549, 13754.862451375759, 13819.239941803296, 13897.027208095336, 13903.235322057384, 14040.227787471787, 9438.811223775348, 9708.902910970799, 9772.462514561767, 9799.412035277906, 9814.312998090065, 11082.944585277022, 11246.212765106691, 11288.43557822122, 11360.431978401211, 11427.428628568705, 8616.396852220285, 8721.694740684046, 8863.867041994246, 9088.0, 9104.726858194264, 9004.459732416077, 9014.504202268938, 9038.502882341534, 9135.908640913505, 9352.906470935204, 8263.793405164919, 8309.83380332402, 8387.161283871565, 8388.874166887379, 8393.412461127908, 6573.1783527059115, 6677.232118306262, 6736.4274036705465, 6747.493937954575, 6805.6937437816405, 6594.538382313384, 6757.391834734897, 6763.391294783296, 6783.402589100239, 6848.486363522656], "value": 5583.019144176506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.16762564437795, 446.4976900986392, 449.1869715814376, 450.2098816576907, 452.1070885002121, 5291.28567643368, 5304.628675992677, 5308.628396012281, 5309.097453432916, 5359.437259087788, 5176.66351687141, 5279.340082489689, 5283.867903302419, 5292.523672869441, 5318.654287471311, 4265.8080386382535, 4267.935980960288, 4270.97864510677, 4292.377605247238, 4335.284678028122, 4338.891527711817, 4350.36919646651, 4354.433923589945, 4357.782110894458, 4362.760048197348, 4254.212970600449, 4311.477785495489, 4345.565443455661, 4354.17270718564, 4378.985666593371, 4303.65570754342, 4306.913861722748, 4320.891977700567, 4323.805428755713, 4344.500382456011, 3911.4523966644692, 3935.4293627423863, 3947.46709194258, 3972.2254160438724, 3996.6403023728003, 5185.196294574347, 5223.589630799663, 5240.502152295526, 5250.0, 5259.973700131475, 4793.616510679138, 4889.535494128052, 4948.78754705096, 4957.02578452929, 4967.7019378837385, 5472.0, 5525.76206636313, 5550.47270509301, 5561.805336813192, 5562.527185189264, 4323.870283891489, 4328.718633288826, 4334.956650433487, 4338.175746608134, 4341.261985462479, 5447.836564903059, 5468.0, 5528.502434780889, 5646.632968857052, 5700.201971723962, 4675.742834144155, 4719.315699223594, 4734.739589322551, 4778.761061946889, 4797.784099715521, 4784.712917224978, 4796.832110876103, 4810.254410566367, 4866.975665121722, 4868.2210846264425, 5013.24801279811, 5067.695938243717, 5140.383154021467, 5141.151709967889, 5170.37955445349, 5528.944710552843, 5539.473749993744, 5541.229385307346, 5547.645098597582, 5559.833205003856, 1617.6036870966689, 1634.4442889417655, 1658.7642206555952, 1679.1520282257381, 1687.2829047654698, 1272.1858010872863, 1290.8317972235143, 1301.3383924469158, 1303.7066660001424, 1328.734253149374, 5423.830467812906, 5425.810096646677, 5455.181722741541, 5460.590455715837, 5484.643498172606, 5567.331920169606, 5642.351129620064, 5679.166562570249, 5708.372079071299, 5731.936502858967, 5038.899222015539, 5361.785528578813, 5372.865678358073, 5383.838484845461, 5406.135018397038, 2966.4363770883465, 2982.8806847726282, 2985.5820185174093, 2998.460277150134, 2998.4752668282963, 5531.3362396511875, 5834.474897259182, 5855.4730074292665, 5871.858555672695, 5879.676617785977, 5714.142878568248, 5769.336526299529, 5804.970975145097, 5833.7540047082175, 5847.853803654858, 5854.414558544111, 5890.970545147246, 5910.734016969161, 5914.822555323347, 5980.342162362137, 5545.500904918576, 5583.413741557078, 5680.517156041617, 5692.6299790513485, 5718.79984200551, 5464.644798088111, 5641.633293835887, 5657.8019769307875, 5687.971560142173, 5688.77244910199, 5815.41845815415, 5845.269341332333, 5921.407859214044, 5933.347331793413, 5955.034838503197, 4552.314134660566, 4638.814447422065, 4643.3963584733765, 4749.242780171358, 4765.69023013503, 5551.278333816578, 5587.55299576041, 5651.915221271602, 5703.258576385209, 5718.971405142947, 5521.337439507124, 5533.335999680065, 5540.69526176048, 5552.416996215339, 5585.0, 5360.0, 5495.862603434786, 5528.834134975958, 5587.497125258747, 5592.3848376677715, 5508.91736623959, 5532.834014979557, 5535.501804837423, 5536.2249285099315, 5583.609147359808, 5401.648892821954, 5568.081266591129, 5578.972105139448, 5589.888202236014, 5641.69960573053, 5487.176923461513, 5495.697736624365, 5497.725113744217, 5503.477169668875, 5510.641808282448], "value": 4441.276755589123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.7789607857835, 889.2574700125394, 921.8617207418887, 926.1386910173541, 947.1049857884299, 16014.83985160148, 17234.65530689387, 17265.13674316284, 17469.912650436763, 19317.903410482962, 14450.494232701858, 15654.060756354627, 15877.761833572506, 15878.52364429066, 16022.118783466905, 680.0411419897945, 688.1432616392573, 880.6345366672823, 883.2536506651901, 909.5315912305168, 895.3956079646213, 903.9548022598876, 904.159132007233, 907.791208022152, 909.1136142261308, 955.8231727130468, 958.3770549143043, 959.5633986536109, 960.4861399151455, 962.1821451766012, 947.4883562876039, 947.734634302393, 948.7059011706324, 951.9619215231382, 956.8564715292692, 695.7982185166296, 703.3318347569829, 703.3669697272445, 703.6341102626657, 703.6727921516468, 16639.916800416042, 16768.832311676848, 16869.24088416024, 17820.10899455022, 17927.83469074506, 17315.82684173155, 17928.193231304624, 18205.362812301504, 18283.542911427186, 18487.2605095796, 13401.329933503383, 13552.796708049387, 13601.979851511176, 13641.727165456734, 13822.585322440344, 4012.478377810866, 4054.7567145971334, 4061.776602286873, 4071.124708187724, 4136.648384887289, 87526.5623671884, 87953.0, 88161.55919220363, 89204.0, 91238.08761912429, 13360.271761198675, 13364.131331463424, 13463.259520726455, 13517.526886558922, 13676.93161534186, 26663.866680666473, 26687.866560667073, 26761.464770704475, 26777.86611066971, 27263.591046134323, 8848.1151884811, 8983.77540561478, 8984.281257499384, 9002.0, 9219.124183202584, 5194.27280180775, 5199.818006369759, 5257.947420525822, 5283.0, 5360.249565060894, 3570.8750193743094, 3685.834137463767, 3691.8707845225827, 3695.279420512974, 3743.756655817363, 608.6834845880118, 611.6634456376687, 618.6350053468475, 620.0853740732383, 627.1439485102802, 833.6373677450329, 844.6241422566899, 860.5310105992249, 874.8993865705398, 878.7934835313696, 10883.748368937515, 11641.301521908712, 11705.0, 11773.705657358463, 12206.633800985985, 745.8172747676854, 752.6387334079564, 768.1205020251781, 775.5230533222141, 775.6587101675245, 5136.178211486144, 5342.813001544927, 5364.409914909357, 5444.727763611803, 5447.073997420448, 62634.0, 64058.0, 78193.21806781973, 79199.0, 80572.59713701511, 73023.26976730165, 73228.63385683038, 75127.62436187784, 77070.22929770742, 77630.0, 52457.2131418029, 65807.67096164488, 66384.0, 66765.0, 66833.0, 11634.185607007428, 11776.175667703179, 11797.823032654345, 11938.52245910154, 12127.332996684914, 41677.58322416737, 42129.157416852104, 42268.788656056524, 42334.36498452464, 43260.0, 51463.0, 51668.0, 51757.482425175265, 51924.22113668222, 52083.479165207864, 12707.682307942376, 12733.172343797623, 12822.102452828212, 12847.0, 13653.931730341284, 16271.674566508367, 16541.0, 16774.66450671004, 17238.827611723722, 17490.0, 14330.856691432953, 14545.854541454873, 14591.416343346147, 15338.38646454174, 15397.307121179798, 14572.708545829237, 14588.416463341347, 15005.399784008518, 15240.923795380952, 15277.847221528087, 14072.226027568582, 14632.487862925173, 14758.262086895398, 15459.536213913601, 15494.302756376215, 10939.343639381663, 11362.602308919148, 11472.598459054227, 11662.358570278377, 11812.763744724885, 12708.682282943, 12863.678408039874, 12922.289274089639, 13124.409401577142, 13126.14680045794], "value": 7959.502952941857, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1450.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 112.0, 113.0, 113.0, 113.0, 112.0, 112.0, 111.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 116.0, 148.0, 115.0, 115.0, 114.0, 114.0, 138.0, 115.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 162.0, 160.0, 162.0, 160.0, 160.0, 162.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 113.0, 76.0, 75.0, 75.0, 75.0, 90.0, 74.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 103.0, 82.0, 82.0, 83.0, 101.0, 82.0, 82.0, 100.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 54.0, 54.0, 55.0, 56.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 52.0, 52.0, 51.0, 54.0, 52.0, 51.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 74.0, 78.0, 76.0, 79.0, 79.0, 79.0, 81.0, 80.0, 109.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 121.0, 136.0, 129.0, 119.0, 130.0, 118.0, 118.0, 120.0, 118.0], "value": 124.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 158.0, 157.0, 166.0, 157.0, 159.0, 166.0, 160.0, 156.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 63.0, 60.0, 60.0, 59.0, 60.0, 62.0, 58.0, 58.0], "value": 60.0, "unit": "score"}]}]} 14:45:30 INFO - Return code: 0 14:45:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:45:31 INFO - # TBPL SUCCESS # 14:45:31 INFO - Running post-action listener: _resource_record_post_action 14:45:31 INFO - [mozharness: 2017-06-01 21:45:31.014078Z] Finished run-tests step (success) 14:45:31 INFO - Running post-run listener: _resource_record_post_run 14:45:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331751424; Read time: 16; Write time: 199664 14:45:31 INFO - TinderboxPrint: CPU usage
13.1% 14:45:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 14:45:31 INFO - TinderboxPrint: I/O write bytes / time
331,751,424 / 199,664 14:45:31 INFO - TinderboxPrint: CPU idle
8,255.0 (86.9%) 14:45:31 INFO - TinderboxPrint: CPU user
1,196.0 (12.6%) 14:45:31 INFO - TinderboxPrint: Swap in / out
0 / 0 14:45:31 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 483328; Read time: 0; Write time: 44 14:45:31 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 290299904; Read time: 0; Write time: 189340 14:45:31 INFO - Running post-run listener: _upload_blobber_files 14:45:31 INFO - Blob upload gear active. 14:45:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:45:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:45:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:45:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:45:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:45:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:45:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:45:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:45:33 INFO - (blobuploader) - INFO - Done attempting. 14:45:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:45:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:45:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:45:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:45:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:45:34 INFO - (blobuploader) - INFO - Done attempting. 14:45:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:45:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:45:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:45:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:45:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:45:38 INFO - (blobuploader) - INFO - Done attempting. 14:45:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:45:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:45:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:45:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:45:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:45:39 INFO - (blobuploader) - INFO - Done attempting. 14:45:39 INFO - (blobuploader) - INFO - Iteration through files over. 14:45:39 INFO - Return code: 0 14:45:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:45:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:45:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8f533a493f3395d48849d8562fed1f3a34f6571c1b7384b3bb3115e66ce0a40753ff4087f4a11d0fed4ca1583ce2e3cf0896b506922d7b80d9ea5e87867e11f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/818aa48e4df42061413c481f01b691e35220875c3265e79767f0d5ba4ef6755bfae79cf4750c586a12df8b69eb2dc61d49487ad2a2ee9af4eb61b9c6d438648a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75c4fe55ee33beeda0d447584f36c4279627e67dc3e4197c2d10482443a6b7039844f7c5b6eea88f682d8e4c6a762754f917ac12ac87c717b97c1c999a63f7cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2a60849b943695d293e3701edf376dd53dbdfcef1ff34c9af123c71aee2400ba494372f7b7b56c6c09d1643d59c29f080c390481c363b949c68fad52fd63ef36"} 14:45:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:45:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:45:39 INFO - Contents: 14:45:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8f533a493f3395d48849d8562fed1f3a34f6571c1b7384b3bb3115e66ce0a40753ff4087f4a11d0fed4ca1583ce2e3cf0896b506922d7b80d9ea5e87867e11f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/818aa48e4df42061413c481f01b691e35220875c3265e79767f0d5ba4ef6755bfae79cf4750c586a12df8b69eb2dc61d49487ad2a2ee9af4eb61b9c6d438648a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75c4fe55ee33beeda0d447584f36c4279627e67dc3e4197c2d10482443a6b7039844f7c5b6eea88f682d8e4c6a762754f917ac12ac87c717b97c1c999a63f7cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2a60849b943695d293e3701edf376dd53dbdfcef1ff34c9af123c71aee2400ba494372f7b7b56c6c09d1643d59c29f080c390481c363b949c68fad52fd63ef36"} 14:45:39 INFO - Running post-run listener: copy_logs_to_upload_dir 14:45:39 INFO - Copying logs to upload dir... 14:45:39 INFO - mkdir: /builds/slave/test/build/upload/logs 14:45:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.846587 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-06-01 14:45:38.817546) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 14:45:38.826811) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8f533a493f3395d48849d8562fed1f3a34f6571c1b7384b3bb3115e66ce0a40753ff4087f4a11d0fed4ca1583ce2e3cf0896b506922d7b80d9ea5e87867e11f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/818aa48e4df42061413c481f01b691e35220875c3265e79767f0d5ba4ef6755bfae79cf4750c586a12df8b69eb2dc61d49487ad2a2ee9af4eb61b9c6d438648a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75c4fe55ee33beeda0d447584f36c4279627e67dc3e4197c2d10482443a6b7039844f7c5b6eea88f682d8e4c6a762754f917ac12ac87c717b97c1c999a63f7cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2a60849b943695d293e3701edf376dd53dbdfcef1ff34c9af123c71aee2400ba494372f7b7b56c6c09d1643d59c29f080c390481c363b949c68fad52fd63ef36"} build_url:https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/EIXi-LmPRU24R9h6LiuzMA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8f533a493f3395d48849d8562fed1f3a34f6571c1b7384b3bb3115e66ce0a40753ff4087f4a11d0fed4ca1583ce2e3cf0896b506922d7b80d9ea5e87867e11f5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/818aa48e4df42061413c481f01b691e35220875c3265e79767f0d5ba4ef6755bfae79cf4750c586a12df8b69eb2dc61d49487ad2a2ee9af4eb61b9c6d438648a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75c4fe55ee33beeda0d447584f36c4279627e67dc3e4197c2d10482443a6b7039844f7c5b6eea88f682d8e4c6a762754f917ac12ac87c717b97c1c999a63f7cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2a60849b943695d293e3701edf376dd53dbdfcef1ff34c9af123c71aee2400ba494372f7b7b56c6c09d1643d59c29f080c390481c363b949c68fad52fd63ef36"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 14:45:38.859225) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:45:38.859642) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1496352293.598577-517852501 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 14:45:38.942818) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 14:45:38.943120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 14:45:38.943412) ========= ========= Total master_lag: 0.10 =========