builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1486600992.05 results: success (0) revision: f4f18619855b03958d6475ea67ebe410ed1c078c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.053172) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.053714) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.054048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.071921) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.072341) ========= 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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-08 16:43:12-- 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% 46.1M=0s 2017-02-08 16:43:12 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095335 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.183110) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.183663) ========= 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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017182 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:12.234501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 16:43:12.234952) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f4f18619855b03958d6475ea67ebe410ed1c078c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev f4f18619855b03958d6475ea67ebe410ed1c078c --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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-08 16:43:12,426 truncating revision to first 12 chars 2017-02-08 16:43:12,426 Setting DEBUG logging. 2017-02-08 16:43:12,426 attempt 1/10 2017-02-08 16:43:12,426 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f4f18619855b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-08 16:43:13,281 unpacking tar archive at: mozilla-central-f4f18619855b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.166724 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-08 16:43:13.414914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:13.415243) ========= script_repo_revision: f4f18619855b03958d6475ea67ebe410ed1c078c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:13.415684) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:13.415972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-08 16:43:13.424944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-02-08 16:43:13.425324) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:43:13 INFO - MultiFileLogger online at 20170208 16:43:13 in /builds/slave/test 16:43:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:43:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:43:13 INFO - {'append_to_log': False, 16:43:13 INFO - 'base_work_dir': '/builds/slave/test', 16:43:13 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:43:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:43:13 INFO - 'branch': 'Firefox-Non-PGO', 16:43:13 INFO - 'buildbot_json_path': 'buildprops.json', 16:43:13 INFO - 'config_files': ('talos/linux_config.py',), 16:43:13 INFO - 'default_actions': ('clobber', 16:43:13 INFO - 'read-buildbot-config', 16:43:13 INFO - 'download-and-extract', 16:43:13 INFO - 'populate-webroot', 16:43:13 INFO - 'create-virtualenv', 16:43:13 INFO - 'install', 16:43:13 INFO - 'run-tests'), 16:43:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:43:13 INFO - 'download_minidump_stackwalk': True, 16:43:13 INFO - 'download_symbols': 'ondemand', 16:43:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:43:13 INFO - 'tooltool.py': '/tools/tooltool.py', 16:43:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:43:13 INFO - '/tools/misc-python/virtualenv.py')}, 16:43:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:43:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:43:13 INFO - 'installer_path': 'installer.exe', 16:43:13 INFO - 'log_level': 'info', 16:43:13 INFO - 'log_name': 'talos', 16:43:13 INFO - 'log_to_console': True, 16:43:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:43:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:43:13 INFO - 'opt_config_files': (), 16:43:13 INFO - 'pip_index': False, 16:43:13 INFO - 'sps_profile': False, 16:43:13 INFO - 'sps_profile_interval': 0, 16:43:13 INFO - 'suite': 'dromaeojs', 16:43:13 INFO - 'system_bits': '32', 16:43:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:43:13 INFO - 'title': 'talos-linux64-ix-036', 16:43:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:43:13 INFO - 'use_talos_json': True, 16:43:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:43:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:43:13 INFO - 'work_dir': 'build'} 16:43:13 INFO - [mozharness: 2017-02-09 00:43:13.720100Z] Running clobber step. 16:43:13 INFO - Running pre-action listener: _resource_record_pre_action 16:43:13 INFO - Running main action method: clobber 16:43:13 INFO - rmtree: /builds/slave/test/build 16:43:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:43:14 INFO - Running post-action listener: _resource_record_post_action 16:43:14 INFO - [mozharness: 2017-02-09 00:43:14.189947Z] Finished clobber step (success) 16:43:14 INFO - [mozharness: 2017-02-09 00:43:14.190049Z] Running read-buildbot-config step. 16:43:14 INFO - Running pre-action listener: _resource_record_pre_action 16:43:14 INFO - Running main action method: read_buildbot_config 16:43:14 INFO - Using buildbot properties: 16:43:14 INFO - { 16:43:14 INFO - "project": "", 16:43:14 INFO - "product": "firefox", 16:43:14 INFO - "who": "kwierso@gmail.com", 16:43:14 INFO - "installer_path": "public/build/target.tar.bz2", 16:43:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:43:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:43:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:43:14 INFO - "stage_platform": "linux64", 16:43:14 INFO - "basedir": "/builds/slave/test", 16:43:14 INFO - "buildnumber": 966, 16:43:14 INFO - "platform": "ubuntu64_hw", 16:43:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:43:14 INFO - "slavebuilddir": "test", 16:43:14 INFO - "taskId": "IG9QkNNZSqKKavZl320mJg", 16:43:14 INFO - "branch": "mozilla-central", 16:43:14 INFO - "script_repo_revision": "production", 16:43:14 INFO - "revision": "f4f18619855b03958d6475ea67ebe410ed1c078c", 16:43:14 INFO - "slavename": "talos-linux64-ix-036", 16:43:14 INFO - "repo_path": "mozilla-central" 16:43:14 INFO - } 16:43:14 INFO - Finding installer, test and symbols from parent task. 16:43:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IG9QkNNZSqKKavZl320mJg'}, attempt #1 16:43:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IG9QkNNZSqKKavZl320mJg'}, attempt #1 16:43:15 INFO - Task dependencies: fQLIp1cHQxypFBTEvDgjWw 16:43:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw'}, attempt #1 16:43:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 16:43:15 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/test_packages.json 16:43:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.crashreporter-symbols.zip 16:43:15 INFO - Running post-action listener: _resource_record_post_action 16:43:15 INFO - [mozharness: 2017-02-09 00:43:15.575778Z] Finished read-buildbot-config step (success) 16:43:15 INFO - [mozharness: 2017-02-09 00:43:15.575996Z] Running download-and-extract step. 16:43:15 INFO - Running pre-action listener: _resource_record_pre_action 16:43:15 INFO - Running main action method: download_and_extract 16:43:15 INFO - mkdir: /builds/slave/test/build/tests 16:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:43:15 INFO - trying https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.test_packages.json 16:43:15 INFO - Downloading https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:43:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:43:16 INFO - Downloaded 896 bytes. 16:43:16 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:43:16 INFO - Using the following test package requirements: 16:43:16 INFO - {u'common': [u'target.common.tests.zip'], 16:43:16 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:43:16 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:43:16 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:43:16 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:43:16 INFO - u'mozbase': [u'target.common.tests.zip'], 16:43:16 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:43:16 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:43:16 INFO - u'web-platform': [u'target.common.tests.zip', 16:43:16 INFO - u'target.web-platform.tests.zip'], 16:43:16 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:43:16 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:43:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip 16:43:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:43:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip into memory 16:43:17 INFO - Expected file size: 40399971 16:43:17 INFO - Obtained file size: 40399971 16:43:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:43:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip 16:43:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:43:21 INFO - Fetch https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.common.tests.zip into memory 16:43:22 INFO - Expected file size: 40399971 16:43:22 INFO - Obtained file size: 40399971 16:43:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.talos.tests.zip 16:43:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:43:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.talos.tests.zip into memory 16:43:43 INFO - retry: attempt #1 caught exception: HTTP Error 503: Service Unavailable 16:43:43 INFO - retry: Failed, sleeping 60 seconds before retrying 16:44:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.talos.tests.zip'}, attempt #2 16:44:43 INFO - Fetch https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.talos.tests.zip into memory 16:44:44 INFO - Expected file size: 14403454 16:44:44 INFO - Obtained file size: 14403454 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:44 INFO - trying https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 16:44:44 INFO - Downloading https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:44:45 INFO - Downloaded 56373828 bytes. 16:44:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 16:44:45 INFO - mkdir: /builds/slave/test/properties 16:44:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:45 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:45 INFO - Contents: 16:44:45 INFO - build_url:https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 16:44:45 INFO - Running post-action listener: _resource_record_post_action 16:44:45 INFO - Running post-action listener: set_extra_try_arguments 16:44:45 INFO - [mozharness: 2017-02-09 00:44:45.548834Z] Finished download-and-extract step (success) 16:44:45 INFO - [mozharness: 2017-02-09 00:44:45.548932Z] Running populate-webroot step. 16:44:45 INFO - Running pre-action listener: _resource_record_pre_action 16:44:45 INFO - Running main action method: populate_webroot 16:44:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:45 INFO - '--tppagecycles', 16:44:45 INFO - '7'], 16:44:45 INFO - 'tests': ['tsvgm']}}, 16:44:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:44:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:44:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['damp', 'tps']}, 16:44:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'tests': ['damp', 'tps']}, 16:44:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['dromaeo_dom']}, 16:44:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['basic_compositor_video']}, 16:44:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:44:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'other-e10s': {'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['a11yr', 16:44:45 INFO - 'ts_paint', 16:44:45 INFO - 'tpaint', 16:44:45 INFO - 'sessionrestore', 16:44:45 INFO - 'sessionrestore_no_auto_restore', 16:44:45 INFO - 'tabpaint']}, 16:44:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['tsvgx', 16:44:45 INFO - 'tsvgr_opacity', 16:44:45 INFO - 'tart', 16:44:45 INFO - 'tscrollx', 16:44:45 INFO - 'cart', 16:44:45 INFO - 'tsvg_static']}, 16:44:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:44:45 INFO - 'tsvgr_opacity', 16:44:45 INFO - 'tart', 16:44:45 INFO - 'tscrollx', 16:44:45 INFO - 'cart', 16:44:45 INFO - 'tsvg_static']}, 16:44:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'talos_options': ['--disable-e10s'], 16:44:45 INFO - 'tests': ['tp5o']}, 16:44:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:44:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'talos_options': ['--disable-e10s', 16:44:45 INFO - '--xperf_path', 16:44:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:45 INFO - 'tests': ['tp5n']}, 16:44:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:44:45 INFO - 'talos_options': ['--xperf_path', 16:44:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:45 INFO - 'tests': ['tp5n']}}, 16:44:45 INFO - 'talos.zip': {'path': '', 16:44:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:45 INFO - Running post-action listener: _resource_record_post_action 16:44:45 INFO - [mozharness: 2017-02-09 00:44:45.561964Z] Finished populate-webroot step (success) 16:44:45 INFO - [mozharness: 2017-02-09 00:44:45.562053Z] Running create-virtualenv step. 16:44:45 INFO - Running pre-action listener: _resource_record_pre_action 16:44:45 INFO - Running main action method: create_virtualenv 16:44:45 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:45 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 16:44:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:45 INFO - Using real prefix '/usr' 16:44:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:46 INFO - Installing distribute.............................................................................................................................................................................................done. 16:44:48 INFO - Installing pip.................done. 16:44:48 INFO - Return code: 0 16:44:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:44:48 INFO - Reading from file tmpfile_stdout 16:44:48 INFO - Output received: 16:44:48 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) 16:44:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:44:48 INFO - Using env: {'DISPLAY': ':0', 16:44:48 INFO - 'HOME': '/home/cltbld', 16:44:48 INFO - 'LANG': 'en_US.UTF-8', 16:44:48 INFO - 'LANGUAGE': 'en_US:en', 16:44:48 INFO - 'LOGNAME': 'cltbld', 16:44:48 INFO - 'MAIL': '/var/mail/cltbld', 16:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:48 INFO - 'MOZ_NO_REMOTE': '1', 16:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:48 INFO - 'NO_EM_RESTART': '1', 16:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:48 INFO - 'PWD': '/builds/slave/test', 16:44:48 INFO - 'SHELL': '/bin/bash', 16:44:48 INFO - 'SHLVL': '1', 16:44:48 INFO - 'TERM': 'linux', 16:44:48 INFO - 'TMOUT': '86400', 16:44:48 INFO - 'USER': 'cltbld', 16:44:48 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:48 INFO - '_': '/tools/buildbot/bin/python'} 16:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:48 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 16:44:48 INFO - Cleaning up... 16:44:48 INFO - Return code: 0 16:44:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:44:48 INFO - Using env: {'DISPLAY': ':0', 16:44:48 INFO - 'HOME': '/home/cltbld', 16:44:48 INFO - 'LANG': 'en_US.UTF-8', 16:44:48 INFO - 'LANGUAGE': 'en_US:en', 16:44:48 INFO - 'LOGNAME': 'cltbld', 16:44:48 INFO - 'MAIL': '/var/mail/cltbld', 16:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:48 INFO - 'MOZ_NO_REMOTE': '1', 16:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:48 INFO - 'NO_EM_RESTART': '1', 16:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:48 INFO - 'PWD': '/builds/slave/test', 16:44:48 INFO - 'SHELL': '/bin/bash', 16:44:48 INFO - 'SHLVL': '1', 16:44:48 INFO - 'TERM': 'linux', 16:44:48 INFO - 'TMOUT': '86400', 16:44:48 INFO - 'USER': 'cltbld', 16:44:48 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:48 INFO - '_': '/tools/buildbot/bin/python'} 16:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:48 INFO - Downloading/unpacking psutil>=3.1.1 16:44:48 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 16:44:48 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 16:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:48 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 16:44:48 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 16:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:44:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:50 INFO - Installing collected packages: psutil 16:44:50 INFO - Running setup.py install for psutil 16:44:50 INFO - building 'psutil._psutil_linux' extension 16:44:50 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 16:44:51 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 16:44:51 INFO - building 'psutil._psutil_posix' extension 16:44:51 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 16:44:51 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 16:44:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:44:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:44:51 INFO - Successfully installed psutil 16:44:51 INFO - Cleaning up... 16:44:51 INFO - Return code: 0 16:44:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:51 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:44:51 INFO - Using env: {'DISPLAY': ':0', 16:44:51 INFO - 'HOME': '/home/cltbld', 16:44:51 INFO - 'LANG': 'en_US.UTF-8', 16:44:51 INFO - 'LANGUAGE': 'en_US:en', 16:44:51 INFO - 'LOGNAME': 'cltbld', 16:44:51 INFO - 'MAIL': '/var/mail/cltbld', 16:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:51 INFO - 'MOZ_NO_REMOTE': '1', 16:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:51 INFO - 'NO_EM_RESTART': '1', 16:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:51 INFO - 'PWD': '/builds/slave/test', 16:44:51 INFO - 'SHELL': '/bin/bash', 16:44:51 INFO - 'SHLVL': '1', 16:44:51 INFO - 'TERM': 'linux', 16:44:51 INFO - 'TMOUT': '86400', 16:44:51 INFO - 'USER': 'cltbld', 16:44:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:51 INFO - '_': '/tools/buildbot/bin/python'} 16:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:44:51 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 16:44:51 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 16:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:51 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 16:44:51 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 16:44:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:44:53 INFO - Installing collected packages: mozsystemmonitor 16:44:53 INFO - Running setup.py install for mozsystemmonitor 16:44:53 INFO - Successfully installed mozsystemmonitor 16:44:53 INFO - Cleaning up... 16:44:53 INFO - Return code: 0 16:44:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:44:53 INFO - Using env: {'DISPLAY': ':0', 16:44:53 INFO - 'HOME': '/home/cltbld', 16:44:53 INFO - 'LANG': 'en_US.UTF-8', 16:44:53 INFO - 'LANGUAGE': 'en_US:en', 16:44:53 INFO - 'LOGNAME': 'cltbld', 16:44:53 INFO - 'MAIL': '/var/mail/cltbld', 16:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:53 INFO - 'MOZ_NO_REMOTE': '1', 16:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:53 INFO - 'NO_EM_RESTART': '1', 16:44:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:53 INFO - 'PWD': '/builds/slave/test', 16:44:53 INFO - 'SHELL': '/bin/bash', 16:44:53 INFO - 'SHLVL': '1', 16:44:53 INFO - 'TERM': 'linux', 16:44:53 INFO - 'TMOUT': '86400', 16:44:53 INFO - 'USER': 'cltbld', 16:44:53 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:53 INFO - '_': '/tools/buildbot/bin/python'} 16:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:54 INFO - Downloading/unpacking jsonschema==2.5.1 16:44:54 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 16:44:54 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 16:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:54 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 16:44:54 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 16:44:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:44:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:44:56 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 16:44:56 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 16:44:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:56 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 16:44:56 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 16:44:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:44:56 INFO - warning: no files found matching '*.txt' 16:44:56 INFO - no previously-included directories found matching 'build' 16:44:56 INFO - no previously-included directories found matching 'dist' 16:44:56 INFO - no previously-included directories found matching '.git*' 16:44:56 INFO - Installing collected packages: jsonschema, functools32 16:44:56 INFO - Running setup.py install for functools32 16:44:56 INFO - warning: no files found matching '*.txt' 16:44:56 INFO - no previously-included directories found matching 'build' 16:44:56 INFO - no previously-included directories found matching 'dist' 16:44:56 INFO - no previously-included directories found matching '.git*' 16:44:56 INFO - Successfully installed jsonschema functools32 16:44:56 INFO - Cleaning up... 16:44:56 INFO - Return code: 0 16:44:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:44:56 INFO - Using env: {'DISPLAY': ':0', 16:44:56 INFO - 'HOME': '/home/cltbld', 16:44:56 INFO - 'LANG': 'en_US.UTF-8', 16:44:56 INFO - 'LANGUAGE': 'en_US:en', 16:44:56 INFO - 'LOGNAME': 'cltbld', 16:44:56 INFO - 'MAIL': '/var/mail/cltbld', 16:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:56 INFO - 'MOZ_NO_REMOTE': '1', 16:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:56 INFO - 'NO_EM_RESTART': '1', 16:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:56 INFO - 'PWD': '/builds/slave/test', 16:44:56 INFO - 'SHELL': '/bin/bash', 16:44:56 INFO - 'SHLVL': '1', 16:44:56 INFO - 'TERM': 'linux', 16:44:56 INFO - 'TMOUT': '86400', 16:44:56 INFO - 'USER': 'cltbld', 16:44:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:56 INFO - '_': '/tools/buildbot/bin/python'} 16:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:44:56 INFO - Cleaning up... 16:44:56 INFO - Return code: 0 16:44:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:44:56 INFO - Using env: {'DISPLAY': ':0', 16:44:56 INFO - 'HOME': '/home/cltbld', 16:44:56 INFO - 'LANG': 'en_US.UTF-8', 16:44:56 INFO - 'LANGUAGE': 'en_US:en', 16:44:56 INFO - 'LOGNAME': 'cltbld', 16:44:56 INFO - 'MAIL': '/var/mail/cltbld', 16:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:56 INFO - 'MOZ_NO_REMOTE': '1', 16:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:44:56 INFO - 'NO_EM_RESTART': '1', 16:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:56 INFO - 'PWD': '/builds/slave/test', 16:44:56 INFO - 'SHELL': '/bin/bash', 16:44:56 INFO - 'SHLVL': '1', 16:44:56 INFO - 'TERM': 'linux', 16:44:56 INFO - 'TMOUT': '86400', 16:44:56 INFO - 'USER': 'cltbld', 16:44:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:56 INFO - '_': '/tools/buildbot/bin/python'} 16:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:57 INFO - Downloading/unpacking blobuploader==1.2.4 16:44:57 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 16:44:57 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 16:44:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:57 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 16:44:57 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 16:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 16:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:44:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:44:59 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 16:44:59 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 16:44:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:59 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 16:44:59 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 16:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:44:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:44:59 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 16:44:59 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 16:44:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:44:59 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 16:44:59 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 16:44:59 INFO - Downloading docopt-0.6.1.tar.gz 16:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:44:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:44:59 INFO - Running setup.py install for blobuploader 16:44:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:44:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:44:59 INFO - Running setup.py install for requests 16:45:00 INFO - Running setup.py install for docopt 16:45:00 INFO - Successfully installed blobuploader requests docopt 16:45:00 INFO - Cleaning up... 16:45:00 INFO - Return code: 0 16:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:45:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:45:00 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:00 INFO - Using env: {'DISPLAY': ':0', 16:45:00 INFO - 'HOME': '/home/cltbld', 16:45:00 INFO - 'LANG': 'en_US.UTF-8', 16:45:00 INFO - 'LANGUAGE': 'en_US:en', 16:45:00 INFO - 'LOGNAME': 'cltbld', 16:45:00 INFO - 'MAIL': '/var/mail/cltbld', 16:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:00 INFO - 'MOZ_NO_REMOTE': '1', 16:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:00 INFO - 'NO_EM_RESTART': '1', 16:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:00 INFO - 'PWD': '/builds/slave/test', 16:45:00 INFO - 'SHELL': '/bin/bash', 16:45:00 INFO - 'SHLVL': '1', 16:45:00 INFO - 'TERM': 'linux', 16:45:00 INFO - 'TMOUT': '86400', 16:45:00 INFO - 'USER': 'cltbld', 16:45:00 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:00 INFO - '_': '/tools/buildbot/bin/python'} 16:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:00 INFO - Running setup.py (path:/tmp/pip-WDa2H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:00 INFO - Running setup.py (path:/tmp/pip-x4K9pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:00 INFO - Running setup.py (path:/tmp/pip-eJHlpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:00 INFO - Running setup.py (path:/tmp/pip-bro5Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:01 INFO - Running setup.py (path:/tmp/pip-0knbHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:01 INFO - Running setup.py (path:/tmp/pip-r5fxtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:01 INFO - Running setup.py (path:/tmp/pip-r5Rsjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:01 INFO - Running setup.py (path:/tmp/pip-MHcklY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:01 INFO - Running setup.py (path:/tmp/pip-WqiGMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:01 INFO - Running setup.py (path:/tmp/pip-GrAM5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:01 INFO - Running setup.py (path:/tmp/pip-6cqilK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:01 INFO - Running setup.py (path:/tmp/pip-NAr6wO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:01 INFO - Running setup.py (path:/tmp/pip-VAnVwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:01 INFO - Running setup.py (path:/tmp/pip-FfVGFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:01 INFO - Running setup.py (path:/tmp/pip-kWTAr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:01 INFO - Running setup.py (path:/tmp/pip-8ig50m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:01 INFO - Running setup.py (path:/tmp/pip-Ic3J6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:01 INFO - Running setup.py install for manifestparser 16:45:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for mozcrash 16:45:02 INFO - Running setup.py install for mozdebug 16:45:02 INFO - Running setup.py install for mozdevice 16:45:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for mozfile 16:45:02 INFO - Running setup.py install for mozhttpd 16:45:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for mozinfo 16:45:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for mozInstall 16:45:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for mozleak 16:45:02 INFO - Running setup.py install for mozlog 16:45:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:02 INFO - Running setup.py install for moznetwork 16:45:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Running setup.py install for mozprocess 16:45:03 INFO - Running setup.py install for mozprofile 16:45:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Running setup.py install for mozrunner 16:45:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Running setup.py install for mozscreenshot 16:45:03 INFO - Running setup.py install for moztest 16:45:03 INFO - Running setup.py install for mozversion 16:45:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:03 INFO - Cleaning up... 16:45:03 INFO - Return code: 0 16:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:03 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:45:03 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:45:03 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:03 INFO - Using env: {'DISPLAY': ':0', 16:45:03 INFO - 'HOME': '/home/cltbld', 16:45:03 INFO - 'LANG': 'en_US.UTF-8', 16:45:03 INFO - 'LANGUAGE': 'en_US:en', 16:45:03 INFO - 'LOGNAME': 'cltbld', 16:45:03 INFO - 'MAIL': '/var/mail/cltbld', 16:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:03 INFO - 'MOZ_NO_REMOTE': '1', 16:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:03 INFO - 'NO_EM_RESTART': '1', 16:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:03 INFO - 'PWD': '/builds/slave/test', 16:45:03 INFO - 'SHELL': '/bin/bash', 16:45:03 INFO - 'SHLVL': '1', 16:45:03 INFO - 'TERM': 'linux', 16:45:03 INFO - 'TMOUT': '86400', 16:45:03 INFO - 'USER': 'cltbld', 16:45:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:03 INFO - '_': '/tools/buildbot/bin/python'} 16:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:03 INFO - Running setup.py (path:/tmp/pip-ZK_Ua6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:04 INFO - Running setup.py (path:/tmp/pip-Q9NTFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:04 INFO - Running setup.py (path:/tmp/pip-HJ7JcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:04 INFO - Running setup.py (path:/tmp/pip-9KXqJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:04 INFO - Running setup.py (path:/tmp/pip-_NyhlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:04 INFO - Running setup.py (path:/tmp/pip-YWMtYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:04 INFO - Running setup.py (path:/tmp/pip-e_juSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:04 INFO - Running setup.py (path:/tmp/pip-aSPgVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:04 INFO - Running setup.py (path:/tmp/pip-gsfyaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:04 INFO - Running setup.py (path:/tmp/pip-R2dyyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:04 INFO - Running setup.py (path:/tmp/pip-lnUtmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:04 INFO - Running setup.py (path:/tmp/pip-urFmlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:04 INFO - Running setup.py (path:/tmp/pip-BBniT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:04 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)) 16:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:04 INFO - Running setup.py (path:/tmp/pip-K3hjJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:05 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)) 16:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:05 INFO - Running setup.py (path:/tmp/pip-jnSglY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:05 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)) 16:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:05 INFO - Running setup.py (path:/tmp/pip-1zMi_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:05 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)) 16:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:05 INFO - Running setup.py (path:/tmp/pip-JfYre0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:05 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)) 16:45:05 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)) 16:45:05 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)) 16:45:05 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)) 16:45:05 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)) 16:45:05 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)) 16:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:05 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 16:45:05 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 16:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:05 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 16:45:05 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 16:45:07 INFO - Downloading blessings-1.6.tar.gz 16:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:07 INFO - Installing collected packages: blessings 16:45:07 INFO - Running setup.py install for blessings 16:45:07 INFO - Successfully installed blessings 16:45:07 INFO - Cleaning up... 16:45:07 INFO - Return code: 0 16:45:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:07 INFO - Reading from file tmpfile_stdout 16:45:07 INFO - Current package versions: 16:45:07 INFO - argparse == 1.2.1 16:45:07 INFO - blessings == 1.6 16:45:07 INFO - blobuploader == 1.2.4 16:45:07 INFO - docopt == 0.6.1 16:45:07 INFO - functools32 == 3.2.3-2 16:45:07 INFO - jsonschema == 2.5.1 16:45:07 INFO - manifestparser == 1.1 16:45:07 INFO - mozInstall == 1.12 16:45:07 INFO - mozcrash == 1.0 16:45:07 INFO - mozdebug == 0.1 16:45:07 INFO - mozdevice == 0.49 16:45:07 INFO - mozfile == 1.2 16:45:07 INFO - mozhttpd == 0.7 16:45:07 INFO - mozinfo == 0.9 16:45:07 INFO - mozleak == 0.1 16:45:07 INFO - mozlog == 3.4 16:45:07 INFO - moznetwork == 0.27 16:45:07 INFO - mozprocess == 0.24 16:45:07 INFO - mozprofile == 0.28 16:45:07 INFO - mozrunner == 6.13 16:45:07 INFO - mozscreenshot == 0.1 16:45:07 INFO - mozsystemmonitor == 0.3 16:45:07 INFO - moztest == 0.8 16:45:07 INFO - mozversion == 1.4 16:45:07 INFO - psutil == 3.1.1 16:45:07 INFO - requests == 1.2.3 16:45:07 INFO - wsgiref == 0.1.2 16:45:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:45:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:45:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:45:07 INFO - Using env: {'DISPLAY': ':0', 16:45:07 INFO - 'HOME': '/home/cltbld', 16:45:07 INFO - 'LANG': 'en_US.UTF-8', 16:45:07 INFO - 'LANGUAGE': 'en_US:en', 16:45:07 INFO - 'LOGNAME': 'cltbld', 16:45:07 INFO - 'MAIL': '/var/mail/cltbld', 16:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:07 INFO - 'MOZ_NO_REMOTE': '1', 16:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:07 INFO - 'NO_EM_RESTART': '1', 16:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:07 INFO - 'PWD': '/builds/slave/test', 16:45:07 INFO - 'SHELL': '/bin/bash', 16:45:07 INFO - 'SHLVL': '1', 16:45:07 INFO - 'TERM': 'linux', 16:45:07 INFO - 'TMOUT': '86400', 16:45:07 INFO - 'USER': 'cltbld', 16:45:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:07 INFO - '_': '/tools/buildbot/bin/python'} 16:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 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)) 16:45:07 INFO - Cleaning up... 16:45:07 INFO - Return code: 0 16:45:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb78a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdda630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, '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': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', '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 16:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:45:07 INFO - Using env: {'DISPLAY': ':0', 16:45:07 INFO - 'HOME': '/home/cltbld', 16:45:07 INFO - 'LANG': 'en_US.UTF-8', 16:45:07 INFO - 'LANGUAGE': 'en_US:en', 16:45:07 INFO - 'LOGNAME': 'cltbld', 16:45:07 INFO - 'MAIL': '/var/mail/cltbld', 16:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:07 INFO - 'MOZ_NO_REMOTE': '1', 16:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:07 INFO - 'NO_EM_RESTART': '1', 16:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:07 INFO - 'PWD': '/builds/slave/test', 16:45:07 INFO - 'SHELL': '/bin/bash', 16:45:07 INFO - 'SHLVL': '1', 16:45:07 INFO - 'TERM': 'linux', 16:45:07 INFO - 'TMOUT': '86400', 16:45:07 INFO - 'USER': 'cltbld', 16:45:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:07 INFO - '_': '/tools/buildbot/bin/python'} 16:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:08 INFO - Cleaning up... 16:45:08 INFO - Return code: 0 16:45:08 INFO - Running post-action listener: _resource_record_post_action 16:45:08 INFO - Running post-action listener: _start_resource_monitoring 16:45:08 INFO - Starting resource monitoring. 16:45:08 INFO - [mozharness: 2017-02-09 00:45:08.210523Z] Finished create-virtualenv step (success) 16:45:08 INFO - [mozharness: 2017-02-09 00:45:08.211026Z] Running install step. 16:45:08 INFO - Running pre-action listener: _resource_record_pre_action 16:45:08 INFO - Running main action method: install 16:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:08 INFO - Reading from file tmpfile_stdout 16:45:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:08 INFO - Reading from file tmpfile_stdout 16:45:08 INFO - Output received: 16:45:08 INFO - Usage: mozinstall [options] installer 16:45:08 INFO - Options: 16:45:08 INFO - -h, --help show this help message and exit 16:45:08 INFO - -d DEST, --destination=DEST 16:45:08 INFO - Directory to install application into. [default: 16:45:08 INFO - "/builds/slave/test"] 16:45:08 INFO - --app=APP Application being installed. [default: firefox] 16:45:08 INFO - mkdir: /builds/slave/test/build/application 16:45:08 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'] 16:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:45:24 INFO - Reading from file tmpfile_stdout 16:45:24 INFO - Output received: 16:45:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:45:24 INFO - Running post-action listener: _resource_record_post_action 16:45:24 INFO - [mozharness: 2017-02-09 00:45:24.717025Z] Finished install step (success) 16:45:24 INFO - [mozharness: 2017-02-09 00:45:24.717301Z] Running run-tests step. 16:45:24 INFO - Running pre-action listener: _resource_record_pre_action 16:45:24 INFO - Running main action method: run_tests 16:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:24 INFO - Python 2.7.3 16:45:24 INFO - Return code: 0 16:45:24 INFO - grabbing minidump binary from tooltool 16:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 16:45:24 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 0xe63850>, '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 0xdd86d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd8b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:24 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 16:45:24 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 16:45:24 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 16:45:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:24 INFO - Return code: 0 16:45:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:45:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:45:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 16:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 16:45:24 INFO - Using env: {'DISPLAY': ':0', 16:45:24 INFO - 'HOME': '/home/cltbld', 16:45:24 INFO - 'LANG': 'en_US.UTF-8', 16:45:24 INFO - 'LANGUAGE': 'en_US:en', 16:45:24 INFO - 'LOGNAME': 'cltbld', 16:45:24 INFO - 'MAIL': '/var/mail/cltbld', 16:45:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:24 INFO - 'MOZ_NO_REMOTE': '1', 16:45:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:24 INFO - 'NO_EM_RESTART': '1', 16:45:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:24 INFO - 'PWD': '/builds/slave/test', 16:45:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:24 INFO - 'SHELL': '/bin/bash', 16:45:24 INFO - 'SHLVL': '1', 16:45:24 INFO - 'TERM': 'linux', 16:45:24 INFO - 'TMOUT': '86400', 16:45:24 INFO - 'USER': 'cltbld', 16:45:24 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935', 16:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:24 INFO - '_': '/tools/buildbot/bin/python'} 16:45:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 16:45:24 INFO - mozversion application_buildid: 20170209000931 16:45:24 INFO - mozversion application_changeset: f4f18619855b03958d6475ea67ebe410ed1c078c 16:45:24 INFO - mozversion application_display_name: Nightly 16:45:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:24 INFO - mozversion application_name: Firefox 16:45:24 INFO - mozversion application_remotingname: firefox 16:45:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:45:24 INFO - mozversion application_vendor: Mozilla 16:45:24 INFO - mozversion application_version: 54.0a1 16:45:24 INFO - mozversion platform_buildid: 20170209000931 16:45:24 INFO - mozversion platform_changeset: f4f18619855b03958d6475ea67ebe410ed1c078c 16:45:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:45:24 INFO - mozversion platform_version: 54.0a1 16:45:24 INFO - using testdate: 1486601124 16:45:24 INFO - actual date: 1486601124 16:45:24 INFO - starting webserver on 'localhost:36326' 16:45:24 INFO - SUITE-START | Running 2 tests 16:45:24 INFO - TEST-START | dromaeo_css 16:45:24 INFO - operating with platform_type : linux_ 16:45:24 INFO - Initialising browser for dromaeo_css test... 16:45:25 INFO - TEST-INFO | started process 22998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoIZMn/profile http://localhost:36326/getInfo.html) 16:45:28 INFO - PROCESS | 22998 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 16:45:29 INFO - PROCESS | 22998 | __metrics Screen width/height:1600/1200 16:45:29 INFO - PROCESS | 22998 | colorDepth:24 16:45:29 INFO - PROCESS | 22998 | Browser inner width/height: 1024/697 16:45:29 INFO - PROCESS | 22998 | __metrics 16:45:30 INFO - PROCESS | 22998 | 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" 16:45:30 INFO - PROCESS | 22998 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:45:31 INFO - TEST-INFO | 22998: exit 0 16:45:31 INFO - Browser initialized. 16:45:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:31 INFO - TEST-INFO | started process 23081 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvoIZMn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:43 INFO - PROCESS | 23081 | RSS: Main: 153059328 16:45:43 INFO - PROCESS | 23081 | 16:48:49 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36326/tests/dromaeo/cssquery-ext.html) 16:48:51 INFO - PROCESS | 23081 | RSS: Main: 281829376 16:48:51 INFO - PROCESS | 23081 | 16:50:33 INFO - PROCESS | 23081 | ************************* 16:50:33 INFO - PROCESS | 23081 | A coding exception was thrown and uncaught in a Task. 16:50:33 INFO - PROCESS | 23081 | 16:50:33 ERROR - PROCESS | 23081 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:50:33 INFO - PROCESS | 23081 | Full stack: 16:50:33 INFO - PROCESS | 23081 | ************************* 16:51:53 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-ext.html (next: http://localhost:36326/tests/dromaeo/cssquery-jquery.html) 16:51:54 INFO - PROCESS | 23081 | RSS: Main: 233156608 16:51:54 INFO - PROCESS | 23081 | 16:54:56 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36326/tests/dromaeo/cssquery-mootools.html) 16:54:57 INFO - PROCESS | 23081 | RSS: Main: 187019264 16:54:57 INFO - PROCESS | 23081 | 16:57:59 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36326/tests/dromaeo/cssquery-prototype.html) 16:58:00 INFO - PROCESS | 23081 | RSS: Main: 191766528 16:58:00 INFO - PROCESS | 23081 | 17:01:03 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36326/tests/dromaeo/cssquery-yui.html) 17:01:04 INFO - PROCESS | 23081 | RSS: Main: 193142784 17:01:04 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | Cycle 1(1): loaded http://localhost:36326/tests/dromaeo/cssquery-yui.html (next: http://localhost:36326/tests/dromaeo/cssquery-dojo.html) 17:04:07 INFO - PROCESS | 23081 | RSS: Main: 280907776 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | __start_tp_report 17:04:07 INFO - PROCESS | 23081 | _x_x_mozilla_page_load 17:04:07 INFO - PROCESS | 23081 | _x_x_mozilla_page_load_details 17:04:07 INFO - PROCESS | 23081 | |i|pagename|runs| 17:04:07 INFO - PROCESS | 23081 | |0;dojo.html;2567.6533667954823;2580.367809886578;2611;2624.566946453835;2630.421307312392;4601.608863246628;4653.302004699289;4743.454502732183;4783.425988881335;4810.663253572251;4647.604953578942;4651.674382793206;4652.32541281514;4715.1512727709;4731.834385796497;4231.682623803219;4308.159908817766;4309.245881970643;4351.042770590465;4377.956220437787;4367.650587952956;4381.780910954455;4409.426774519308;4410.889727756799;4444.822207111712;4199.2441360555085;4240.830366785325;4244.554321796229;4278.63631591312;4282.314829627245;4273.18809426208;4280.272353699878;4294.098239369736;4325.4593175853015;4340.066885619607;4303.569643035703;4318.892027699333;4328.285832837578;4350.543192964757;4379.1679580879845;4686.789094490756;4709.882252943669;4734.739589322586;4749.26386410107;4788.664793464459;4903.4606193318705;4948.851534453972;5045.293658887758;5054.570361519275;5058.645894787366;4994.475580064077;5045.722485263309;5053;5062.822801201977;5138.563222126124;4734.242521196661;4738.763061846894;4747.311639812208;4753.667243293005;4789.568938795525;4979.45226025137;5161.5870730341485;5165.948340516622;5173.379194496609;5182;4769.403824521934;4865.8053677853195;4889.877753056202;4913.950860491421;4932.383452068491;4990.445099132091;5030.8239211627415;5039.143345631252;5048.242763585492;5081.593472522263;5029.723365214948;5030.346055012825;5031.069252188357;5032.672876263031;5065.11360511909;4824.879378015507;4872.269159626014;5016.44819069902;5038.974805125951;5062.164742817467;4327.350897365357;4330.372096046119;4358.694891357638;4371.191329604034;4374.387585737999;3750.1187221003042;3757.680597149218;3772.2078363543415;3802.1065049713297;3804.752691075071;4244.02387450888;4271.572842715766;4322.956770432255;4428.955710442919;4430.645548356124;5053.343065401547;5082.339295891585;5100.770465329069;5126.974365128151;5142.6914385137015;4362.192994296065;4363.672724545671;4368.170047690928;4371.256886329332;4415.183191109655;4442;4476.865694029184;4571.725696458223;4577.793999270041;4607.792649330788;4749.382580264543;4816.34979277793;4816.362436771515;4841;4870.439899411613;5139.537441630271;5168.689878607295;5169.663971841818;5180.145276029413;5234.712090835041;4831.178699621143;4833.73414462208;4835.347228124158;4836.105320515716;4876.731779752005;4831.685940414002;4865.1242776300605;5001.274815151855;5007.524285192901;5039.395272567315;5152.227165925141;5156.7421628919155;5159.045576568347;5162.97418512905;5198.818041368534;4761.809527618995;4796.640251981185;4800.135975524299;4863.562279394871;5007.39911210657;4847.030593881308;4854.63590230727;4854.68444551103;4870.951290487192;4912.926306105483;4697.107549565571;4858.92711609319;4876.122297986397;4880.99939512392;4884.8046078157895;4829.5653391194965;4832.516748325139;4855.344528488608;4887.144749668722;4939.036887806843;4872.049950259665;4875.098106850244;4875.146849301429;4889.315495830658;4917.188663870421 17:04:07 INFO - PROCESS | 23081 | |1;ext.html;538.8329617818476;540.1061243641774;552.2213678713014;553.112254830996;560.5375565158745;27389.726102738965;38155;38815.611843881554;38847.86336751474;42221.366679499755;38928;39452.60547394525;39474.605253947455;39492.40761388574;39587.406188907116;7452.366548843354;7605.049368828896;7608.809779755523;7624.313811756941;7631.313181813635;6986.720531178747;7132.85734285314;9337.953310233474;9501.619935202583;9525.618975240983;9568.282378821581;9572.712818615453;9634.807303853919;9636.662716804913;9641.855372169422;9422.434653920787;9495.572699228516;9505.144536991704;9513.809723805485;9572.28207884408;9275.58259878307;9349.953250233773;9353.204977576846;9354.298427617956;9359.578818953163;35390.46914296287;36087.819560902026;36133.45799813005;36141.27717445663;36224.45663315053;3343.5151902974176;3345.1637090727318;3449.760694505637;3501.19472521319;3528.770629909048;23460.7653923462;24709;24900.626490602473;25069.87465062681;26101.347466313306;3085.1207405889213;3091.520814273791;3144.937101257985;3163.209197700575;3171.5401266816184;229759;230912;231624;231684;232373.9999999983;40394.79802601027;46313;46575;46697.766511167225;46715.06569868584;43514.782426087666;43676.344854827206;43678.563214368085;43707.56292437035;49488.752556237705;11596.536138554364;12171.513139474499;12285.69285767863;12288.078394120477;12296.938515307545;9303.162715355651;9342.953285233667;9390.718278451657;9394.201492873046;9426.858597121049;16006.919965400099;16073.678526429407;16184.520328129509;16185.681935203254;16186.676266474604;6155.815325540241;6222.377762223741;6335.271443783931;6350.841228969312;6402.967985160138;740.1155619035309;756.2437562437563;756.8486302739474;761.5506850957851;768.2739810878778;26477.47045059088;26527;27049.323766905592;27574.862125689244;27720.861395692893;880.6521424037469;886.1360173830464;893.4728510178895;913.2511340700695;917.6191880369572;13954.232517211447;14262.857371426153;14439.133651980916;14599.416023358946;14703.632409189642;111345.44327278312;111780.44109779561;112284;112717;112999;49896.750516247186;50204.49795501998;50375.74812125916;50498.49501505012;50510.49489505131;1131.1911982932406;1163.5694792926672;1165.1901928160016;1173.3018853781987;1174.5712814822527;24627;25460.363490912874;26633.733662663126;26689.599656004786;26714.46571068529;39449;41343.58656413398;41404;41416;41416.79291603523;265398;265487;265877;266130;266945;24464.877675611508;24641.63037554474;25065.248042558753;25410.618840717034;25443.236702898943;35369.29261414706;35503.11242218965;35580.466293006146;35628;35895.461568075974;30414.84792576023;30704;30872.228194295323;31123.68876311208;31766;30365.544516831822;30439.847800760854;30517.08448746541;30809.537856931714;31517;29981.400371992;30560.847195764767;30647.77408903619;30844.691553084183;30975.84512077425;26714.332141696614;27881;28085.297867553476;28509.287267818472;28558.286042849097;21912.890435548357;22136.225232116798;22213;22237.22169724052;22243.443913902283 17:04:07 INFO - PROCESS | 23081 | |2;jquery.html;343.1763766959297;348.13141212674384;372.5175897213109;386.50334320398287;397.48128692056855;12134.332611706353;16530;16707.331706731748;17515.2118154683;18548.72176917344;18336.724949125775;18401.907990460062;18416.81583184168;18434.631307373846;18473.35343262983;4822.324874517571;5188.662736922088;5246.055709972204;5250.05499010178;5251.55361794248;3575.910602234939;3759.191773768653;5193.350831146106;5193.766280517386;5241.135212689921;5659.717014149297;5874.265716785402;5905.793297234598;5999.61002534836;6014.699265036753;5813.447722466359;5970.432808883149;5975.850603734898;5980.79067232647;6020.337762846083;5323.494268044529;5329.360476742816;5329.813456529003;5330.573554115662;5332.653377530448;21050.157993680234;21597.35207943764;21685;21807.45481362963;22121.557568848693;2477.1453848422266;2547.3758929062315;2561.5773397389407;2591.2744431559004;2596.4417650205296;12988.610341689764;13373;13649.590512284647;14116.223607701573;14360.138391696531;1610.9194540273056;1651.8926269792426;1663.1351697117432;1688.653825965674;1740.356068254741;76517.23482765212;77085.61457192735;78262.6086869562;79334.20665793326;79470;9487.241020718326;9822.705318840446;9881.901180988098;9884.752881177885;9910.752231194278;13852.792208116887;14362.85637143636;14414.783778243334;14417.711645767025;14422.855771442151;6854.828629284308;7045.471589630695;7084.326988936043;7129.108861392326;7152.213256541776;6533.236026272974;6551.770688025897;6716.227633822075;6722.361375669304;6747.257801641816;6309;6450.741970321228;6465.838354041092;6509.674516274262;6521.445677117404;1814.782226132846;1829.7163939589384;1861.702127659568;1861.757971463701;1862.636785826778;885.2431171348533;912.2109375390372;920.7329874336501;926.5876684875301;929.340168480373;16797.83202167987;16994.320227190772;17381.130943452776;17698.991589939855;17940;897.4660077254024;899.3075331994328;916.400880463582;917.312015988009;922.8338898998245;9391.436513809193;9877.555510002067;10004.299699021;10082;10088.798224035478;424490;471525;476005;479645;479754;74328;76106.23893761102;77975.61012194902;78661.60669196617;79223;927.355487935878;931.3108299858192;944.197432182653;950.7147855643279;951.3055469507299;14941.402343906557;15010.249487525798;15401.845981540042;15643.530694079196;15770.605734856721;35212;36158.638413615525;36178;36979.8151009254;37014.25971480609;46577.53422465732;47306;47521.762391187825;47894.76052619715;48472.515274848214;7475.36459400957;7762.5730584818375;7804.648790804314;7815.609219539114;7935.761927142195;11638.767224655629;11744.70638234051;11924.582639607572;12115.878841211828;12422.627321180378;9940.155086817702;10160.491975401348;10263.692089237335;10532.684019479428;10781.19141064439;10311.123554497952;10343.896561034595;10388.844167337345;10394;10570.312929659547;10090.596376144871;10216.284860059724;10652.520636571218;10665.733356666145;11377.886221137682;7791.92208077912;7938.3252423542335;8106.189381061847;8107.824365466856;8162.755117346489;8036.638351274323;10090.545925433225;10265.435401053013;10328.483575821328;10483.318584291997 17:04:07 INFO - PROCESS | 23081 | |3;mootools.html;1351.7026254224074;1351.9999999999993;1358.714669919317;1365.36510522607;1368.9178649281048;7011.894821577674;7397.63011849408;7404.629768511573;7408.629568521579;7411.740589079383;7218.819529511765;7310.853782924339;7407.481476296662;7429.331360177583;7568.281013303741;2025.4430031741279;2053.394248696638;2059.248374343369;2062.7009083682856;2062.9587408251828;1743.163281624821;2040.173729639491;2078.594674038563;2085.509905172283;2092.016752126498;1762.5946032412553;1972.9901350493228;2037.1545808489534;2038.9898050509726;2048.8156065954063;1961.597872436147;1980.1881228696166;1981.4848139483765;1981.8612697111207;1984.9404517864343;1745.519982004946;1805.9458216253533;2000.5098750806;2048.95902081959;2054.2707338894625;7087.929120708778;7283.7450689225625;7284.490085694004;7444.516106453118;7451.739189128409;4295.634871035965;4413.095315460355;4416.293393057128;4417.690761646686;4419.027813880941;2690.9730902690917;2855.029290041396;2879.7984141110132;2880.222339968218;2887.725666061721;1012.9442880641561;1018.8981101889751;1041.5417216424826;1043.191526566909;1071.405370019639;9639.036096390304;9711.66009189675;9725.61097556104;9726.513674316257;9731.221502279801;3728.2729867675544;3836.731428800013;3914.354131568259;3928.0572662561353;3943.802809859553;7873.488223265584;7885.881711774328;7888.881666775003;7894.447388682737;7896.170902055316;4459.264221403432;4459.888502787457;4461.219286624828;4461.799219035086;4462.062966776948;3876.6511014008875;3994.08136128692;4045.5145382554283;4050.0887300357185;4052.5542190359038;3172.4924012158403;3201.535777312277;3270.149761062141;3296.4396052671104;3314.486254630488;942.0579420579421;943.6021590728719;955.6655170690203;962.8170647576937;967.5597603090577;615.1725928625991;624.500399680254;631.2235949781794;642.4795915308522;650.9381608747161;4446.444194475691;4524.276115821519;4684.344191813149;4701.976490117528;4884.145274576935;822.3585603229371;851.2636569367577;872.1191596487594;889.1686273334453;892.6027917576746;3133.1697100268448;3159.573457583243;3259.08745551246;3284.901452956415;3453.309338132384;7577.924220757722;7899.328557072598;8123.75628731139;8399.244068033906;8680.522571258642;8566.828663426697;8620.698275560326;8624.396292259607;8629.187870547255;8717.738467845847;5918.112283157561;6154.107654390178;6691.799246022627;6988.615626140509;7064.258252883374;4645.5354464553275;4758.714477131383;4840.056189043103;5011.4236862761245;5248.790048398174;7900.130985591465;7941.364690824836;8058.758237252658;8129.146439623754;8165.714200003209;8151.144129866516;8235.50586964784;8306.833863322821;8340.666373344999;8386.287165590993;5776.335721392094;5892.823215303548;6137.877242455215;6318.210223722035;6375;7063.081799366049;7113.857722845618;7116.288371162842;7124.821879453055;7127.322904324081;5914.763409463573;5974;6028.2766068072115;6028.720471269632;6130.601510901777;6273.215848018997;6282.874342513032;6332.145160403286;6336.112944187726;6366.77716279928;5585.469380408855;5705.657660540381;5708.828735137952;5711.914321285267;5717;4141.358089496133;4142.5443201247235;4197.223513649985;4261.105167914772;4312.460942382202;3865.284922289273;3905.414187871842;3994.281029414617;3997.620226078518;4003.4395184673585 17:04:07 INFO - PROCESS | 23081 | |4;prototype.html;314.6837428384472;325.6726989375677;328.4498465071007;332.7920049968769;334.32966901362767;4622.399088118548;4678.461976872661;4772.331873537703;4798.47216806151;4831.855044348671;4621.260598304272;4737.597304229136;4751.786169622366;4765.571098601125;4765.594924431428;3813.828377723002;3829.5021647185827;3833.11838277195;3840;3844.442555829403;3582.659647333499;3602.2255215128744;3831.750936189139;3832.7891965941863;3842.2507611015862;3735.7571757835653;3744.3634582121103;3787.2993496203153;3798.65812076913;3807.257584770971;3780.319542482352;3780.8865734028022;3784.9053773655605;3794.1842503861526;3798.6704653371376;3407.16524451511;3407.216340241758;3416.6583341665882;3416.8633254669785;3422.144463884029;4591.793369298356;4646.535346465326;4647.419072615923;4656.41794775653;4753.762311884427;4282.764447955361;4321.481422229353;4348.499922508889;4350.129974005212;4355.825766969318;5026.170681837492;5057.443681195065;5062.797488100471;5065.746712664389;5068.4424713281505;3297.3899828531557;3508.298340331918;3728.1984373359587;3746.6628003479545;3752.362098443244;5192.870178245574;5217.0870097733505;5247.530691406374;5254.054270231357;5264.184051472028;3538.8584456621447;3559.555055618048;3562.71498280137;3564.0733409313766;3572.4641303804638;3585.6195364784744;3590.6947909427467;3590.928181436409;3592.3713350163616;3593.6765691087926;3942.250972315251;4131.710780245414;4140.544540100586;4170.457840480718;4201.390798334224;5009.073321435546;5023.894743156573;5029.346184995927;5030.679751364082;5035.748212589356;1131.5643477261315;1154.3189518184145;1202.3867827408085;1205.15036898986;1205.2768338996707;808.5431731071925;828.2256090555229;829.3821103278096;831.983360332802;833.7290380626199;4315.309550471972;4322.913541729148;4325.978370108192;4326.567343265649;4348.195583817004;5054;5058.140116180258;5066.949330506722;5078.466760990117;5099.311592934981;4177.436046133733;4284.421603083606;4308.870733877989;4311.310190369526;4312.7627980461375;2626.645402870588;2626.921192364232;2640.788736901043;2657.162993656992;2660.2418310781336;4694.596417597283;4709.645177411295;4712.717236965725;4739.881502962454;4778.450478195052;4940.221520252706;5050.419201791768;5058.671186372874;5116.948830511722;5122.334096567422;4642.3500709900645;4675.298705194248;4678.298255261738;4713.505081966344;4716.9292460613115;4987.8753031173055;5007.57435617962;5017.838761580327;5021.372328458942;5023.673461225155;4685;4970.279309500174;4994.775235114356;5018.29743835856;5058.519440653132;4789.760511974457;4790.490995336458;4800.903981920412;4801.247544646249;4816.325714399917;3423.863045478253;3442.397580423366;3471.635478274745;3479.565054368204;3484.8954531364097;4901.779419926183;4976.776045077908;5005.199168133081;5050.469700681522;5115.667481613683;4823.903521929472;4828.903421931612;4831.637627178046;4855.805767769391;4857.368542089505;4851.781669824937;4865.343178670834;4867.488913664014;4876.122297986397;4890.828820991248;4640.767961601839;4773.570378665937;4795.592374648194;4806.14034649383;4829.44461386945;4876.366072410564;4887.828925987574;4909.852704418873;4926.679765815211;4929.151568161939;4797.328374027708;4800.279958006327;4800.495947925418;4824.638152138534;4827.61379089679 17:04:07 INFO - PROCESS | 23081 | |5;yui.html;659.3670076726343;677.8440958579527;720.0782997762865;732.6190381001879;741.2846603028079;11102.666919992393;11765.823512647317;11771.529138834458;11864.881351186486;11867.347295898722;11453.541858325678;11537.711557211074;11547.364894930775;11553.480093395796;11564;797.4736673795296;806.733777853307;821.1172989036779;824.3364091906013;829.4152622401193;808.1110778144024;812.1796985045117;812.4597142899964;813.3046245460135;813.3208770676492;851.4593233296839;851.4891065360765;852.0840096895831;852.5864955496579;852.842224188524;847.0851480401152;847.5423271433418;847.652462490376;847.7033038436559;848.6096395658004;618.0420348459915;624.0452108274347;624.1511544299749;624.272722278545;624.3974564545235;11956.103292253116;12159.504915905592;12368.938155309166;12392.690182745504;12394.62816115518;11556.711082222928;11755.941220293844;11929.761404771943;12669.597833917047;13521.256330901797;11878.40607969598;12019.759604807854;12198.939005304917;12283.631491055283;12475.563355282613;3960.8217630206423;4052.37188235821;4114.506259248879;4132.793360331972;4142.2958097123565;59045;59208;59262;59929.101063484086;61775.38224617741;8448.704295349633;8487.321014318839;8622.26710729595;8655.437396569203;8733.38866279367;17655;17724.9113754433;17748.3788067417;17821.376251831123;17851.107444627716;5901.675407852524;6000.459958603747;6008.819735407945;6092.3603021682975;6153.7230824613;3325.9833700831823;3398;3421.4183588789965;3431.519587257786;3518.8416521256604;2909.229054300612;2960.6299212598424;2973.048624440202;2986.3877905029417;3243.6107667079627;542.5903442900585;544.3223187131966;546.7184400033985;546.8742189296485;559.5160186438783;616.7964571691331;625.9874802504015;627.6579264300967;630.1870586942885;633.1227909249099;8138.573571418043;8277.089520152778;8336.249737523653;8518.105598912149;8745.256653184551;484.1889834527153;504.87630530520255;508.0626244578746;508.65157367202846;510.1480527518978;3978;4192.3711443283755;4273.508546517128;4303.397524346594;4397.714148580332;46839;49025;49330.75334623304;54836;64971;33510;44149.55850441455;47874.28188577174;49937.500624993285;51677.22484162741;44820;45637.77181114073;45875;46470.30294545584;46603.5339646606;7872.567008814341;8401.663933442594;8525.317974562144;8530;8560.91439085626;31721.36557268888;32197.195070123435;32225.677743223205;32315;32388.35223295568;36811;36811.447828282064;37652.81173594115;39240.803795980835;39463.60536394714;10456.424896630455;10805.109105906593;10883.67348979532;10946.726331841768;11015.228933974546;12242.510299587917;12398.876011239772;12451.439685214005;12515.12394132402;12822.166559173624;11112.277701949348;11625.59310424131;11657.591984280509;11708.414579270831;12040;11012.889871101186;11453.255538389978;11667.369360879014;11759.294442333145;12000.63998080059;10712.19658525598;10939.56241750321;11117.610883619034;11177;11252.156088293574;9632.181264592587;9832.9016709832;9837.213022958003;10337.276390652582;10344.431056291674;9175.77060573491;9410.623575057194;9447.811043779224;9751.122398984126;9892.679417231053 17:04:07 INFO - PROCESS | 23081 | __end_tp_report 17:04:07 INFO - PROCESS | 23081 | __start_cc_report 17:04:07 INFO - PROCESS | 23081 | _x_x_mozilla_cycle_collect,1511 17:04:07 INFO - PROCESS | 23081 | __end_cc_report 17:04:07 INFO - PROCESS | 23081 | __startTimestamp1486602247501__endTimestamp 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | ------- Summary: start ------- 17:04:07 INFO - PROCESS | 23081 | Number of tests: 6 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#0] dojo.html Cycles:165 Average:4665.01 Median:4816.36 stddev:493.11 (10.2%) stddev-sans-first:466.36 17:04:07 INFO - PROCESS | 23081 | Values: 2567.7 2580.4 2611.0 2624.6 2630.4 4601.6 4653.3 4743.5 4783.4 4810.7 4647.6 4651.7 4652.3 4715.2 4731.8 4231.7 4308.2 4309.2 4351.0 4378.0 4367.7 4381.8 4409.4 4410.9 4444.8 4199.2 4240.8 4244.6 4278.6 4282.3 4273.2 4280.3 4294.1 4325.5 4340.1 4303.6 4318.9 4328.3 4350.5 4379.2 4686.8 4709.9 4734.7 4749.3 4788.7 4903.5 4948.9 5045.3 5054.6 5058.6 4994.5 5045.7 5053.0 5062.8 5138.6 4734.2 4738.8 4747.3 4753.7 4789.6 4979.5 5161.6 5165.9 5173.4 5182.0 4769.4 4865.8 4889.9 4914.0 4932.4 4990.4 5030.8 5039.1 5048.2 5081.6 5029.7 5030.3 5031.1 5032.7 5065.1 4824.9 4872.3 5016.4 5039.0 5062.2 4327.4 4330.4 4358.7 4371.2 4374.4 3750.1 3757.7 3772.2 3802.1 3804.8 4244.0 4271.6 4323.0 4429.0 4430.6 5053.3 5082.3 5100.8 5127.0 5142.7 4362.2 4363.7 4368.2 4371.3 4415.2 4442.0 4476.9 4571.7 4577.8 4607.8 4749.4 4816.3 4816.4 4841.0 4870.4 5139.5 5168.7 5169.7 5180.1 5234.7 4831.2 4833.7 4835.3 4836.1 4876.7 4831.7 4865.1 5001.3 5007.5 5039.4 5152.2 5156.7 5159.0 5163.0 5198.8 4761.8 4796.6 4800.1 4863.6 5007.4 4847.0 4854.6 4854.7 4871.0 4912.9 4697.1 4858.9 4876.1 4881.0 4884.8 4829.6 4832.5 4855.3 4887.1 4939.0 4872.0 4875.1 4875.1 4889.3 4917.2 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#1] ext.html Cycles:180 Average:36030.89 Median:19449.03 stddev:55888.53 (287.4%) stddev-sans-first:55981.77 17:04:07 INFO - PROCESS | 23081 | Values: 538.8 540.1 552.2 553.1 560.5 27389.7 38155.0 38815.6 38847.9 42221.4 38928.0 39452.6 39474.6 39492.4 39587.4 7452.4 7605.0 7608.8 7624.3 7631.3 6986.7 7132.9 9338.0 9501.6 9525.6 9568.3 9572.7 9634.8 9636.7 9641.9 9422.4 9495.6 9505.1 9513.8 9572.3 9275.6 9350.0 9353.2 9354.3 9359.6 35390.5 36087.8 36133.5 36141.3 36224.5 3343.5 3345.2 3449.8 3501.2 3528.8 23460.8 24709.0 24900.6 25069.9 26101.3 3085.1 3091.5 3144.9 3163.2 3171.5 229759.0 230912.0 231624.0 231684.0 232374.0 40394.8 46313.0 46575.0 46697.8 46715.1 43514.8 43676.3 43678.6 43707.6 49488.8 11596.5 12171.5 12285.7 12288.1 12296.9 9303.2 9343.0 9390.7 9394.2 9426.9 16006.9 16073.7 16184.5 16185.7 16186.7 6155.8 6222.4 6335.3 6350.8 6403.0 740.1 756.2 756.8 761.6 768.3 26477.5 26527.0 27049.3 27574.9 27720.9 880.7 886.1 893.5 913.3 917.6 13954.2 14262.9 14439.1 14599.4 14703.6 111345.4 111780.4 112284.0 112717.0 112999.0 49896.8 50204.5 50375.7 50498.5 50510.5 1131.2 1163.6 1165.2 1173.3 1174.6 24627.0 25460.4 26633.7 26689.6 26714.5 39449.0 41343.6 41404.0 41416.0 41416.8 265398.0 265487.0 265877.0 266130.0 266945.0 24464.9 24641.6 25065.2 25410.6 25443.2 35369.3 35503.1 35580.5 35628.0 35895.5 30414.8 30704.0 30872.2 31123.7 31766.0 30365.5 30439.8 30517.1 30809.5 31517.0 29981.4 30560.8 30647.8 30844.7 30975.8 26714.3 27881.0 28085.3 28509.3 28558.3 21912.9 22136.2 22213.0 22237.2 22243.4 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#2] jquery.html Cycles:180 Average:26874.92 Median:259415.50 stddev:76719.90 (29.6%) stddev-sans-first:76909.26 17:04:07 INFO - PROCESS | 23081 | Values: 343.2 348.1 372.5 386.5 397.5 12134.3 16530.0 16707.3 17515.2 18548.7 18336.7 18401.9 18416.8 18434.6 18473.4 4822.3 5188.7 5246.1 5250.1 5251.6 3575.9 3759.2 5193.4 5193.8 5241.1 5659.7 5874.3 5905.8 5999.6 6014.7 5813.4 5970.4 5975.9 5980.8 6020.3 5323.5 5329.4 5329.8 5330.6 5332.7 21050.2 21597.4 21685.0 21807.5 22121.6 2477.1 2547.4 2561.6 2591.3 2596.4 12988.6 13373.0 13649.6 14116.2 14360.1 1610.9 1651.9 1663.1 1688.7 1740.4 76517.2 77085.6 78262.6 79334.2 79470.0 9487.2 9822.7 9881.9 9884.8 9910.8 13852.8 14362.9 14414.8 14417.7 14422.9 6854.8 7045.5 7084.3 7129.1 7152.2 6533.2 6551.8 6716.2 6722.4 6747.3 6309.0 6450.7 6465.8 6509.7 6521.4 1814.8 1829.7 1861.7 1861.8 1862.6 885.2 912.2 920.7 926.6 929.3 16797.8 16994.3 17381.1 17699.0 17940.0 897.5 899.3 916.4 917.3 922.8 9391.4 9877.6 10004.3 10082.0 10088.8 424490.0 471525.0 476005.0 479645.0 479754.0 74328.0 76106.2 77975.6 78661.6 79223.0 927.4 931.3 944.2 950.7 951.3 14941.4 15010.2 15401.8 15643.5 15770.6 35212.0 36158.6 36178.0 36979.8 37014.3 46577.5 47306.0 47521.8 47894.8 48472.5 7475.4 7762.6 7804.6 7815.6 7935.8 11638.8 11744.7 11924.6 12115.9 12422.6 9940.2 10160.5 10263.7 10532.7 10781.2 10311.1 10343.9 10388.8 10394.0 10570.3 10090.6 10216.3 10652.5 10665.7 11377.9 7791.9 7938.3 8106.2 8107.8 8162.8 8036.6 10090.5 10265.4 10328.5 10483.3 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#3] mootools.html Cycles:180 Average:4696.71 Median:5645.56 stddev:2594.92 (46.0%) stddev-sans-first:2590.03 17:04:07 INFO - PROCESS | 23081 | Values: 1351.7 1352.0 1358.7 1365.4 1368.9 7011.9 7397.6 7404.6 7408.6 7411.7 7218.8 7310.9 7407.5 7429.3 7568.3 2025.4 2053.4 2059.2 2062.7 2063.0 1743.2 2040.2 2078.6 2085.5 2092.0 1762.6 1973.0 2037.2 2039.0 2048.8 1961.6 1980.2 1981.5 1981.9 1984.9 1745.5 1805.9 2000.5 2049.0 2054.3 7087.9 7283.7 7284.5 7444.5 7451.7 4295.6 4413.1 4416.3 4417.7 4419.0 2691.0 2855.0 2879.8 2880.2 2887.7 1012.9 1018.9 1041.5 1043.2 1071.4 9639.0 9711.7 9725.6 9726.5 9731.2 3728.3 3836.7 3914.4 3928.1 3943.8 7873.5 7885.9 7888.9 7894.4 7896.2 4459.3 4459.9 4461.2 4461.8 4462.1 3876.7 3994.1 4045.5 4050.1 4052.6 3172.5 3201.5 3270.1 3296.4 3314.5 942.1 943.6 955.7 962.8 967.6 615.2 624.5 631.2 642.5 650.9 4446.4 4524.3 4684.3 4702.0 4884.1 822.4 851.3 872.1 889.2 892.6 3133.2 3159.6 3259.1 3284.9 3453.3 7577.9 7899.3 8123.8 8399.2 8680.5 8566.8 8620.7 8624.4 8629.2 8717.7 5918.1 6154.1 6691.8 6988.6 7064.3 4645.5 4758.7 4840.1 5011.4 5248.8 7900.1 7941.4 8058.8 8129.1 8165.7 8151.1 8235.5 8306.8 8340.7 8386.3 5776.3 5892.8 6137.9 6318.2 6375.0 7063.1 7113.9 7116.3 7124.8 7127.3 5914.8 5974.0 6028.3 6028.7 6130.6 6273.2 6282.9 6332.1 6336.1 6366.8 5585.5 5705.7 5708.8 5711.9 5717.0 4141.4 4142.5 4197.2 4261.1 4312.5 3865.3 3905.4 3994.3 3997.6 4003.4 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#4] prototype.html Cycles:180 Average:4104.00 Median:4681.73 stddev:1185.45 (25.3%) stddev-sans-first:1154.16 17:04:07 INFO - PROCESS | 23081 | Values: 314.7 325.7 328.4 332.8 334.3 4622.4 4678.5 4772.3 4798.5 4831.9 4621.3 4737.6 4751.8 4765.6 4765.6 3813.8 3829.5 3833.1 3840.0 3844.4 3582.7 3602.2 3831.8 3832.8 3842.3 3735.8 3744.4 3787.3 3798.7 3807.3 3780.3 3780.9 3784.9 3794.2 3798.7 3407.2 3407.2 3416.7 3416.9 3422.1 4591.8 4646.5 4647.4 4656.4 4753.8 4282.8 4321.5 4348.5 4350.1 4355.8 5026.2 5057.4 5062.8 5065.7 5068.4 3297.4 3508.3 3728.2 3746.7 3752.4 5192.9 5217.1 5247.5 5254.1 5264.2 3538.9 3559.6 3562.7 3564.1 3572.5 3585.6 3590.7 3590.9 3592.4 3593.7 3942.3 4131.7 4140.5 4170.5 4201.4 5009.1 5023.9 5029.3 5030.7 5035.7 1131.6 1154.3 1202.4 1205.2 1205.3 808.5 828.2 829.4 832.0 833.7 4315.3 4322.9 4326.0 4326.6 4348.2 5054.0 5058.1 5066.9 5078.5 5099.3 4177.4 4284.4 4308.9 4311.3 4312.8 2626.6 2626.9 2640.8 2657.2 2660.2 4694.6 4709.6 4712.7 4739.9 4778.5 4940.2 5050.4 5058.7 5116.9 5122.3 4642.4 4675.3 4678.3 4713.5 4716.9 4987.9 5007.6 5017.8 5021.4 5023.7 4685.0 4970.3 4994.8 5018.3 5058.5 4789.8 4790.5 4800.9 4801.2 4816.3 3423.9 3442.4 3471.6 3479.6 3484.9 4901.8 4976.8 5005.2 5050.5 5115.7 4823.9 4828.9 4831.6 4855.8 4857.4 4851.8 4865.3 4867.5 4876.1 4890.8 4640.8 4773.6 4795.6 4806.1 4829.4 4876.4 4887.8 4909.9 4926.7 4929.2 4797.3 4800.3 4800.5 4824.6 4827.6 17:04:07 INFO - PROCESS | 23081 | 17:04:07 INFO - PROCESS | 23081 | [#5] yui.html Cycles:180 Average:13376.83 Median:45756.39 stddev:15753.53 (34.4%) stddev-sans-first:15768.78 17:04:07 INFO - PROCESS | 23081 | Values: 659.4 677.8 720.1 732.6 741.3 11102.7 11765.8 11771.5 11864.9 11867.3 11453.5 11537.7 11547.4 11553.5 11564.0 797.5 806.7 821.1 824.3 829.4 808.1 812.2 812.5 813.3 813.3 851.5 851.5 852.1 852.6 852.8 847.1 847.5 847.7 847.7 848.6 618.0 624.0 624.2 624.3 624.4 11956.1 12159.5 12368.9 12392.7 12394.6 11556.7 11755.9 11929.8 12669.6 13521.3 11878.4 12019.8 12198.9 12283.6 12475.6 3960.8 4052.4 4114.5 4132.8 4142.3 59045.0 59208.0 59262.0 59929.1 61775.4 8448.7 8487.3 8622.3 8655.4 8733.4 17655.0 17724.9 17748.4 17821.4 17851.1 5901.7 6000.5 6008.8 6092.4 6153.7 3326.0 3398.0 3421.4 3431.5 3518.8 2909.2 2960.6 2973.0 2986.4 3243.6 542.6 544.3 546.7 546.9 559.5 616.8 626.0 627.7 630.2 633.1 8138.6 8277.1 8336.2 8518.1 8745.3 484.2 504.9 508.1 508.7 510.1 3978.0 4192.4 4273.5 4303.4 4397.7 46839.0 49025.0 49330.8 54836.0 64971.0 33510.0 44149.6 47874.3 49937.5 51677.2 44820.0 45637.8 45875.0 46470.3 46603.5 7872.6 8401.7 8525.3 8530.0 8560.9 31721.4 32197.2 32225.7 32315.0 32388.4 36811.0 36811.4 37652.8 39240.8 39463.6 10456.4 10805.1 10883.7 10946.7 11015.2 12242.5 12398.9 12451.4 12515.1 12822.2 11112.3 11625.6 11657.6 11708.4 12040.0 11012.9 11453.3 11667.4 11759.3 12000.6 10712.2 10939.6 11117.6 11177.0 11252.2 9632.2 9832.9 9837.2 10337.3 10344.4 9175.8 9410.6 9447.8 9751.1 9892.7 17:04:07 INFO - PROCESS | 23081 | -------- Summary: end -------- 17:04:07 INFO - PROCESS | 23081 | 17:04:08 INFO - TEST-INFO | 23081: exit 0 17:04:08 INFO - TEST-OK | dromaeo_css | took 1123448ms 17:04:08 INFO - TEST-START | kraken 17:04:08 INFO - operating with platform_type : linux_ 17:04:08 INFO - Initialising browser for kraken test... 17:04:08 INFO - TEST-INFO | started process 23384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDamnGC/profile http://localhost:36326/getInfo.html) 17:04:12 INFO - PROCESS | 23384 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:04:12 INFO - PROCESS | 23384 | __metrics Screen width/height:1600/1200 17:04:12 INFO - PROCESS | 23384 | colorDepth:24 17:04:12 INFO - PROCESS | 23384 | Browser inner width/height: 1024/697 17:04:12 INFO - PROCESS | 23384 | __metrics 17:04:13 INFO - PROCESS | 23384 | 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" 17:04:13 INFO - PROCESS | 23384 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:04:14 INFO - PROCESS | 23384 | console.error: PushService: 17:04:14 INFO - PROCESS | 23384 | stateChangeProcessEnqueue: Error transitioning state 17:04:14 INFO - PROCESS | 23384 | UnknownError 17:04:14 INFO - TEST-INFO | 23384: exit 0 17:04:14 INFO - Browser initialized. 17:04:14 INFO - Running cycle 1/1 for kraken test... 17:04:14 INFO - TEST-INFO | started process 23468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDamnGC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:27 INFO - PROCESS | 23468 | RSS: Main: 153468928 17:04:27 INFO - PROCESS | 23468 | 17:04:29 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36326/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:30 INFO - PROCESS | 23468 | RSS: Main: 209285120 17:04:30 INFO - PROCESS | 23468 | 17:04:33 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36326/tests/kraken/driver.html?testName=audio-dft) 17:04:34 INFO - PROCESS | 23468 | RSS: Main: 187617280 17:04:34 INFO - PROCESS | 23468 | 17:04:37 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36326/tests/kraken/driver.html?testName=audio-fft) 17:04:37 INFO - PROCESS | 23468 | RSS: Main: 270438400 17:04:37 INFO - PROCESS | 23468 | 17:04:40 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36326/tests/kraken/driver.html?testName=audio-oscillator) 17:04:40 INFO - PROCESS | 23468 | RSS: Main: 181358592 17:04:40 INFO - PROCESS | 23468 | 17:04:43 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36326/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:43 INFO - PROCESS | 23468 | RSS: Main: 184184832 17:04:43 INFO - PROCESS | 23468 | 17:04:46 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36326/tests/kraken/driver.html?testName=imaging-darkroom) 17:04:47 INFO - PROCESS | 23468 | RSS: Main: 223875072 17:04:47 INFO - PROCESS | 23468 | 17:04:50 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36326/tests/kraken/driver.html?testName=imaging-desaturate) 17:04:51 INFO - PROCESS | 23468 | RSS: Main: 218284032 17:04:51 INFO - PROCESS | 23468 | 17:04:54 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36326/tests/kraken/driver.html?testName=json-parse-financial) 17:04:54 INFO - PROCESS | 23468 | RSS: Main: 219873280 17:04:54 INFO - PROCESS | 23468 | 17:04:56 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36326/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:57 INFO - PROCESS | 23468 | RSS: Main: 182943744 17:04:57 INFO - PROCESS | 23468 | 17:04:59 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:59 INFO - PROCESS | 23468 | RSS: Main: 190279680 17:04:59 INFO - PROCESS | 23468 | 17:05:02 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:02 INFO - PROCESS | 23468 | RSS: Main: 198397952 17:05:02 INFO - PROCESS | 23468 | 17:05:05 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:06 INFO - PROCESS | 23468 | RSS: Main: 207986688 17:05:06 INFO - PROCESS | 23468 | 17:05:09 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:10 INFO - PROCESS | 23468 | RSS: Main: 204541952 17:05:10 INFO - PROCESS | 23468 | 17:05:11 INFO - PROCESS | 23468 | Cycle 1(1): loaded http://localhost:36326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36326/tests/kraken/driver.html?testName=ai-astar) 17:05:12 INFO - PROCESS | 23468 | RSS: Main: 256659456 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | __start_tp_report 17:05:12 INFO - PROCESS | 23468 | _x_x_mozilla_page_load 17:05:12 INFO - PROCESS | 23468 | _x_x_mozilla_page_load_details 17:05:12 INFO - PROCESS | 23468 | |i|pagename|runs| 17:05:12 INFO - PROCESS | 23468 | |0;ai-astar;117;117;116;116;119;118;118;115;117;117 17:05:12 INFO - PROCESS | 23468 | |1;audio-beat-detection;148;127;127;171;131;125;125;127;147;131 17:05:12 INFO - PROCESS | 23468 | |2;audio-dft;163;162;165;163;163;162;163;163;163;163 17:05:12 INFO - PROCESS | 23468 | |3;audio-fft;76;77;77;114;77;78;77;77;99;78 17:05:12 INFO - PROCESS | 23468 | |4;audio-oscillator;85;86;119;85;85;84;103;84;85;119 17:05:12 INFO - PROCESS | 23468 | |5;imaging-gaussian-blur;113;114;114;114;114;118;113;113;113;113 17:05:12 INFO - PROCESS | 23468 | |6;imaging-darkroom;149;149;149;149;149;149;148;149;148;149 17:05:12 INFO - PROCESS | 23468 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;95;95 17:05:12 INFO - PROCESS | 23468 | |8;json-parse-financial;60;61;61;61;62;61;71;61;61;61 17:05:12 INFO - PROCESS | 23468 | |9;json-stringify-tinderbox;65;65;66;65;66;66;66;66;65;65 17:05:12 INFO - PROCESS | 23468 | |10;stanford-crypto-aes;96;93;91;98;88;87;88;87;87;87 17:05:12 INFO - PROCESS | 23468 | |11;stanford-crypto-ccm;139;142;135;134;142;143;138;145;176;153 17:05:12 INFO - PROCESS | 23468 | |12;stanford-crypto-pbkdf2;189;193;190;186;180;184;194;191;188;179 17:05:12 INFO - PROCESS | 23468 | |13;stanford-crypto-sha256-iterative;72;70;70;71;68;69;70;69;69;71 17:05:12 INFO - PROCESS | 23468 | __end_tp_report 17:05:12 INFO - PROCESS | 23468 | __start_cc_report 17:05:12 INFO - PROCESS | 23468 | _x_x_mozilla_cycle_collect,1949 17:05:12 INFO - PROCESS | 23468 | __end_cc_report 17:05:12 INFO - PROCESS | 23468 | __startTimestamp1486602312187__endTimestamp 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | ------- Summary: start ------- 17:05:12 INFO - PROCESS | 23468 | Number of tests: 14 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.00 stddev:1.15 (1.0%) stddev-sans-first:1.22 17:05:12 INFO - PROCESS | 23468 | Values: 117.0 117.0 116.0 116.0 119.0 118.0 118.0 115.0 117.0 117.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:131.00 stddev:15.00 (11.5%) stddev-sans-first:15.26 17:05:12 INFO - PROCESS | 23468 | Values: 148.0 127.0 127.0 171.0 131.0 125.0 125.0 127.0 147.0 131.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 17:05:12 INFO - PROCESS | 23468 | Values: 163.0 162.0 165.0 163.0 163.0 162.0 163.0 163.0 163.0 163.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#3] audio-fft Cycles:10 Average:83.00 Median:77.00 stddev:12.89 (16.7%) stddev-sans-first:13.42 17:05:12 INFO - PROCESS | 23468 | Values: 76.0 77.0 77.0 114.0 77.0 78.0 77.0 77.0 99.0 78.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:85.00 stddev:14.59 (17.2%) stddev-sans-first:15.15 17:05:12 INFO - PROCESS | 23468 | Values: 85.0 86.0 119.0 85.0 85.0 84.0 103.0 84.0 85.0 119.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.52 (1.3%) stddev-sans-first:1.58 17:05:12 INFO - PROCESS | 23468 | Values: 113.0 114.0 114.0 114.0 114.0 118.0 113.0 113.0 113.0 113.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 17:05:12 INFO - PROCESS | 23468 | Values: 149.0 149.0 149.0 149.0 149.0 149.0 148.0 149.0 148.0 149.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:05:12 INFO - PROCESS | 23468 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:61.00 stddev:3.20 (5.2%) stddev-sans-first:3.31 17:05:12 INFO - PROCESS | 23468 | Values: 60.0 61.0 61.0 61.0 62.0 61.0 71.0 61.0 61.0 61.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 17:05:12 INFO - PROCESS | 23468 | Values: 65.0 65.0 66.0 65.0 66.0 66.0 66.0 66.0 65.0 65.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:89.50 stddev:4.13 (4.6%) stddev-sans-first:3.81 17:05:12 INFO - PROCESS | 23468 | Values: 96.0 93.0 91.0 98.0 88.0 87.0 88.0 87.0 87.0 87.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:142.50 stddev:12.26 (8.6%) stddev-sans-first:12.83 17:05:12 INFO - PROCESS | 23468 | Values: 139.0 142.0 135.0 134.0 142.0 143.0 138.0 145.0 176.0 153.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.40 Median:189.50 stddev:5.13 (2.7%) stddev-sans-first:5.40 17:05:12 INFO - PROCESS | 23468 | Values: 189.0 193.0 190.0 186.0 180.0 184.0 194.0 191.0 188.0 179.0 17:05:12 INFO - PROCESS | 23468 | 17:05:12 INFO - PROCESS | 23468 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:1.20 (1.7%) stddev-sans-first:1.00 17:05:12 INFO - PROCESS | 23468 | Values: 72.0 70.0 70.0 71.0 68.0 69.0 70.0 69.0 69.0 71.0 17:05:12 INFO - PROCESS | 23468 | -------- Summary: end -------- 17:05:12 INFO - PROCESS | 23468 | 17:05:13 INFO - TEST-INFO | 23468: exit 0 17:05:13 INFO - TEST-OK | kraken | took 64932ms 17:05:13 INFO - SUITE-END | took 1188s 17:05:13 INFO - Completed test suite (00:19:48) 17:05:13 INFO - javascript benchmark 17:05:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6155.849817962215, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.6533667954823, 2580.367809886578, 2611.0, 2624.566946453835, 2630.421307312392, 4601.608863246628, 4653.302004699289, 4743.454502732183, 4783.425988881335, 4810.663253572251, 4647.604953578942, 4651.674382793206, 4652.32541281514, 4715.1512727709, 4731.834385796497, 4231.682623803219, 4308.159908817766, 4309.245881970643, 4351.042770590465, 4377.956220437787, 4367.650587952956, 4381.780910954455, 4409.426774519308, 4410.889727756799, 4444.822207111712, 4199.2441360555085, 4240.830366785325, 4244.554321796229, 4278.63631591312, 4282.314829627245, 4273.18809426208, 4280.272353699878, 4294.098239369736, 4325.4593175853015, 4340.066885619607, 4303.569643035703, 4318.892027699333, 4328.285832837578, 4350.543192964757, 4379.1679580879845, 4686.789094490756, 4709.882252943669, 4734.739589322586, 4749.26386410107, 4788.664793464459, 4903.4606193318705, 4948.851534453972, 5045.293658887758, 5054.570361519275, 5058.645894787366, 4994.475580064077, 5045.722485263309, 5053.0, 5062.822801201977, 5138.563222126124, 4734.242521196661, 4738.763061846894, 4747.311639812208, 4753.667243293005, 4789.568938795525, 4979.45226025137, 5161.5870730341485, 5165.948340516622, 5173.379194496609, 5182.0, 4769.403824521934, 4865.8053677853195, 4889.877753056202, 4913.950860491421, 4932.383452068491, 4990.445099132091, 5030.8239211627415, 5039.143345631252, 5048.242763585492, 5081.593472522263, 5029.723365214948, 5030.346055012825, 5031.069252188357, 5032.672876263031, 5065.11360511909, 4824.879378015507, 4872.269159626014, 5016.44819069902, 5038.974805125951, 5062.164742817467, 4327.350897365357, 4330.372096046119, 4358.694891357638, 4371.191329604034, 4374.387585737999, 3750.1187221003042, 3757.680597149218, 3772.2078363543415, 3802.1065049713297, 3804.752691075071, 4244.02387450888, 4271.572842715766, 4322.956770432255, 4428.955710442919, 4430.645548356124, 5053.343065401547, 5082.339295891585, 5100.770465329069, 5126.974365128151, 5142.6914385137015, 4362.192994296065, 4363.672724545671, 4368.170047690928, 4371.256886329332, 4415.183191109655, 4442.0, 4476.865694029184, 4571.725696458223, 4577.793999270041, 4607.792649330788, 4749.382580264543, 4816.34979277793, 4816.362436771515, 4841.0, 4870.439899411613, 5139.537441630271, 5168.689878607295, 5169.663971841818, 5180.145276029413, 5234.712090835041, 4831.178699621143, 4833.73414462208, 4835.347228124158, 4836.105320515716, 4876.731779752005, 4831.685940414002, 4865.1242776300605, 5001.274815151855, 5007.524285192901, 5039.395272567315, 5152.227165925141, 5156.7421628919155, 5159.045576568347, 5162.97418512905, 5198.818041368534, 4761.809527618995, 4796.640251981185, 4800.135975524299, 4863.562279394871, 5007.39911210657, 4847.030593881308, 4854.63590230727, 4854.68444551103, 4870.951290487192, 4912.926306105483, 4697.107549565571, 4858.92711609319, 4876.122297986397, 4880.99939512392, 4884.8046078157895, 4829.5653391194965, 4832.516748325139, 4855.344528488608, 4887.144749668722, 4939.036887806843, 4872.049950259665, 4875.098106850244, 4875.146849301429, 4889.315495830658, 4917.188663870421], "value": 4633.099110704481, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.8329617818476, 540.1061243641774, 552.2213678713014, 553.112254830996, 560.5375565158745, 27389.726102738965, 38155.0, 38815.611843881554, 38847.86336751474, 42221.366679499755, 38928.0, 39452.60547394525, 39474.605253947455, 39492.40761388574, 39587.406188907116, 7452.366548843354, 7605.049368828896, 7608.809779755523, 7624.313811756941, 7631.313181813635, 6986.720531178747, 7132.85734285314, 9337.953310233474, 9501.619935202583, 9525.618975240983, 9568.282378821581, 9572.712818615453, 9634.807303853919, 9636.662716804913, 9641.855372169422, 9422.434653920787, 9495.572699228516, 9505.144536991704, 9513.809723805485, 9572.28207884408, 9275.58259878307, 9349.953250233773, 9353.204977576846, 9354.298427617956, 9359.578818953163, 35390.46914296287, 36087.819560902026, 36133.45799813005, 36141.27717445663, 36224.45663315053, 3343.5151902974176, 3345.1637090727318, 3449.760694505637, 3501.19472521319, 3528.770629909048, 23460.7653923462, 24709.0, 24900.626490602473, 25069.87465062681, 26101.347466313306, 3085.1207405889213, 3091.520814273791, 3144.937101257985, 3163.209197700575, 3171.5401266816184, 229759.0, 230912.0, 231624.0, 231684.0, 232373.9999999983, 40394.79802601027, 46313.0, 46575.0, 46697.766511167225, 46715.06569868584, 43514.782426087666, 43676.344854827206, 43678.563214368085, 43707.56292437035, 49488.752556237705, 11596.536138554364, 12171.513139474499, 12285.69285767863, 12288.078394120477, 12296.938515307545, 9303.162715355651, 9342.953285233667, 9390.718278451657, 9394.201492873046, 9426.858597121049, 16006.919965400099, 16073.678526429407, 16184.520328129509, 16185.681935203254, 16186.676266474604, 6155.815325540241, 6222.377762223741, 6335.271443783931, 6350.841228969312, 6402.967985160138, 740.1155619035309, 756.2437562437563, 756.8486302739474, 761.5506850957851, 768.2739810878778, 26477.47045059088, 26527.0, 27049.323766905592, 27574.862125689244, 27720.861395692893, 880.6521424037469, 886.1360173830464, 893.4728510178895, 913.2511340700695, 917.6191880369572, 13954.232517211447, 14262.857371426153, 14439.133651980916, 14599.416023358946, 14703.632409189642, 111345.44327278312, 111780.44109779561, 112284.0, 112717.0, 112999.0, 49896.750516247186, 50204.49795501998, 50375.74812125916, 50498.49501505012, 50510.49489505131, 1131.1911982932406, 1163.5694792926672, 1165.1901928160016, 1173.3018853781987, 1174.5712814822527, 24627.0, 25460.363490912874, 26633.733662663126, 26689.599656004786, 26714.46571068529, 39449.0, 41343.58656413398, 41404.0, 41416.0, 41416.79291603523, 265398.0, 265487.0, 265877.0, 266130.0, 266945.0, 24464.877675611508, 24641.63037554474, 25065.248042558753, 25410.618840717034, 25443.236702898943, 35369.29261414706, 35503.11242218965, 35580.466293006146, 35628.0, 35895.461568075974, 30414.84792576023, 30704.0, 30872.228194295323, 31123.68876311208, 31766.0, 30365.544516831822, 30439.847800760854, 30517.08448746541, 30809.537856931714, 31517.0, 29981.400371992, 30560.847195764767, 30647.77408903619, 30844.691553084183, 30975.84512077425, 26714.332141696614, 27881.0, 28085.297867553476, 28509.287267818472, 28558.286042849097, 21912.890435548357, 22136.225232116798, 22213.0, 22237.22169724052, 22243.443913902283], "value": 15911.386829585666, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.1763766959297, 348.13141212674384, 372.5175897213109, 386.50334320398287, 397.48128692056855, 12134.332611706353, 16530.0, 16707.331706731748, 17515.2118154683, 18548.72176917344, 18336.724949125775, 18401.907990460062, 18416.81583184168, 18434.631307373846, 18473.35343262983, 4822.324874517571, 5188.662736922088, 5246.055709972204, 5250.05499010178, 5251.55361794248, 3575.910602234939, 3759.191773768653, 5193.350831146106, 5193.766280517386, 5241.135212689921, 5659.717014149297, 5874.265716785402, 5905.793297234598, 5999.61002534836, 6014.699265036753, 5813.447722466359, 5970.432808883149, 5975.850603734898, 5980.79067232647, 6020.337762846083, 5323.494268044529, 5329.360476742816, 5329.813456529003, 5330.573554115662, 5332.653377530448, 21050.157993680234, 21597.35207943764, 21685.0, 21807.45481362963, 22121.557568848693, 2477.1453848422266, 2547.3758929062315, 2561.5773397389407, 2591.2744431559004, 2596.4417650205296, 12988.610341689764, 13373.0, 13649.590512284647, 14116.223607701573, 14360.138391696531, 1610.9194540273056, 1651.8926269792426, 1663.1351697117432, 1688.653825965674, 1740.356068254741, 76517.23482765212, 77085.61457192735, 78262.6086869562, 79334.20665793326, 79470.0, 9487.241020718326, 9822.705318840446, 9881.901180988098, 9884.752881177885, 9910.752231194278, 13852.792208116887, 14362.85637143636, 14414.783778243334, 14417.711645767025, 14422.855771442151, 6854.828629284308, 7045.471589630695, 7084.326988936043, 7129.108861392326, 7152.213256541776, 6533.236026272974, 6551.770688025897, 6716.227633822075, 6722.361375669304, 6747.257801641816, 6309.0, 6450.741970321228, 6465.838354041092, 6509.674516274262, 6521.445677117404, 1814.782226132846, 1829.7163939589384, 1861.702127659568, 1861.757971463701, 1862.636785826778, 885.2431171348533, 912.2109375390372, 920.7329874336501, 926.5876684875301, 929.340168480373, 16797.83202167987, 16994.320227190772, 17381.130943452776, 17698.991589939855, 17940.0, 897.4660077254024, 899.3075331994328, 916.400880463582, 917.312015988009, 922.8338898998245, 9391.436513809193, 9877.555510002067, 10004.299699021, 10082.0, 10088.798224035478, 424490.0, 471525.0, 476005.0, 479645.0, 479754.0, 74328.0, 76106.23893761102, 77975.61012194902, 78661.60669196617, 79223.0, 927.355487935878, 931.3108299858192, 944.197432182653, 950.7147855643279, 951.3055469507299, 14941.402343906557, 15010.249487525798, 15401.845981540042, 15643.530694079196, 15770.605734856721, 35212.0, 36158.638413615525, 36178.0, 36979.8151009254, 37014.25971480609, 46577.53422465732, 47306.0, 47521.762391187825, 47894.76052619715, 48472.515274848214, 7475.36459400957, 7762.5730584818375, 7804.648790804314, 7815.609219539114, 7935.761927142195, 11638.767224655629, 11744.70638234051, 11924.582639607572, 12115.878841211828, 12422.627321180378, 9940.155086817702, 10160.491975401348, 10263.692089237335, 10532.684019479428, 10781.19141064439, 10311.123554497952, 10343.896561034595, 10388.844167337345, 10394.0, 10570.312929659547, 10090.596376144871, 10216.284860059724, 10652.520636571218, 10665.733356666145, 11377.886221137682, 7791.92208077912, 7938.3252423542335, 8106.189381061847, 8107.824365466856, 8162.755117346489, 8036.638351274323, 10090.545925433225, 10265.435401053013, 10328.483575821328, 10483.318584291997], "value": 8583.961730136156, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.7026254224074, 1351.9999999999993, 1358.714669919317, 1365.36510522607, 1368.9178649281048, 7011.894821577674, 7397.63011849408, 7404.629768511573, 7408.629568521579, 7411.740589079383, 7218.819529511765, 7310.853782924339, 7407.481476296662, 7429.331360177583, 7568.281013303741, 2025.4430031741279, 2053.394248696638, 2059.248374343369, 2062.7009083682856, 2062.9587408251828, 1743.163281624821, 2040.173729639491, 2078.594674038563, 2085.509905172283, 2092.016752126498, 1762.5946032412553, 1972.9901350493228, 2037.1545808489534, 2038.9898050509726, 2048.8156065954063, 1961.597872436147, 1980.1881228696166, 1981.4848139483765, 1981.8612697111207, 1984.9404517864343, 1745.519982004946, 1805.9458216253533, 2000.5098750806, 2048.95902081959, 2054.2707338894625, 7087.929120708778, 7283.7450689225625, 7284.490085694004, 7444.516106453118, 7451.739189128409, 4295.634871035965, 4413.095315460355, 4416.293393057128, 4417.690761646686, 4419.027813880941, 2690.9730902690917, 2855.029290041396, 2879.7984141110132, 2880.222339968218, 2887.725666061721, 1012.9442880641561, 1018.8981101889751, 1041.5417216424826, 1043.191526566909, 1071.405370019639, 9639.036096390304, 9711.66009189675, 9725.61097556104, 9726.513674316257, 9731.221502279801, 3728.2729867675544, 3836.731428800013, 3914.354131568259, 3928.0572662561353, 3943.802809859553, 7873.488223265584, 7885.881711774328, 7888.881666775003, 7894.447388682737, 7896.170902055316, 4459.264221403432, 4459.888502787457, 4461.219286624828, 4461.799219035086, 4462.062966776948, 3876.6511014008875, 3994.08136128692, 4045.5145382554283, 4050.0887300357185, 4052.5542190359038, 3172.4924012158403, 3201.535777312277, 3270.149761062141, 3296.4396052671104, 3314.486254630488, 942.0579420579421, 943.6021590728719, 955.6655170690203, 962.8170647576937, 967.5597603090577, 615.1725928625991, 624.500399680254, 631.2235949781794, 642.4795915308522, 650.9381608747161, 4446.444194475691, 4524.276115821519, 4684.344191813149, 4701.976490117528, 4884.145274576935, 822.3585603229371, 851.2636569367577, 872.1191596487594, 889.1686273334453, 892.6027917576746, 3133.1697100268448, 3159.573457583243, 3259.08745551246, 3284.901452956415, 3453.309338132384, 7577.924220757722, 7899.328557072598, 8123.75628731139, 8399.244068033906, 8680.522571258642, 8566.828663426697, 8620.698275560326, 8624.396292259607, 8629.187870547255, 8717.738467845847, 5918.112283157561, 6154.107654390178, 6691.799246022627, 6988.615626140509, 7064.258252883374, 4645.5354464553275, 4758.714477131383, 4840.056189043103, 5011.4236862761245, 5248.790048398174, 7900.130985591465, 7941.364690824836, 8058.758237252658, 8129.146439623754, 8165.714200003209, 8151.144129866516, 8235.50586964784, 8306.833863322821, 8340.666373344999, 8386.287165590993, 5776.335721392094, 5892.823215303548, 6137.877242455215, 6318.210223722035, 6375.0, 7063.081799366049, 7113.857722845618, 7116.288371162842, 7124.821879453055, 7127.322904324081, 5914.763409463573, 5974.0, 6028.2766068072115, 6028.720471269632, 6130.601510901777, 6273.215848018997, 6282.874342513032, 6332.145160403286, 6336.112944187726, 6366.77716279928, 5585.469380408855, 5705.657660540381, 5708.828735137952, 5711.914321285267, 5717.0, 4141.358089496133, 4142.5443201247235, 4197.223513649985, 4261.105167914772, 4312.460942382202, 3865.284922289273, 3905.414187871842, 3994.281029414617, 3997.620226078518, 4003.4395184673585], "value": 3792.351455806064, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.6837428384472, 325.6726989375677, 328.4498465071007, 332.7920049968769, 334.32966901362767, 4622.399088118548, 4678.461976872661, 4772.331873537703, 4798.47216806151, 4831.855044348671, 4621.260598304272, 4737.597304229136, 4751.786169622366, 4765.571098601125, 4765.594924431428, 3813.828377723002, 3829.5021647185827, 3833.11838277195, 3840.0, 3844.442555829403, 3582.659647333499, 3602.2255215128744, 3831.750936189139, 3832.7891965941863, 3842.2507611015862, 3735.7571757835653, 3744.3634582121103, 3787.2993496203153, 3798.65812076913, 3807.257584770971, 3780.319542482352, 3780.8865734028022, 3784.9053773655605, 3794.1842503861526, 3798.6704653371376, 3407.16524451511, 3407.216340241758, 3416.6583341665882, 3416.8633254669785, 3422.144463884029, 4591.793369298356, 4646.535346465326, 4647.419072615923, 4656.41794775653, 4753.762311884427, 4282.764447955361, 4321.481422229353, 4348.499922508889, 4350.129974005212, 4355.825766969318, 5026.170681837492, 5057.443681195065, 5062.797488100471, 5065.746712664389, 5068.4424713281505, 3297.3899828531557, 3508.298340331918, 3728.1984373359587, 3746.6628003479545, 3752.362098443244, 5192.870178245574, 5217.0870097733505, 5247.530691406374, 5254.054270231357, 5264.184051472028, 3538.8584456621447, 3559.555055618048, 3562.71498280137, 3564.0733409313766, 3572.4641303804638, 3585.6195364784744, 3590.6947909427467, 3590.928181436409, 3592.3713350163616, 3593.6765691087926, 3942.250972315251, 4131.710780245414, 4140.544540100586, 4170.457840480718, 4201.390798334224, 5009.073321435546, 5023.894743156573, 5029.346184995927, 5030.679751364082, 5035.748212589356, 1131.5643477261315, 1154.3189518184145, 1202.3867827408085, 1205.15036898986, 1205.2768338996707, 808.5431731071925, 828.2256090555229, 829.3821103278096, 831.983360332802, 833.7290380626199, 4315.309550471972, 4322.913541729148, 4325.978370108192, 4326.567343265649, 4348.195583817004, 5054.0, 5058.140116180258, 5066.949330506722, 5078.466760990117, 5099.311592934981, 4177.436046133733, 4284.421603083606, 4308.870733877989, 4311.310190369526, 4312.7627980461375, 2626.645402870588, 2626.921192364232, 2640.788736901043, 2657.162993656992, 2660.2418310781336, 4694.596417597283, 4709.645177411295, 4712.717236965725, 4739.881502962454, 4778.450478195052, 4940.221520252706, 5050.419201791768, 5058.671186372874, 5116.948830511722, 5122.334096567422, 4642.3500709900645, 4675.298705194248, 4678.298255261738, 4713.505081966344, 4716.9292460613115, 4987.8753031173055, 5007.57435617962, 5017.838761580327, 5021.372328458942, 5023.673461225155, 4685.0, 4970.279309500174, 4994.775235114356, 5018.29743835856, 5058.519440653132, 4789.760511974457, 4790.490995336458, 4800.903981920412, 4801.247544646249, 4816.325714399917, 3423.863045478253, 3442.397580423366, 3471.635478274745, 3479.565054368204, 3484.8954531364097, 4901.779419926183, 4976.776045077908, 5005.199168133081, 5050.469700681522, 5115.667481613683, 4823.903521929472, 4828.903421931612, 4831.637627178046, 4855.805767769391, 4857.368542089505, 4851.781669824937, 4865.343178670834, 4867.488913664014, 4876.122297986397, 4890.828820991248, 4640.767961601839, 4773.570378665937, 4795.592374648194, 4806.14034649383, 4829.44461386945, 4876.366072410564, 4887.828925987574, 4909.852704418873, 4926.679765815211, 4929.151568161939, 4797.328374027708, 4800.279958006327, 4800.495947925418, 4824.638152138534, 4827.61379089679], "value": 3731.920475746915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.3670076726343, 677.8440958579527, 720.0782997762865, 732.6190381001879, 741.2846603028079, 11102.666919992393, 11765.823512647317, 11771.529138834458, 11864.881351186486, 11867.347295898722, 11453.541858325678, 11537.711557211074, 11547.364894930775, 11553.480093395796, 11564.0, 797.4736673795296, 806.733777853307, 821.1172989036779, 824.3364091906013, 829.4152622401193, 808.1110778144024, 812.1796985045117, 812.4597142899964, 813.3046245460135, 813.3208770676492, 851.4593233296839, 851.4891065360765, 852.0840096895831, 852.5864955496579, 852.842224188524, 847.0851480401152, 847.5423271433418, 847.652462490376, 847.7033038436559, 848.6096395658004, 618.0420348459915, 624.0452108274347, 624.1511544299749, 624.272722278545, 624.3974564545235, 11956.103292253116, 12159.504915905592, 12368.938155309166, 12392.690182745504, 12394.62816115518, 11556.711082222928, 11755.941220293844, 11929.761404771943, 12669.597833917047, 13521.256330901797, 11878.40607969598, 12019.759604807854, 12198.939005304917, 12283.631491055283, 12475.563355282613, 3960.8217630206423, 4052.37188235821, 4114.506259248879, 4132.793360331972, 4142.2958097123565, 59045.0, 59208.0, 59262.0, 59929.101063484086, 61775.38224617741, 8448.704295349633, 8487.321014318839, 8622.26710729595, 8655.437396569203, 8733.38866279367, 17655.0, 17724.9113754433, 17748.3788067417, 17821.376251831123, 17851.107444627716, 5901.675407852524, 6000.459958603747, 6008.819735407945, 6092.3603021682975, 6153.7230824613, 3325.9833700831823, 3398.0, 3421.4183588789965, 3431.519587257786, 3518.8416521256604, 2909.229054300612, 2960.6299212598424, 2973.048624440202, 2986.3877905029417, 3243.6107667079627, 542.5903442900585, 544.3223187131966, 546.7184400033985, 546.8742189296485, 559.5160186438783, 616.7964571691331, 625.9874802504015, 627.6579264300967, 630.1870586942885, 633.1227909249099, 8138.573571418043, 8277.089520152778, 8336.249737523653, 8518.105598912149, 8745.256653184551, 484.1889834527153, 504.87630530520255, 508.0626244578746, 508.65157367202846, 510.1480527518978, 3978.0, 4192.3711443283755, 4273.508546517128, 4303.397524346594, 4397.714148580332, 46839.0, 49025.0, 49330.75334623304, 54836.0, 64971.0, 33510.0, 44149.55850441455, 47874.28188577174, 49937.500624993285, 51677.22484162741, 44820.0, 45637.77181114073, 45875.0, 46470.30294545584, 46603.5339646606, 7872.567008814341, 8401.663933442594, 8525.317974562144, 8530.0, 8560.91439085626, 31721.36557268888, 32197.195070123435, 32225.677743223205, 32315.0, 32388.35223295568, 36811.0, 36811.447828282064, 37652.81173594115, 39240.803795980835, 39463.60536394714, 10456.424896630455, 10805.109105906593, 10883.67348979532, 10946.726331841768, 11015.228933974546, 12242.510299587917, 12398.876011239772, 12451.439685214005, 12515.12394132402, 12822.166559173624, 11112.277701949348, 11625.59310424131, 11657.591984280509, 11708.414579270831, 12040.0, 11012.889871101186, 11453.255538389978, 11667.369360879014, 11759.294442333145, 12000.63998080059, 10712.19658525598, 10939.56241750321, 11117.610883619034, 11177.0, 11252.156088293574, 9632.181264592587, 9832.9016709832, 9837.213022958003, 10337.276390652582, 10344.431056291674, 9175.77060573491, 9410.623575057194, 9447.811043779224, 9751.122398984126, 9892.679417231053], "value": 6075.31851555805, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 119.0, 118.0, 118.0, 115.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 127.0, 171.0, 131.0, 125.0, 125.0, 127.0, 147.0, 131.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 163.0, 163.0, 162.0, 163.0, 163.0, 163.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 114.0, 77.0, 78.0, 77.0, 77.0, 99.0, 78.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 119.0, 85.0, 85.0, 84.0, 103.0, 84.0, 85.0, 119.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 118.0, 113.0, 113.0, 113.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 149.0, 148.0, 149.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 62.0, 61.0, 71.0, 61.0, 61.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 93.0, 91.0, 98.0, 88.0, 87.0, 88.0, 87.0, 87.0, 87.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 135.0, 134.0, 142.0, 143.0, 138.0, 145.0, 176.0, 153.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 193.0, 190.0, 186.0, 180.0, 184.0, 194.0, 191.0, 188.0, 179.0], "value": 187.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 70.0, 71.0, 68.0, 69.0, 70.0, 69.0, 69.0, 71.0], "value": 69.9, "unit": "score"}]}]} 17:05:13 INFO - Return code: 0 17:05:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:13 ERROR - # TBPL SUCCESS # 17:05:13 INFO - Running post-action listener: _resource_record_post_action 17:05:13 INFO - [mozharness: 2017-02-09 01:05:13.962411Z] Finished run-tests step (success) 17:05:13 INFO - Running post-run listener: _resource_record_post_run 17:05:14 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 396206080; Read time: 20; Write time: 417084 17:05:14 INFO - TinderboxPrint: CPU usage
13.2% 17:05:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 17:05:14 INFO - TinderboxPrint: I/O write bytes / time
396,206,080 / 417,084 17:05:14 INFO - TinderboxPrint: CPU idle
8,350.1 (86.8%) 17:05:14 INFO - TinderboxPrint: CPU user
1,219.2 (12.7%) 17:05:14 INFO - TinderboxPrint: Swap in / out
0 / 0 17:05:14 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 101658624; Read time: 0; Write time: 159500 17:05:14 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 293933056; Read time: 0; Write time: 257584 17:05:14 INFO - Running post-run listener: _upload_blobber_files 17:05:14 INFO - Blob upload gear active. 17:05:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:14 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 17:05:14 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:14 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:15 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:05:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:05:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:16 INFO - (blobuploader) - INFO - Done attempting. 17:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:05:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:16 INFO - (blobuploader) - INFO - Done attempting. 17:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:17 INFO - (blobuploader) - INFO - Done attempting. 17:05:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:20 INFO - (blobuploader) - INFO - Done attempting. 17:05:20 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:20 INFO - Return code: 0 17:05:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/811ca3ba4ad63ffe45879d105558a3285588aea5191592e7f6654cdb4330f2a34d5544a793506fb4d14b7a4a7c614e78760752076e281f3d6eb4ef13eae8603b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/818b5fcb5e229dffd9816f2aa585f908ad77c0b2131baa681d7aa2e2d86c863282504cf05bcc21c07ab99b102f66837a78313361fa0a9552cc9e6a54dfd95420", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/52555b25045dfcb3a7cd13d54ca6beb9856f41e0a8cbe39999821ad6251915aa44399dca959ddf3989a9b82472518c17c8a95e8eed1a3b857c3b8187000e86d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/499a830e6c5330297d325ad5560a6131a6624a030fbdfb913f676baf79a2177da7668c5e909779d4e67bc48fa58eb5b9b68ab764a40ac51e53c8a512f13656f6"} 17:05:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:20 INFO - Contents: 17:05:20 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/811ca3ba4ad63ffe45879d105558a3285588aea5191592e7f6654cdb4330f2a34d5544a793506fb4d14b7a4a7c614e78760752076e281f3d6eb4ef13eae8603b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/818b5fcb5e229dffd9816f2aa585f908ad77c0b2131baa681d7aa2e2d86c863282504cf05bcc21c07ab99b102f66837a78313361fa0a9552cc9e6a54dfd95420", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/52555b25045dfcb3a7cd13d54ca6beb9856f41e0a8cbe39999821ad6251915aa44399dca959ddf3989a9b82472518c17c8a95e8eed1a3b857c3b8187000e86d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/499a830e6c5330297d325ad5560a6131a6624a030fbdfb913f676baf79a2177da7668c5e909779d4e67bc48fa58eb5b9b68ab764a40ac51e53c8a512f13656f6"} 17:05:20 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:20 INFO - Copying logs to upload dir... 17:05:20 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.429365 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-02-08 17:05:20.872854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:05:20.875980) ========= 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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/811ca3ba4ad63ffe45879d105558a3285588aea5191592e7f6654cdb4330f2a34d5544a793506fb4d14b7a4a7c614e78760752076e281f3d6eb4ef13eae8603b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/818b5fcb5e229dffd9816f2aa585f908ad77c0b2131baa681d7aa2e2d86c863282504cf05bcc21c07ab99b102f66837a78313361fa0a9552cc9e6a54dfd95420", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/52555b25045dfcb3a7cd13d54ca6beb9856f41e0a8cbe39999821ad6251915aa44399dca959ddf3989a9b82472518c17c8a95e8eed1a3b857c3b8187000e86d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/499a830e6c5330297d325ad5560a6131a6624a030fbdfb913f676baf79a2177da7668c5e909779d4e67bc48fa58eb5b9b68ab764a40ac51e53c8a512f13656f6"} build_url:https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011506 build_url: 'https://queue.taskcluster.net/v1/task/fQLIp1cHQxypFBTEvDgjWw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/811ca3ba4ad63ffe45879d105558a3285588aea5191592e7f6654cdb4330f2a34d5544a793506fb4d14b7a4a7c614e78760752076e281f3d6eb4ef13eae8603b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/818b5fcb5e229dffd9816f2aa585f908ad77c0b2131baa681d7aa2e2d86c863282504cf05bcc21c07ab99b102f66837a78313361fa0a9552cc9e6a54dfd95420", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/52555b25045dfcb3a7cd13d54ca6beb9856f41e0a8cbe39999821ad6251915aa44399dca959ddf3989a9b82472518c17c8a95e8eed1a3b857c3b8187000e86d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/499a830e6c5330297d325ad5560a6131a6624a030fbdfb913f676baf79a2177da7668c5e909779d4e67bc48fa58eb5b9b68ab764a40ac51e53c8a512f13656f6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 17:05:20.900975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:05:20.901288) ========= 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=14ef55db36f7623abb31b9cf000001d5-1486600991.583784-919613935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004543 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 17:05:20.985488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:05:20.985811) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 17:05:20.986106) ========= ========= Total master_lag: 0.17 =========