builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-056 starttime: 1468372381.02 results: success (0) buildid: 20160712165527 builduid: c6489b32af94416780df9336b930a9f0 revision: 0d7a788f1c40adf5038505e25e600d8551dbda06 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.022917) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.023457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.023766) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.105539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.105894) ========= 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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 18:13:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-07-12 18:13:01 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159281 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.321412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.321761) ========= 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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016801 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.375643) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:01.375957) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0d7a788f1c40adf5038505e25e600d8551dbda06 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0d7a788f1c40adf5038505e25e600d8551dbda06 --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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 18:13:01,477 truncating revision to first 12 chars 2016-07-12 18:13:01,477 Setting DEBUG logging. 2016-07-12 18:13:01,477 attempt 1/10 2016-07-12 18:13:01,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0d7a788f1c40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 18:13:01,842 unpacking tar archive at: fx-team-0d7a788f1c40/testing/mozharness/ program finished with exit code 0 elapsedTime=0.694840 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:02.085134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:02.085535) ========= script_repo_revision: 0d7a788f1c40adf5038505e25e600d8551dbda06 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:02.086075) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:02.086401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 18:13:02.101719) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-12 18:13:02.102080) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:13:02 INFO - MultiFileLogger online at 20160712 18:13:02 in /builds/slave/test 18:13:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 18:13:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:13:02 INFO - {'append_to_log': False, 18:13:02 INFO - 'base_work_dir': '/builds/slave/test', 18:13:02 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:13:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:13:02 INFO - 'branch': 'Fx-Team-Non-PGO', 18:13:02 INFO - 'buildbot_json_path': 'buildprops.json', 18:13:02 INFO - 'config_files': ('talos/linux_config.py',), 18:13:02 INFO - 'default_actions': ('clobber', 18:13:02 INFO - 'read-buildbot-config', 18:13:02 INFO - 'download-and-extract', 18:13:02 INFO - 'populate-webroot', 18:13:02 INFO - 'create-virtualenv', 18:13:02 INFO - 'install', 18:13:02 INFO - 'run-tests'), 18:13:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:13:02 INFO - 'download_minidump_stackwalk': True, 18:13:02 INFO - 'download_symbols': 'ondemand', 18:13:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:13:02 INFO - 'tooltool.py': '/tools/tooltool.py', 18:13:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:13:02 INFO - '/tools/misc-python/virtualenv.py')}, 18:13:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:13:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:13:02 INFO - 'installer_path': 'installer.exe', 18:13:02 INFO - 'log_level': 'info', 18:13:02 INFO - 'log_name': 'talos', 18:13:02 INFO - 'log_to_console': True, 18:13:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:13:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:13:02 INFO - 'opt_config_files': (), 18:13:02 INFO - 'pip_index': False, 18:13:02 INFO - 'sps_profile': False, 18:13:02 INFO - 'sps_profile_interval': 0, 18:13:02 INFO - 'suite': 'dromaeojs', 18:13:02 INFO - 'system_bits': '32', 18:13:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:13:02 INFO - 'title': 'talos-linux64-ix-056', 18:13:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:13:02 INFO - 'use_talos_json': True, 18:13:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:13:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:13:02 INFO - 'work_dir': 'build'} 18:13:02 INFO - ##### 18:13:02 INFO - ##### Running clobber step. 18:13:02 INFO - ##### 18:13:02 INFO - Running pre-action listener: _resource_record_pre_action 18:13:02 INFO - Running main action method: clobber 18:13:02 INFO - rmtree: /builds/slave/test/build 18:13:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:13:03 INFO - Running post-action listener: _resource_record_post_action 18:13:03 INFO - ##### 18:13:03 INFO - ##### Finished clobber step (success) 18:13:03 INFO - ##### 18:13:03 INFO - ##### 18:13:03 INFO - ##### Running read-buildbot-config step. 18:13:03 INFO - ##### 18:13:03 INFO - Running pre-action listener: _resource_record_pre_action 18:13:03 INFO - Running main action method: read_buildbot_config 18:13:03 INFO - Using buildbot properties: 18:13:03 INFO - { 18:13:03 INFO - "project": "", 18:13:03 INFO - "product": "firefox", 18:13:03 INFO - "script_repo_revision": "production", 18:13:03 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:13:03 INFO - "repository": "", 18:13:03 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:13:03 INFO - "buildid": "20160712165527", 18:13:03 INFO - "pgo_build": "False", 18:13:03 INFO - "basedir": "/builds/slave/test", 18:13:03 INFO - "buildnumber": 1983, 18:13:03 INFO - "slavename": "talos-linux64-ix-056", 18:13:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:13:03 INFO - "platform": "ubuntu64_hw", 18:13:03 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:13:03 INFO - "branch": "fx-team", 18:13:03 INFO - "repo_path": "integration/fx-team", 18:13:03 INFO - "revision": "0d7a788f1c40adf5038505e25e600d8551dbda06", 18:13:03 INFO - "stage_platform": "linux64", 18:13:03 INFO - "builduid": "c6489b32af94416780df9336b930a9f0", 18:13:03 INFO - "slavebuilddir": "test" 18:13:03 INFO - } 18:13:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:13:03 INFO - Running post-action listener: _resource_record_post_action 18:13:03 INFO - ##### 18:13:03 INFO - ##### Finished read-buildbot-config step (success) 18:13:03 INFO - ##### 18:13:03 INFO - ##### 18:13:03 INFO - ##### Running download-and-extract step. 18:13:03 INFO - ##### 18:13:03 INFO - Running pre-action listener: _resource_record_pre_action 18:13:03 INFO - Running main action method: download_and_extract 18:13:03 INFO - mkdir: /builds/slave/test/build/tests 18:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:03 INFO - https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:13:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:13:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:13:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:13:08 INFO - Downloaded 1361 bytes. 18:13:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:13:08 INFO - Using the following test package requirements: 18:13:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:13:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:13:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:13:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'jsshell-linux-x86_64.zip'], 18:13:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:13:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:13:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:13:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:13:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:13:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:13:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:13:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:13:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:08 INFO - https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:13:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:13:16 INFO - Downloaded 22750981 bytes. 18:13:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:13:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:13:18 INFO - Return code: 0 18:13:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:13:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:18 INFO - https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:13:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:13:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:13:26 INFO - Downloaded 22750981 bytes. 18:13:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:13:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:13:28 INFO - Return code: 0 18:13:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:28 INFO - https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:13:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:13:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:13:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:13:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:13:29 INFO - Downloaded 14403317 bytes. 18:13:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:13:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:13:29 INFO - Return code: 0 18:13:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:29 INFO - https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:13:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:13:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:13:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:13:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:13:31 INFO - Downloaded 57016979 bytes. 18:13:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:13:31 INFO - mkdir: /builds/slave/test/properties 18:13:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:13:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:13:31 INFO - Contents: 18:13:31 INFO - build_url:https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:13:32 INFO - Running post-action listener: _resource_record_post_action 18:13:32 INFO - Running post-action listener: set_extra_try_arguments 18:13:32 INFO - ##### 18:13:32 INFO - ##### Finished download-and-extract step (success) 18:13:32 INFO - ##### 18:13:32 INFO - ##### 18:13:32 INFO - ##### Running populate-webroot step. 18:13:32 INFO - ##### 18:13:32 INFO - Running pre-action listener: _resource_record_pre_action 18:13:32 INFO - Running main action method: populate_webroot 18:13:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:13:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:13:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:13:32 INFO - '--tppagecycles', 18:13:32 INFO - '7'], 18:13:32 INFO - 'tests': ['tsvgm']}}, 18:13:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:13:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:13:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:13:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:13:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['damp', 'tps']}, 18:13:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'tests': ['damp', 'tps']}, 18:13:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['dromaeo_dom']}, 18:13:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:13:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['basic_compositor_video']}, 18:13:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:13:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'other-e10s': {'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['a11yr', 18:13:32 INFO - 'ts_paint', 18:13:32 INFO - 'tpaint', 18:13:32 INFO - 'sessionrestore', 18:13:32 INFO - 'sessionrestore_no_auto_restore', 18:13:32 INFO - 'tabpaint']}, 18:13:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['tsvgx', 18:13:32 INFO - 'tsvgr_opacity', 18:13:32 INFO - 'tart', 18:13:32 INFO - 'tscrollx', 18:13:32 INFO - 'cart']}, 18:13:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:13:32 INFO - 'tsvgr_opacity', 18:13:32 INFO - 'tart', 18:13:32 INFO - 'tscrollx', 18:13:32 INFO - 'cart']}, 18:13:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'talos_options': ['--disable-e10s'], 18:13:32 INFO - 'tests': ['tp5o']}, 18:13:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'tests': ['tp5o']}, 18:13:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'talos_options': ['--disable-e10s', 18:13:32 INFO - '--xperf_path', 18:13:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:32 INFO - 'tests': ['tp5n']}, 18:13:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:32 INFO - 'talos_options': ['--xperf_path', 18:13:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:32 INFO - 'tests': ['tp5n']}}, 18:13:32 INFO - 'talos.zip': {'path': '', 18:13:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:13:32 INFO - Running post-action listener: _resource_record_post_action 18:13:32 INFO - ##### 18:13:32 INFO - ##### Finished populate-webroot step (success) 18:13:32 INFO - ##### 18:13:32 INFO - ##### 18:13:32 INFO - ##### Running create-virtualenv step. 18:13:32 INFO - ##### 18:13:32 INFO - Running pre-action listener: _resource_record_pre_action 18:13:32 INFO - Running main action method: create_virtualenv 18:13:32 INFO - Creating virtualenv /builds/slave/test/build/venv 18:13:32 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 18:13:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:13:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:13:32 INFO - Using real prefix '/usr' 18:13:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:13:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:13:35 INFO - Installing pip.................done. 18:13:35 INFO - Return code: 0 18:13:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:13:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:35 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:35 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 18:13:35 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 18:13:35 INFO - Using env: {'DISPLAY': ':0', 18:13:35 INFO - 'HOME': '/home/cltbld', 18:13:35 INFO - 'LANG': 'en_US.UTF-8', 18:13:35 INFO - 'LANGUAGE': 'en_US:en', 18:13:35 INFO - 'LOGNAME': 'cltbld', 18:13:35 INFO - 'MAIL': '/var/mail/cltbld', 18:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:35 INFO - 'MOZ_NO_REMOTE': '1', 18:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:35 INFO - 'NO_EM_RESTART': '1', 18:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:35 INFO - 'PWD': '/builds/slave/test', 18:13:35 INFO - 'SHELL': '/bin/bash', 18:13:35 INFO - 'SHLVL': '1', 18:13:35 INFO - 'TERM': 'linux', 18:13:35 INFO - 'TMOUT': '86400', 18:13:35 INFO - 'USER': 'cltbld', 18:13:35 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:35 INFO - '_': '/tools/buildbot/bin/python'} 18:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:36 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 18:13:36 INFO - Cleaning up... 18:13:36 INFO - Return code: 0 18:13:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:13:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:36 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:36 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 18:13:36 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 18:13:36 INFO - Using env: {'DISPLAY': ':0', 18:13:36 INFO - 'HOME': '/home/cltbld', 18:13:36 INFO - 'LANG': 'en_US.UTF-8', 18:13:36 INFO - 'LANGUAGE': 'en_US:en', 18:13:36 INFO - 'LOGNAME': 'cltbld', 18:13:36 INFO - 'MAIL': '/var/mail/cltbld', 18:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:36 INFO - 'MOZ_NO_REMOTE': '1', 18:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:36 INFO - 'NO_EM_RESTART': '1', 18:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:36 INFO - 'PWD': '/builds/slave/test', 18:13:36 INFO - 'SHELL': '/bin/bash', 18:13:36 INFO - 'SHLVL': '1', 18:13:36 INFO - 'TERM': 'linux', 18:13:36 INFO - 'TMOUT': '86400', 18:13:36 INFO - 'USER': 'cltbld', 18:13:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:36 INFO - '_': '/tools/buildbot/bin/python'} 18:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:36 INFO - Downloading/unpacking psutil>=3.1.1 18:13:36 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 18:13:36 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 18:13:36 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 18:13:36 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 18:13:36 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 18:13:36 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 18:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:13:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:38 INFO - Installing collected packages: psutil 18:13:38 INFO - Running setup.py install for psutil 18:13:38 INFO - building 'psutil._psutil_linux' extension 18:13:38 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 18:13:40 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 18:13:40 INFO - building 'psutil._psutil_posix' extension 18:13:40 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 18:13:40 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 18:13:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:40 INFO - Successfully installed psutil 18:13:40 INFO - Cleaning up... 18:13:41 INFO - Return code: 0 18:13:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:13:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:41 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:41 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 18:13:41 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 18:13:41 INFO - Using env: {'DISPLAY': ':0', 18:13:41 INFO - 'HOME': '/home/cltbld', 18:13:41 INFO - 'LANG': 'en_US.UTF-8', 18:13:41 INFO - 'LANGUAGE': 'en_US:en', 18:13:41 INFO - 'LOGNAME': 'cltbld', 18:13:41 INFO - 'MAIL': '/var/mail/cltbld', 18:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:41 INFO - 'MOZ_NO_REMOTE': '1', 18:13:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:41 INFO - 'NO_EM_RESTART': '1', 18:13:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:41 INFO - 'PWD': '/builds/slave/test', 18:13:41 INFO - 'SHELL': '/bin/bash', 18:13:41 INFO - 'SHLVL': '1', 18:13:41 INFO - 'TERM': 'linux', 18:13:41 INFO - 'TMOUT': '86400', 18:13:41 INFO - 'USER': 'cltbld', 18:13:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:41 INFO - '_': '/tools/buildbot/bin/python'} 18:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:13:41 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 18:13:41 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 18:13:41 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 18:13:41 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 18:13:41 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 18:13:41 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 18:13:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:13:43 INFO - Installing collected packages: mozsystemmonitor 18:13:43 INFO - Running setup.py install for mozsystemmonitor 18:13:43 INFO - Successfully installed mozsystemmonitor 18:13:43 INFO - Cleaning up... 18:13:43 INFO - Return code: 0 18:13:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:13:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:43 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:43 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 18:13:43 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 18:13:43 INFO - Using env: {'DISPLAY': ':0', 18:13:43 INFO - 'HOME': '/home/cltbld', 18:13:43 INFO - 'LANG': 'en_US.UTF-8', 18:13:43 INFO - 'LANGUAGE': 'en_US:en', 18:13:43 INFO - 'LOGNAME': 'cltbld', 18:13:43 INFO - 'MAIL': '/var/mail/cltbld', 18:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:43 INFO - 'MOZ_NO_REMOTE': '1', 18:13:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:43 INFO - 'NO_EM_RESTART': '1', 18:13:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:43 INFO - 'PWD': '/builds/slave/test', 18:13:43 INFO - 'SHELL': '/bin/bash', 18:13:43 INFO - 'SHLVL': '1', 18:13:43 INFO - 'TERM': 'linux', 18:13:43 INFO - 'TMOUT': '86400', 18:13:43 INFO - 'USER': 'cltbld', 18:13:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:43 INFO - '_': '/tools/buildbot/bin/python'} 18:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:43 INFO - Downloading/unpacking jsonschema==2.5.1 18:13:43 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 18:13:43 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 18:13:43 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 18:13:43 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 18:13:43 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 18:13:43 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 18:13:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:13:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:13:45 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 18:13:45 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 18:13:45 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 18:13:45 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 18:13:45 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 18:13:45 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 18:13:45 INFO - Downloading functools32-3.2.3-2.tar.gz 18:13:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:13:45 INFO - warning: no files found matching '*.txt' 18:13:45 INFO - no previously-included directories found matching 'build' 18:13:45 INFO - no previously-included directories found matching 'dist' 18:13:45 INFO - no previously-included directories found matching '.git*' 18:13:45 INFO - Installing collected packages: jsonschema, functools32 18:13:45 INFO - Running setup.py install for functools32 18:13:45 INFO - warning: no files found matching '*.txt' 18:13:45 INFO - no previously-included directories found matching 'build' 18:13:45 INFO - no previously-included directories found matching 'dist' 18:13:45 INFO - no previously-included directories found matching '.git*' 18:13:45 INFO - Successfully installed jsonschema functools32 18:13:45 INFO - Cleaning up... 18:13:45 INFO - Return code: 0 18:13:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:13:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:45 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:45 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 18:13:45 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 18:13:45 INFO - Using env: {'DISPLAY': ':0', 18:13:45 INFO - 'HOME': '/home/cltbld', 18:13:45 INFO - 'LANG': 'en_US.UTF-8', 18:13:45 INFO - 'LANGUAGE': 'en_US:en', 18:13:45 INFO - 'LOGNAME': 'cltbld', 18:13:45 INFO - 'MAIL': '/var/mail/cltbld', 18:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:45 INFO - 'MOZ_NO_REMOTE': '1', 18:13:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:45 INFO - 'NO_EM_RESTART': '1', 18:13:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:46 INFO - 'PWD': '/builds/slave/test', 18:13:46 INFO - 'SHELL': '/bin/bash', 18:13:46 INFO - 'SHLVL': '1', 18:13:46 INFO - 'TERM': 'linux', 18:13:46 INFO - 'TMOUT': '86400', 18:13:46 INFO - 'USER': 'cltbld', 18:13:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:46 INFO - '_': '/tools/buildbot/bin/python'} 18:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:46 INFO - Downloading/unpacking blobuploader==1.2.4 18:13:46 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 18:13:46 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 18:13:46 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 18:13:46 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 18:13:46 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 18:13:46 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 18:13:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:13:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:13:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:48 INFO - Downloading docopt-0.6.1.tar.gz 18:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:13:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:13:48 INFO - Running setup.py install for blobuploader 18:13:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:13:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:13:49 INFO - Running setup.py install for requests 18:13:49 INFO - Running setup.py install for docopt 18:13:49 INFO - Successfully installed blobuploader requests docopt 18:13:49 INFO - Cleaning up... 18:13:49 INFO - Return code: 0 18:13:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:49 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:49 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 18:13:49 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 18:13:49 INFO - Using env: {'DISPLAY': ':0', 18:13:49 INFO - 'HOME': '/home/cltbld', 18:13:49 INFO - 'LANG': 'en_US.UTF-8', 18:13:49 INFO - 'LANGUAGE': 'en_US:en', 18:13:49 INFO - 'LOGNAME': 'cltbld', 18:13:49 INFO - 'MAIL': '/var/mail/cltbld', 18:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:49 INFO - 'MOZ_NO_REMOTE': '1', 18:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:49 INFO - 'NO_EM_RESTART': '1', 18:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:49 INFO - 'PWD': '/builds/slave/test', 18:13:49 INFO - 'SHELL': '/bin/bash', 18:13:49 INFO - 'SHLVL': '1', 18:13:49 INFO - 'TERM': 'linux', 18:13:49 INFO - 'TMOUT': '86400', 18:13:49 INFO - 'USER': 'cltbld', 18:13:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:49 INFO - '_': '/tools/buildbot/bin/python'} 18:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:49 INFO - Running setup.py (path:/tmp/pip-cNLWj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:49 INFO - Running setup.py (path:/tmp/pip-4olA8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:49 INFO - Running setup.py (path:/tmp/pip-ezEbTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:49 INFO - Running setup.py (path:/tmp/pip-PNqUg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:50 INFO - Running setup.py (path:/tmp/pip-dP2UYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:50 INFO - Running setup.py (path:/tmp/pip-bPiAf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:50 INFO - Running setup.py (path:/tmp/pip-rN3Amq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:50 INFO - Running setup.py (path:/tmp/pip-Az8gdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:50 INFO - Running setup.py (path:/tmp/pip-2T34XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:50 INFO - Running setup.py (path:/tmp/pip-PEFl7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:50 INFO - Running setup.py (path:/tmp/pip-8KPGZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:50 INFO - Running setup.py (path:/tmp/pip-lbc0hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:50 INFO - Running setup.py (path:/tmp/pip-R530KQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:50 INFO - Running setup.py (path:/tmp/pip-APt0YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:50 INFO - Running setup.py (path:/tmp/pip-jzGUDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:50 INFO - Running setup.py (path:/tmp/pip-vHTcMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:50 INFO - Running setup.py (path:/tmp/pip-qb2cF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:13:50 INFO - Running setup.py install for manifestparser 18:13:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for mozcrash 18:13:51 INFO - Running setup.py install for mozdebug 18:13:51 INFO - Running setup.py install for mozdevice 18:13:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for mozfile 18:13:51 INFO - Running setup.py install for mozhttpd 18:13:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for mozinfo 18:13:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for mozInstall 18:13:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for mozleak 18:13:51 INFO - Running setup.py install for mozlog 18:13:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:13:51 INFO - Running setup.py install for moznetwork 18:13:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Running setup.py install for mozprocess 18:13:52 INFO - Running setup.py install for mozprofile 18:13:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Running setup.py install for mozrunner 18:13:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Running setup.py install for mozscreenshot 18:13:52 INFO - Running setup.py install for moztest 18:13:52 INFO - Running setup.py install for mozversion 18:13:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:13:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:13:52 INFO - Cleaning up... 18:13:52 INFO - Return code: 0 18:13:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:52 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:52 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 18:13:52 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 18:13:52 INFO - Using env: {'DISPLAY': ':0', 18:13:52 INFO - 'HOME': '/home/cltbld', 18:13:52 INFO - 'LANG': 'en_US.UTF-8', 18:13:52 INFO - 'LANGUAGE': 'en_US:en', 18:13:52 INFO - 'LOGNAME': 'cltbld', 18:13:52 INFO - 'MAIL': '/var/mail/cltbld', 18:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:52 INFO - 'MOZ_NO_REMOTE': '1', 18:13:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:52 INFO - 'NO_EM_RESTART': '1', 18:13:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:52 INFO - 'PWD': '/builds/slave/test', 18:13:52 INFO - 'SHELL': '/bin/bash', 18:13:52 INFO - 'SHLVL': '1', 18:13:52 INFO - 'TERM': 'linux', 18:13:52 INFO - 'TMOUT': '86400', 18:13:52 INFO - 'USER': 'cltbld', 18:13:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:52 INFO - '_': '/tools/buildbot/bin/python'} 18:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:52 INFO - Running setup.py (path:/tmp/pip-xtrsmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:53 INFO - Running setup.py (path:/tmp/pip-rbnZBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:53 INFO - Running setup.py (path:/tmp/pip-_Gqndp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:53 INFO - Running setup.py (path:/tmp/pip-s67iMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:53 INFO - Running setup.py (path:/tmp/pip-H53lLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:53 INFO - Running setup.py (path:/tmp/pip-jCdvAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:53 INFO - Running setup.py (path:/tmp/pip-0LPK4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:53 INFO - Running setup.py (path:/tmp/pip-_KgInO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:53 INFO - Running setup.py (path:/tmp/pip-TdhgQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:53 INFO - Running setup.py (path:/tmp/pip-Z6R5Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:53 INFO - Running setup.py (path:/tmp/pip-FTjI_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:53 INFO - Running setup.py (path:/tmp/pip-yBSz0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:53 INFO - Running setup.py (path:/tmp/pip-XIFz5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:53 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:53 INFO - Running setup.py (path:/tmp/pip-V8Dxo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:53 INFO - Running setup.py (path:/tmp/pip-PNJ9J3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:54 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)) 18:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:54 INFO - Running setup.py (path:/tmp/pip-fXBG0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 18:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:54 INFO - Running setup.py (path:/tmp/pip-dx_INS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:54 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)) 18:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:13:54 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)) 18:13:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:13:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:13:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:56 INFO - Downloading blessings-1.6.tar.gz 18:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:13:56 INFO - Installing collected packages: blessings 18:13:56 INFO - Running setup.py install for blessings 18:13:56 INFO - Successfully installed blessings 18:13:56 INFO - Cleaning up... 18:13:56 INFO - Return code: 0 18:13:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:13:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:56 INFO - Reading from file tmpfile_stdout 18:13:56 INFO - Current package versions: 18:13:56 INFO - argparse == 1.2.1 18:13:56 INFO - blessings == 1.6 18:13:56 INFO - blobuploader == 1.2.4 18:13:56 INFO - docopt == 0.6.1 18:13:56 INFO - functools32 == 3.2.3-2 18:13:56 INFO - jsonschema == 2.5.1 18:13:56 INFO - manifestparser == 1.1 18:13:56 INFO - mozInstall == 1.12 18:13:56 INFO - mozcrash == 0.17 18:13:56 INFO - mozdebug == 0.1 18:13:56 INFO - mozdevice == 0.48 18:13:56 INFO - mozfile == 1.2 18:13:56 INFO - mozhttpd == 0.7 18:13:56 INFO - mozinfo == 0.9 18:13:56 INFO - mozleak == 0.1 18:13:56 INFO - mozlog == 3.2 18:13:56 INFO - moznetwork == 0.27 18:13:56 INFO - mozprocess == 0.23 18:13:56 INFO - mozprofile == 0.28 18:13:56 INFO - mozrunner == 6.12 18:13:56 INFO - mozscreenshot == 0.1 18:13:56 INFO - mozsystemmonitor == 0.3 18:13:56 INFO - moztest == 0.7 18:13:56 INFO - mozversion == 1.4 18:13:56 INFO - psutil == 3.1.1 18:13:56 INFO - requests == 1.2.3 18:13:56 INFO - wsgiref == 0.1.2 18:13:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:56 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 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13:56 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 18:13:56 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 18:13:56 INFO - Using env: {'DISPLAY': ':0', 18:13:56 INFO - 'HOME': '/home/cltbld', 18:13:56 INFO - 'LANG': 'en_US.UTF-8', 18:13:56 INFO - 'LANGUAGE': 'en_US:en', 18:13:56 INFO - 'LOGNAME': 'cltbld', 18:13:56 INFO - 'MAIL': '/var/mail/cltbld', 18:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:56 INFO - 'MOZ_NO_REMOTE': '1', 18:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:56 INFO - 'NO_EM_RESTART': '1', 18:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:56 INFO - 'PWD': '/builds/slave/test', 18:13:56 INFO - 'SHELL': '/bin/bash', 18:13:56 INFO - 'SHLVL': '1', 18:13:56 INFO - 'TERM': 'linux', 18:13:56 INFO - 'TMOUT': '86400', 18:13:56 INFO - 'USER': 'cltbld', 18:13:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:56 INFO - '_': '/tools/buildbot/bin/python'} 18:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 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)) 18:13:56 INFO - Cleaning up... 18:13:56 INFO - Return code: 0 18:13:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x289d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, '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': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', '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 18:13: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', 'jsonschema'] in /builds/slave/test/build 18:13: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 jsonschema 18:13:56 INFO - Using env: {'DISPLAY': ':0', 18:13:56 INFO - 'HOME': '/home/cltbld', 18:13:56 INFO - 'LANG': 'en_US.UTF-8', 18:13:56 INFO - 'LANGUAGE': 'en_US:en', 18:13:56 INFO - 'LOGNAME': 'cltbld', 18:13:56 INFO - 'MAIL': '/var/mail/cltbld', 18:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:56 INFO - 'MOZ_NO_REMOTE': '1', 18:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:56 INFO - 'NO_EM_RESTART': '1', 18:13:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:56 INFO - 'PWD': '/builds/slave/test', 18:13:56 INFO - 'SHELL': '/bin/bash', 18:13:56 INFO - 'SHLVL': '1', 18:13:56 INFO - 'TERM': 'linux', 18:13:56 INFO - 'TMOUT': '86400', 18:13:56 INFO - 'USER': 'cltbld', 18:13:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:56 INFO - '_': '/tools/buildbot/bin/python'} 18:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:13:57 INFO - Cleaning up... 18:13:57 INFO - Return code: 0 18:13:57 INFO - Running post-action listener: _resource_record_post_action 18:13:57 INFO - Running post-action listener: _start_resource_monitoring 18:13:57 INFO - Starting resource monitoring. 18:13:57 INFO - ##### 18:13:57 INFO - ##### Finished create-virtualenv step (success) 18:13:57 INFO - ##### 18:13:57 INFO - ##### 18:13:57 INFO - ##### Running install step. 18:13:57 INFO - ##### 18:13:57 INFO - Running pre-action listener: _resource_record_pre_action 18:13:57 INFO - Running main action method: install 18:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:57 INFO - Reading from file tmpfile_stdout 18:13:57 INFO - Detecting whether we're running mozinstall >=1.0... 18:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:13:57 INFO - Reading from file tmpfile_stdout 18:13:57 INFO - Output received: 18:13:57 INFO - Usage: mozinstall [options] installer 18:13:57 INFO - Options: 18:13:57 INFO - -h, --help show this help message and exit 18:13:57 INFO - -d DEST, --destination=DEST 18:13:57 INFO - Directory to install application into. [default: 18:13:57 INFO - "/builds/slave/test"] 18:13:57 INFO - --app=APP Application being installed. [default: firefox] 18:13:57 INFO - mkdir: /builds/slave/test/build/application 18:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:14:13 INFO - Reading from file tmpfile_stdout 18:14:13 INFO - Output received: 18:14:13 INFO - /builds/slave/test/build/application/firefox/firefox 18:14:13 INFO - Running post-action listener: _resource_record_post_action 18:14:13 INFO - ##### 18:14:13 INFO - ##### Finished install step (success) 18:14:13 INFO - ##### 18:14:13 INFO - ##### 18:14:13 INFO - ##### Running run-tests step. 18:14:13 INFO - ##### 18:14:13 INFO - Running pre-action listener: _resource_record_pre_action 18:14:13 INFO - Running main action method: run_tests 18:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:14:13 INFO - Python 2.7.3 18:14:13 INFO - Return code: 0 18:14:13 INFO - grabbing minidump binary from tooltool 18:14:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:13 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 0x289b3b0>, '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 0x289c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x289c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:14:13 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 18:14:13 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 18:14:13 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 18:14:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:14:14 INFO - Return code: 0 18:14:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:14:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:14:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:14:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:14:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 18:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 18:14:14 INFO - Using env: {'DISPLAY': ':0', 18:14:14 INFO - 'HOME': '/home/cltbld', 18:14:14 INFO - 'LANG': 'en_US.UTF-8', 18:14:14 INFO - 'LANGUAGE': 'en_US:en', 18:14:14 INFO - 'LOGNAME': 'cltbld', 18:14:14 INFO - 'MAIL': '/var/mail/cltbld', 18:14:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:14 INFO - 'MOZ_NO_REMOTE': '1', 18:14:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:14:14 INFO - 'NO_EM_RESTART': '1', 18:14:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:14 INFO - 'PWD': '/builds/slave/test', 18:14:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:14 INFO - 'SHELL': '/bin/bash', 18:14:14 INFO - 'SHLVL': '1', 18:14:14 INFO - 'TERM': 'linux', 18:14:14 INFO - 'TMOUT': '86400', 18:14:14 INFO - 'USER': 'cltbld', 18:14:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149', 18:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:14 INFO - '_': '/tools/buildbot/bin/python'} 18:14:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 18:14:14 INFO - mozversion application_buildid: 20160712165527 18:14:14 INFO - mozversion application_changeset: 0d7a788f1c40adf5038505e25e600d8551dbda06 18:14:14 INFO - mozversion application_display_name: Nightly 18:14:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:14:14 INFO - mozversion application_name: Firefox 18:14:14 INFO - mozversion application_remotingname: firefox 18:14:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:14:14 INFO - mozversion application_vendor: Mozilla 18:14:14 INFO - mozversion application_version: 50.0a1 18:14:14 INFO - mozversion platform_buildid: 20160712165527 18:14:14 INFO - mozversion platform_changeset: 0d7a788f1c40adf5038505e25e600d8551dbda06 18:14:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:14:14 INFO - mozversion platform_version: 50.0a1 18:14:14 INFO - using testdate: 1468372454 18:14:14 INFO - actual date: 1468372454 18:14:14 INFO - starting webserver on 'localhost:45942' 18:14:14 INFO - SUITE-START | Running 2 tests 18:14:14 INFO - TEST-START | dromaeo_css 18:14:14 INFO - operating with platform_type : linux_ 18:14:14 INFO - Initialising browser for dromaeo_css test... 18:14:14 INFO - TEST-INFO | started process 3291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNbX64z/profile http://localhost:45942/getInfo.html) 18:14:21 INFO - PROCESS | 3291 | __metrics Screen width/height:1600/1200 18:14:21 INFO - PROCESS | 3291 | colorDepth:24 18:14:21 INFO - PROCESS | 3291 | Browser inner width/height: 1024/697 18:14:21 INFO - PROCESS | 3291 | __metrics 18:14:22 INFO - PROCESS | 3291 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:14:22 INFO - PROCESS | 3291 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:14:23 INFO - PROCESS | 3291 | console.error: PushService: 18:14:23 INFO - PROCESS | 3291 | stateChangeProcessEnqueue: Error transitioning state 18:14:23 INFO - PROCESS | 3291 | UnknownError 18:14:23 INFO - TEST-INFO | 3291: exit 0 18:14:23 INFO - Browser initialized. 18:14:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:14:23 INFO - TEST-INFO | started process 3374 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNbX64z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:14:35 INFO - PROCESS | 3374 | RSS: Main: 159100928 18:14:35 INFO - PROCESS | 3374 | 18:15:27 INFO - PROCESS | 3374 | 1468372527517 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:17:40 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45942/tests/dromaeo/cssquery-ext.html) 18:17:41 INFO - PROCESS | 3374 | RSS: Main: 259645440 18:17:41 INFO - PROCESS | 3374 | 18:20:43 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-ext.html (next: http://localhost:45942/tests/dromaeo/cssquery-jquery.html) 18:20:44 INFO - PROCESS | 3374 | RSS: Main: 247209984 18:20:44 INFO - PROCESS | 3374 | 18:23:46 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45942/tests/dromaeo/cssquery-mootools.html) 18:23:47 INFO - PROCESS | 3374 | RSS: Main: 250335232 18:23:47 INFO - PROCESS | 3374 | 18:26:49 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45942/tests/dromaeo/cssquery-prototype.html) 18:26:50 INFO - PROCESS | 3374 | RSS: Main: 243191808 18:26:50 INFO - PROCESS | 3374 | 18:29:53 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45942/tests/dromaeo/cssquery-yui.html) 18:29:54 INFO - PROCESS | 3374 | RSS: Main: 220291072 18:29:54 INFO - PROCESS | 3374 | 18:32:56 INFO - PROCESS | 3374 | Cycle 1(1): loaded http://localhost:45942/tests/dromaeo/cssquery-yui.html (next: http://localhost:45942/tests/dromaeo/cssquery-dojo.html) 18:32:57 INFO - PROCESS | 3374 | RSS: Main: 282873856 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | __start_tp_report 18:32:57 INFO - PROCESS | 3374 | _x_x_mozilla_page_load 18:32:57 INFO - PROCESS | 3374 | _x_x_mozilla_page_load_details 18:32:57 INFO - PROCESS | 3374 | |i|pagename|runs| 18:32:57 INFO - PROCESS | 3374 | |0;dojo.html;2549.2861998640365;2589.98705006475;2624.435746314542;2634.2887420396487;2638.300850274676;4494.528074552174;4568.5888270055775;4571.2228921083415;4599.4940556538795;4601.6548758843055;4552.29439436887;4616.884577885555;4653.557911998355;4692.718436893789;4723.787429565669;4176.331786914095;4182.937255941163;4186.916261674764;4271.380649805777;4277.422547956017;4238.385434112052;4257.957420425787;4331.30699088146;4337.067530480946;4360.890977725567;4139.627433530996;4170.103427763045;4251.171021650779;4267.701260911737;4295.183915056129;4232.978835105805;4241.512226094008;4242;4242.703010789246;4246.171996460692;4200.306949353353;4210.221109094828;4224.366345048236;4231.936520952188;4235.343521754133;4513.864584062483;4541.886452838672;4547.749873756942;4582.725036497787;4620.561046700558;4838.032393521275;4867.367242258484;4870.512948705101;4886.267059941002;4922.163232250526;4794.640401969831;4897.902041959141;4949.554540091409;4952.777125029383;4961.404631444214;4584.679072464895;4585.014221942299;4641.419822522184;4659.18464268755;4702.435707715028;4998.600111991104;5020.322256495399;5063.1645778446145;5069.898602027939;5073.34046573943;4623.144718227139;4729.077829823152;4837.806487740521;4860.246661767432;4862;4958.281049247911;4976.253561965734;4984.651074424755;5009.924851127236;5012.949870501321;4886.747996234063;4907.116718990616;4910.263460480885;4914.262860570943;4927.211646136601;4711.882202944886;4727.905441891143;4910.288008238786;4918.2376731606655;4943.159662857251;4099.7745124017865;4101.323281658493;4107.075907920694;4108.568600296986;4112.485939257593;3622.2936527377437;3633.182533929845;3648.087978005499;3648.543932008499;3653.598104208535;4341.34879768031;4349.956500435019;4354.281543545279;4357.302831546937;4398.538153493901;4922.827701030446;4980.576650984634;4985.277134815431;4998.175301075355;5025.447200807908;4172.5410204877435;4304.978475107604;4332.545082766327;4339.349097635317;4358.8910277242685;4457.799399026987;4481.865544033684;4488.5960263576435;4498.347739577743;4507.256302710081;4761.452432970253;4764.618830493552;4764.952350476589;4765.69023013503;4811.422629284578;4775.808967641395;4896.583790377857;4922.704637721748;4939.308496810521;5001.174806157089;4678.157931572349;4763.833265835679;4768.403949506312;4769.189237829508;4817.566419022165;4736;4887.095887260868;4895.922896962663;4896.632752543503;4950.77721502526;4887.60899128076;4951.9752401237765;4960.156773348467;4973.626977976739;4979;4810.518948105301;4855.957226079414;4856.053069651525;4859.659823812299;4897.461279259207;4701.059788042337;4724.71651700899;4845.030993801324;4867.415910090812;4909.582685471775;4690.413698287714;4701.177293973609;4752.073345697556;4767.189577771856;4767.998720268783;4809.062232864558;4813.350197723263;4823.589994850477;4834;4862.881537246382;4422.00504886398;4630.212863813093;4640.419947506562;4704.129735998851;4710.105080034782 18:32:57 INFO - PROCESS | 3374 | |1;ext.html;572.1360745274634;576.1214148423653;580.5442727458945;584.5294537896993;589.3340525206517;27597.999999999975;39757.80121099394;41130.17739645209;41130.794346028226;44901.77549112258;41575.58424415755;41809;42074;42125.57874421255;42156;7724.266194711521;7875.251851074138;7878.763637090897;7879.330256928168;7896.486728362666;7711.652975616083;7761.650725717328;7775.611219439033;7809.140994490602;7812.843743125134;7773.455858089936;7826.1391246962785;7839.02012248469;7850.764477065697;7869.370450363957;7791.961040194791;7826.413019023539;7841.176676449005;7843.176466470995;7850.568218747967;7801.102873169544;7865.528068315919;7868.606569671493;7869.685212591489;7872.055353357577;38860;39187.21625567501;39251.8037409814;39267;39279.214415711525;3191.872325106993;3222.7582931280012;3563.82180890957;3716.9628303716886;3792.1657235408165;29816.254593635334;30370.69629303723;30816.8459157705;31968.840155799306;32726.509102363485;3518.286816643302;3537.2218112015316;3543.8936831894825;3566.982165089184;3592.6186396188486;261195;265773;265845;266150.6692466545;266853;55384;55437;55480.16779748307;55629;56007.43992560104;33397.499037514455;34642.30715385678;58585.70707146437;58659.70670146622;58720.7063964686;15173.393064277305;15216.239188040554;15419.383224670888;15541.145237012073;15614.219289035504;11199.496022678999;11236.719082023015;11337.546498140147;11388.487518061542;11473.942630286796;18547.72178417325;18646.160922758507;18675.346362877233;18684;18744.25022999065;6724.495662825308;6897.41371983377;6926.757563485254;6956.304369563003;7039.964800175966;774.9690012399553;777.1140899374717;833.112734937302;835.3734698975768;855.9358048146414;26997.460050799265;27006.729932700815;27515.449691006066;27834.582481262798;29014.99517815834;963.205355581648;1004.3829434610418;1029.2177944761886;1030.7371620236793;1095.9780804383868;14745.336459859333;15384.692306153815;15442.536723898302;15593.532194034196;15658.138802365978;368584;369148;369782;370210;371730;51201;51566;51842.740786296585;51886.22170667443;51924.74037629863;1044.6865940218056;1134.489479734123;1163.203205804027;1189.8869607387285;1195.6472840511897;29270.121896343142;30164.54753178748;30312;30338.696613033586;30511.507034159484;43037.35443968406;43470.78264608657;43655.78172109246;43665.56334436615;43679.56320436755;307853;315139;316048;317646;318791;32332.515012274365;37689.43465847959;39150.6084939147;39640.40539391969;42725.35911961261;42592.361114583924;42978.35532467078;43637.56362436335;43725.56274437342;44110.11779764451;36222.378270158944;37162.442563362114;37553.24893502169;38053.61946380501;38246.42630360491;37116.25767484689;37619.811900941415;37903.62096379001;37936.430953535164;38243.808780955915;34863.30273394467;36162.8191859039;36293;36599.45100823543;37101.814490927376;32197.35605287928;33148.502772458916;33278.66721332756;33952.320953581286;33993.490097648064;24877.751222487543;25688.743112569384;25766.742332576436;25928;25978.480430391664 18:32:57 INFO - PROCESS | 3374 | |2;jquery.html;345.5576861617131;370.1782043862625;376.35079487883405;377.22668529514505;391.3952942703523;12902.80645790314;16226.675466490678;16774.41289554864;18418.631627367446;18457.63084738308;17012.48962531126;17496.912515437438;17765.55586110348;17778.199981000853;17787.199576019077;5135.666181698187;5182.844514664566;5314.734263286839;5326.92009619856;5341.4658534146665;3755.3428150073764;5266.947330526684;5702.4012478689765;6032.27612686476;6055.6063855849225;6066.363031881633;6078.939210607904;6089.756409743605;6117.113018612298;6139.508839292846;5162.406323272815;5503.3120859892515;5512.062949298629;5515.25544051556;5520.944790552104;5291.656042357235;5345.1447768356875;5348.786048558053;5375.838724838261;5378.650387724785;12957.09300348976;12977;12977.41601627929;18962.336318228932;21093.683594745937;1957.862949593529;1976.4070778766456;2022.6055919095782;2125.7236559247353;2431.1734010436357;9682.612695492171;9891.8516222256;13385.66535836612;13522.661933451644;14038.368273427619;1626.723457012299;1736.8610511159077;1749.9912500437417;1763.3563749231566;1764.7352897065414;68460.26158953636;72730.90903636503;73149;73360;74189;8008.39937004727;9389.62441502346;10066.14437772783;10203.846942295871;10477.895221047844;13656.795148072786;14119.576412707635;14163.78754318686;14228.644283892985;14254.501092461922;7183.49715519919;7239.058922340168;7250.673719682627;7257.478996577174;7386.224446433154;6517.51118666102;7263.201047884729;7318.524295920748;7323.340899319087;7483.4761566690895;4431.180231657154;4440.555944405533;4496.21316269658;4519.7740112994215;4576.931346029813;1827.479168436989;1839.8988055657067;1877.4555378940227;1879.6804543227684;1922.6731455652573;814.861473549498;826.2150956591142;875.8395126457469;895.0154829687422;932.2588542109039;16565.834341656428;16609.916950415172;16857.915710421614;17574.736378954327;18099.095045247686;874.8337815814975;882.2236431939851;902.7607683963755;928.679605536089;945.6264775413811;10036.046575575308;10265.640702575374;10291.639792607222;10294.794104118026;10362.689119326433;398805;407839;410299;410749;411228;83241.58379208065;84960;85992.14007859842;86447;86867;938.8685584018243;942.9245660347293;960.0927123867878;961.0725649748026;967.6855022117699;15861.841381586037;16336.836631633532;16411.58971025734;16451.91774041122;16620.916895415445;38552.614473854905;39530;40001;40228.7988560067;41174.79412602918;55214.72392638011;55828.44171558395;56982.430175699374;57034.7148264256;57271.42728572661;7049.647517623996;7379.8155046124275;7556.58438785872;7589.924100759143;7828.686852525835;10687.839682404614;10946.233763636468;11491.94254028758;11703.063754899418;11829.467673954521;9446.716598501782;9501.287403444907;10076;10128;10226.539805708611;9639.662611808553;9728.12446879756;9888.208943284664;10044.899551004688;10061.547230374797;9800.85298720534;10025.598976040877;10277.74305642365;10388.792224155324;10523.158147348347;7175;7303.014093097364;7352.632368381666;7356.301151390823;7459.888101678588;9383.577739001897;9605.47169905662;9981.500924953869;10178.541965611714;10468 18:32:57 INFO - PROCESS | 3374 | |3;mootools.html;1126.6225814352192;1163.9883601163986;1238.616029031;1290.4193113099104;1392.7284179584979;6344.714487848047;6805.455563554916;7034.577925324485;7076.752313669022;7080.9645951770235;6864.588124712508;7125.964370178155;7154.0342053822715;7296.598687072209;7354.742584009561;1870.69133592957;2095.7170781944396;2128.968065479019;2142.089611914938;2179.226374637005;2210.6462965925457;2217.1353172262748;2218.678291647718;2223.943626777284;2224.2104053061094;2110.3668899330214;2151.139544182332;2175.423512769117;2177.7495588007423;2183.4432219783935;1814.764080669511;1882.8117188281199;1882.9925989595638;2037.113855472874;2080.552681173547;2112.2501511963337;2143.142742902836;2144.2709478777133;2145.227718021511;2145.313499680103;7152.928470715278;7186.065811444479;7188.604626745527;7190.137183537957;7191.604461754601;4328.567143285678;4476.462824461053;4477.3731677565165;4481.193385190664;4484.663650226246;2741.2872653110135;2795.888164473419;2836.957445638317;2839.318563544755;2856.7286107819723;857.4512312120248;880.4629176202511;889.8220355928776;903.46695449685;908.8364094462994;9703.272254580865;9927.205823534172;9928.354656947347;9947.1544918682;9950;3896.649301562873;3973.4834471518516;4124.711270211056;4134.834606615702;4136.607022332873;8380.371472139614;8430.662773489114;8437.240648341556;8447.324214062983;8453.196946290092;4521.547845215452;4534.727916325031;4543.227651299287;4547.704399214107;4548.7725613719185;3613.765105268149;3922.2155568886337;4015.5382131054716;4024.9597504024587;4087.1621317629815;3565.826414491105;3609.6751292383287;3651.1602331463914;3661.0664280608808;3719.367707489733;1040.6565833275001;1122.085500317239;1148.26511032939;1167.5738355500182;1183.4319526627266;735.5549892315179;777.1373775109623;836.3977935886152;842.6966292134827;850.2815121222557;4872.561469467765;4880.414350277918;5126.307948426989;5146.639735218499;5298.81454149103;826.574314228173;864.3776480933718;881.6649673124172;882.9028806831243;888.6356593796512;3342.782719123249;3513.3675938330834;3524.577050753926;3537.398642230827;3607.0441333046765;8861.822763544693;9152.679656212;9229.077092290852;9251.167394934353;9267.582958766872;9161.862572061424;9173.862392064124;9192.21866141372;9193.080691930887;9193.218576421079;6954;6961.79114626562;7016.596680663888;7079.115110611174;7085.468589855782;5002.299678045149;5015.724135172601;5066.746662666926;5176.275321454924;5227.71920879387;8011.319037881845;8379.203975622306;8379.413441059067;8391.496510209407;8392.87410688828;8412.696032115033;8442.831143377221;8453.746387608382;8575.785605359666;8599.14008599135;6029.095635654687;6123.4488895999575;6131.110988906673;6179.073139029181;6221.906671399842;7133.357997780225;7177.354038136593;7185.317394847481;7188.388986936169;7202.279772022755;6519.478441724746;6642;6659.966700166468;6666.666666666744;6796.184457865088;6468.514861385321;6520.152380190545;6525.3800888915475;6613.570117942319;6695.263521012587;6026;6033.577649564489;6074.574779765374;6080.087986802015;6092.360302168208;4533.886652833706;4597.7241365518175;4603.263477843529;4644.674872758875;4689.249720044776;4568.70303430276;4601.562851529099;4671.579557839811;4681.6722829401615;4712.151812673615 18:32:57 INFO - PROCESS | 3374 | |4;prototype.html;295.2647906712286;297.2776153945911;302.9636443626765;306.5539639824056;306.60601127551104;4517.683762136652;4675.8363457279;4684.227102528083;4699.154152252593;4765.142274390609;4465.106978604276;4621.74580398078;4625.144348295567;4631.7915693793775;4654.138984287901;3704.259148170363;3707.5921648618632;3710.703143748494;3725.143217060078;3732.4401339798974;3530.717542596599;3573.446115852034;3696.796676182809;3715.8699445519414;3724.664780169784;3658.213484100918;3678.2459595782934;3695.445683147536;3696.2977034363385;3699.9630003699885;3672.394054980925;3682.797446140461;3696.759710618828;3696.963030369716;3709.2210635766523;3299.967000330014;3301.943378119003;3306.5205545196054;3320.236345640492;3321.1199032256472;4345.69580129391;4519.0058187198765;4535.229011068126;4581.908361832778;4643.3731446255;4098.856540021085;4102.979485102555;4118.897027574335;4122.37231236513;4122.732022418563;4804.063207674505;4822.228443449067;4824.662273640846;4832.323474713543;4835.564799168092;3324.6535153262967;3493.2314890724;3493.318802833423;3495.5455790747296;3519.7008254298416;5065.721385323805;5105.463926287724;5118.283440318359;5118.769655365518;5144.279800827887;3591.012471570339;3592.1199306169633;3600.6579374959338;3610.494530765695;3629.8366573504254;3681.153334732973;3682.2635472905527;3683.778973261613;3687.0966613179944;3694.0606181754792;3933.9803300983885;4125.546189919107;4141.446957390978;4146.69858228686;4183.372494125844;4596.517365676623;4861;4885.438174609894;4886.291487734258;4896.926546101811;1209.38925842449;1247.2952781678323;1262.3309645887693;1267.182667179664;1269.8095285707034;805.2792750488397;817.0277369929764;830.4519017448455;836.60679480644;839.299185180369;3958.3572817280506;3972.3048466518244;3975.264576053439;3984.3703155916955;3993.72043956926;4447.777611119496;4451;4451.287793952952;4456.242438785414;4471.731696098244;3965.2862484753023;3968.333300014001;3972.5698748450536;3999.200159968018;4009.91980160401;2341.8360714750143;2382.213869423086;2414.082648593523;2435.695538057743;2463.4334103156375;4290.914181716411;4369.213541562549;4374.934375984363;4375.212461756914;4402.801873915681;4576.931346029813;4583.197940360424;4640.628749700016;4644.3265726470145;4644.419447569054;4320.027993701455;4325.399602147226;4357;4526.321051842184;4953.578945789648;5080.568151706999;5103.209002604603;5115.667481613683;5132.640715149903;5136.30659860914;5014.420457555858;5020.673656212334;5036.269740887624;5052.267421223975;5062.443466049622;4708.623310135113;4858.514148585114;4871.171900776805;4940.60475161979;4946;3595.2742683511538;3605.963940360563;3610.187707765837;3625.329314076904;3627.492151098901;4889.364382630235;5103.336566246513;5111.8466446005195;5129.358830146231;5153.845384638467;4832.540908613676;4935.728534930613;4938.75306234694;4944.307796908363;4949;4831;4893.825481884251;4910.961950790382;4916.360873086619;4972.129877271391;4610.976945115253;4858.542437268735;4871.95128048715;4875.341828853201;4930.704157750546;4932.975335123301;4964.280179374043;4966.553010229097;4967.925481117858;4969.254611808257;4835.903281934271;4853.174960256694;4855.295982082507;4889.119958407378;4913.606911447004 18:32:57 INFO - PROCESS | 3374 | |5;yui.html;677.7322957431815;697.9197392299884;699.7236091743762;724.7644515532453;762.3320611255317;10156.2890597658;11795.351255680935;12578.119531632788;12655.114142010063;13220.338983050857;13766.793498097511;14411.207383593897;14568.490102846403;14580.270986450689;14588.416463341453;622.987540249197;764.1174443517746;765.7549584133079;788.8619491588978;789.9842003159933;797.2227078598378;802.995985020074;804.493169303338;805.2028491793142;805.2591615713558;838.1116017021976;847.3729440214258;849.4478588917223;849.6261644876265;849.7280870121564;839.4207996482469;840.315143158324;840.504102579481;841.3563623827777;841.8484672758901;611.703323887914;612.8498517863152;614.5145335185198;615.0313256620796;615.5721773367511;11907.702307442296;11969.162158648898;11973.221740586834;11980.940095299467;12013.519459221621;13202.933985330108;13705.657358566015;13754.587362379145;13881.722365552632;13924.721505569933;10503.474826258733;10550.366977981268;10634.36193828365;10704.661917260342;10753.78492430147;3364.680355366236;3368.4610462326154;3373.274745929637;3401.540791993074;3445.9483107753404;55844;61814;69479.65260173718;70429;70550;10692.786144277072;10767.385255571102;10931.945340273407;11130.666080017612;11132.276402033842;20692.172313107607;20736.06687699057;20773.792262077186;20776.68834967477;20801.687974680393;6530.542861999615;6745.696443659949;6752.831179220559;6768.9661551691925;6893.724251030003;3606.5672119345845;3627.5863439055306;3683.226522430266;3731.384321586962;3732.234891847165;2885;2979.1956171833485;2979.8361090139815;2988.775841811873;2994.431058098954;494.3721473728345;496.85839535732146;499.4506043352283;500.30276258450084;505.70921720010404;544.6160456877892;566.7392999220405;569.3367227180289;651.2315467748008;654.4142992022114;8272.586370681442;8310.418270721113;8709.91290087091;8942.910570894208;8945.552722363856;580.0215891397703;596.8686888884439;626.2215308523814;629.5404354821006;646.3956200952031;3743.5694895086867;3780.243951209769;3824.330742120118;3827.8086095695103;3876.3022655922205;58687.70656146777;91304.19737707087;97221.02778972262;108555;108649.4567527173;94460.52769736107;96877.5156124229;96882;97170;97307.51346243224;49000.509994900305;77376.61311693405;82029.58985205035;82529.58735206406;84024;7316.195218526064;7430.219826918095;7479.214682458263;7499.315665049147;7548.131964824115;46074.53925460702;46986.06027879355;47028.29457558208;47353.52646473491;48547.27179092387;59417.70291148689;67378.3262167372;67635.66182169058;68736;69287;10203.38779673222;10551.683449496528;10686.679399618024;10748.838767418338;10779.622713205;11528.538858445569;11580.536778528765;11736.591609006633;11770.88229117732;11887;10668.98644628759;10737.785244295226;10933.617323393642;11098.445077745919;11423.714407139556;10626.71525621995;10750.24748267645;10889.346639201673;11075.778484430428;11438.370889601152;10842.837357439486;11033.598026980151;11146.944265278622;11179.66461006171;11443.14176436754;8657.740267791976;8853.955730221307;9182.67860624875;9206.309526785384;9395.577199026196;8563.657453702031;8578.91421085781;8699.391042626956;8723.432976856484;8959.283257339266 18:32:57 INFO - PROCESS | 3374 | __end_tp_report 18:32:57 INFO - PROCESS | 3374 | __start_cc_report 18:32:57 INFO - PROCESS | 3374 | _x_x_mozilla_cycle_collect,984 18:32:57 INFO - PROCESS | 3374 | __end_cc_report 18:32:57 INFO - PROCESS | 3374 | __startTimestamp1468373577043__endTimestamp 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | ------- Summary: start ------- 18:32:57 INFO - PROCESS | 3374 | Number of tests: 6 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#0] dojo.html Cycles:165 Average:4562.95 Median:4704.13 stddev:473.96 (10.1%) stddev-sans-first:448.31 18:32:57 INFO - PROCESS | 3374 | Values: 2549.3 2590.0 2624.4 2634.3 2638.3 4494.5 4568.6 4571.2 4599.5 4601.7 4552.3 4616.9 4653.6 4692.7 4723.8 4176.3 4182.9 4186.9 4271.4 4277.4 4238.4 4258.0 4331.3 4337.1 4360.9 4139.6 4170.1 4251.2 4267.7 4295.2 4233.0 4241.5 4242.0 4242.7 4246.2 4200.3 4210.2 4224.4 4231.9 4235.3 4513.9 4541.9 4547.7 4582.7 4620.6 4838.0 4867.4 4870.5 4886.3 4922.2 4794.6 4897.9 4949.6 4952.8 4961.4 4584.7 4585.0 4641.4 4659.2 4702.4 4998.6 5020.3 5063.2 5069.9 5073.3 4623.1 4729.1 4837.8 4860.2 4862.0 4958.3 4976.3 4984.7 5009.9 5012.9 4886.7 4907.1 4910.3 4914.3 4927.2 4711.9 4727.9 4910.3 4918.2 4943.2 4099.8 4101.3 4107.1 4108.6 4112.5 3622.3 3633.2 3648.1 3648.5 3653.6 4341.3 4350.0 4354.3 4357.3 4398.5 4922.8 4980.6 4985.3 4998.2 5025.4 4172.5 4305.0 4332.5 4339.3 4358.9 4457.8 4481.9 4488.6 4498.3 4507.3 4761.5 4764.6 4765.0 4765.7 4811.4 4775.8 4896.6 4922.7 4939.3 5001.2 4678.2 4763.8 4768.4 4769.2 4817.6 4736.0 4887.1 4895.9 4896.6 4950.8 4887.6 4952.0 4960.2 4973.6 4979.0 4810.5 4856.0 4856.1 4859.7 4897.5 4701.1 4724.7 4845.0 4867.4 4909.6 4690.4 4701.2 4752.1 4767.2 4768.0 4809.1 4813.4 4823.6 4834.0 4862.9 4422.0 4630.2 4640.4 4704.1 4710.1 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#1] ext.html Cycles:180 Average:48005.11 Median:37586.53 stddev:83857.21 (223.1%) stddev-sans-first:84016.83 18:32:57 INFO - PROCESS | 3374 | Values: 572.1 576.1 580.5 584.5 589.3 27598.0 39757.8 41130.2 41130.8 44901.8 41575.6 41809.0 42074.0 42125.6 42156.0 7724.3 7875.3 7878.8 7879.3 7896.5 7711.7 7761.7 7775.6 7809.1 7812.8 7773.5 7826.1 7839.0 7850.8 7869.4 7792.0 7826.4 7841.2 7843.2 7850.6 7801.1 7865.5 7868.6 7869.7 7872.1 38860.0 39187.2 39251.8 39267.0 39279.2 3191.9 3222.8 3563.8 3717.0 3792.2 29816.3 30370.7 30816.8 31968.8 32726.5 3518.3 3537.2 3543.9 3567.0 3592.6 261195.0 265773.0 265845.0 266150.7 266853.0 55384.0 55437.0 55480.2 55629.0 56007.4 33397.5 34642.3 58585.7 58659.7 58720.7 15173.4 15216.2 15419.4 15541.1 15614.2 11199.5 11236.7 11337.5 11388.5 11473.9 18547.7 18646.2 18675.3 18684.0 18744.3 6724.5 6897.4 6926.8 6956.3 7040.0 775.0 777.1 833.1 835.4 855.9 26997.5 27006.7 27515.4 27834.6 29015.0 963.2 1004.4 1029.2 1030.7 1096.0 14745.3 15384.7 15442.5 15593.5 15658.1 368584.0 369148.0 369782.0 370210.0 371730.0 51201.0 51566.0 51842.7 51886.2 51924.7 1044.7 1134.5 1163.2 1189.9 1195.6 29270.1 30164.5 30312.0 30338.7 30511.5 43037.4 43470.8 43655.8 43665.6 43679.6 307853.0 315139.0 316048.0 317646.0 318791.0 32332.5 37689.4 39150.6 39640.4 42725.4 42592.4 42978.4 43637.6 43725.6 44110.1 36222.4 37162.4 37553.2 38053.6 38246.4 37116.3 37619.8 37903.6 37936.4 38243.8 34863.3 36162.8 36293.0 36599.5 37101.8 32197.4 33148.5 33278.7 33952.3 33993.5 24877.8 25688.7 25766.7 25928.0 25978.5 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#2] jquery.html Cycles:180 Average:25354.44 Median:4507.99 stddev:67556.60 (1498.6%) stddev-sans-first:67720.02 18:32:57 INFO - PROCESS | 3374 | Values: 345.6 370.2 376.4 377.2 391.4 12902.8 16226.7 16774.4 18418.6 18457.6 17012.5 17496.9 17765.6 17778.2 17787.2 5135.7 5182.8 5314.7 5326.9 5341.5 3755.3 5266.9 5702.4 6032.3 6055.6 6066.4 6078.9 6089.8 6117.1 6139.5 5162.4 5503.3 5512.1 5515.3 5520.9 5291.7 5345.1 5348.8 5375.8 5378.7 12957.1 12977.0 12977.4 18962.3 21093.7 1957.9 1976.4 2022.6 2125.7 2431.2 9682.6 9891.9 13385.7 13522.7 14038.4 1626.7 1736.9 1750.0 1763.4 1764.7 68460.3 72730.9 73149.0 73360.0 74189.0 8008.4 9389.6 10066.1 10203.8 10477.9 13656.8 14119.6 14163.8 14228.6 14254.5 7183.5 7239.1 7250.7 7257.5 7386.2 6517.5 7263.2 7318.5 7323.3 7483.5 4431.2 4440.6 4496.2 4519.8 4576.9 1827.5 1839.9 1877.5 1879.7 1922.7 814.9 826.2 875.8 895.0 932.3 16565.8 16609.9 16857.9 17574.7 18099.1 874.8 882.2 902.8 928.7 945.6 10036.0 10265.6 10291.6 10294.8 10362.7 398805.0 407839.0 410299.0 410749.0 411228.0 83241.6 84960.0 85992.1 86447.0 86867.0 938.9 942.9 960.1 961.1 967.7 15861.8 16336.8 16411.6 16451.9 16620.9 38552.6 39530.0 40001.0 40228.8 41174.8 55214.7 55828.4 56982.4 57034.7 57271.4 7049.6 7379.8 7556.6 7589.9 7828.7 10687.8 10946.2 11491.9 11703.1 11829.5 9446.7 9501.3 10076.0 10128.0 10226.5 9639.7 9728.1 9888.2 10044.9 10061.5 9800.9 10025.6 10277.7 10388.8 10523.2 7175.0 7303.0 7352.6 7356.3 7459.9 9383.6 9605.5 9981.5 10178.5 10468.0 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#3] mootools.html Cycles:180 Average:4892.46 Median:6027.55 stddev:2679.60 (44.5%) stddev-sans-first:2672.16 18:32:57 INFO - PROCESS | 3374 | Values: 1126.6 1164.0 1238.6 1290.4 1392.7 6344.7 6805.5 7034.6 7076.8 7081.0 6864.6 7126.0 7154.0 7296.6 7354.7 1870.7 2095.7 2129.0 2142.1 2179.2 2210.6 2217.1 2218.7 2223.9 2224.2 2110.4 2151.1 2175.4 2177.7 2183.4 1814.8 1882.8 1883.0 2037.1 2080.6 2112.3 2143.1 2144.3 2145.2 2145.3 7152.9 7186.1 7188.6 7190.1 7191.6 4328.6 4476.5 4477.4 4481.2 4484.7 2741.3 2795.9 2837.0 2839.3 2856.7 857.5 880.5 889.8 903.5 908.8 9703.3 9927.2 9928.4 9947.2 9950.0 3896.6 3973.5 4124.7 4134.8 4136.6 8380.4 8430.7 8437.2 8447.3 8453.2 4521.5 4534.7 4543.2 4547.7 4548.8 3613.8 3922.2 4015.5 4025.0 4087.2 3565.8 3609.7 3651.2 3661.1 3719.4 1040.7 1122.1 1148.3 1167.6 1183.4 735.6 777.1 836.4 842.7 850.3 4872.6 4880.4 5126.3 5146.6 5298.8 826.6 864.4 881.7 882.9 888.6 3342.8 3513.4 3524.6 3537.4 3607.0 8861.8 9152.7 9229.1 9251.2 9267.6 9161.9 9173.9 9192.2 9193.1 9193.2 6954.0 6961.8 7016.6 7079.1 7085.5 5002.3 5015.7 5066.7 5176.3 5227.7 8011.3 8379.2 8379.4 8391.5 8392.9 8412.7 8442.8 8453.7 8575.8 8599.1 6029.1 6123.4 6131.1 6179.1 6221.9 7133.4 7177.4 7185.3 7188.4 7202.3 6519.5 6642.0 6660.0 6666.7 6796.2 6468.5 6520.2 6525.4 6613.6 6695.3 6026.0 6033.6 6074.6 6080.1 6092.4 4533.9 4597.7 4603.3 4644.7 4689.2 4568.7 4601.6 4671.6 4681.7 4712.2 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#4] prototype.html Cycles:180 Average:4012.45 Median:4451.14 stddev:1163.72 (26.1%) stddev-sans-first:1133.05 18:32:57 INFO - PROCESS | 3374 | Values: 295.3 297.3 303.0 306.6 306.6 4517.7 4675.8 4684.2 4699.2 4765.1 4465.1 4621.7 4625.1 4631.8 4654.1 3704.3 3707.6 3710.7 3725.1 3732.4 3530.7 3573.4 3696.8 3715.9 3724.7 3658.2 3678.2 3695.4 3696.3 3700.0 3672.4 3682.8 3696.8 3697.0 3709.2 3300.0 3301.9 3306.5 3320.2 3321.1 4345.7 4519.0 4535.2 4581.9 4643.4 4098.9 4103.0 4118.9 4122.4 4122.7 4804.1 4822.2 4824.7 4832.3 4835.6 3324.7 3493.2 3493.3 3495.5 3519.7 5065.7 5105.5 5118.3 5118.8 5144.3 3591.0 3592.1 3600.7 3610.5 3629.8 3681.2 3682.3 3683.8 3687.1 3694.1 3934.0 4125.5 4141.4 4146.7 4183.4 4596.5 4861.0 4885.4 4886.3 4896.9 1209.4 1247.3 1262.3 1267.2 1269.8 805.3 817.0 830.5 836.6 839.3 3958.4 3972.3 3975.3 3984.4 3993.7 4447.8 4451.0 4451.3 4456.2 4471.7 3965.3 3968.3 3972.6 3999.2 4009.9 2341.8 2382.2 2414.1 2435.7 2463.4 4290.9 4369.2 4374.9 4375.2 4402.8 4576.9 4583.2 4640.6 4644.3 4644.4 4320.0 4325.4 4357.0 4526.3 4953.6 5080.6 5103.2 5115.7 5132.6 5136.3 5014.4 5020.7 5036.3 5052.3 5062.4 4708.6 4858.5 4871.2 4940.6 4946.0 3595.3 3606.0 3610.2 3625.3 3627.5 4889.4 5103.3 5111.8 5129.4 5153.8 4832.5 4935.7 4938.8 4944.3 4949.0 4831.0 4893.8 4911.0 4916.4 4972.1 4611.0 4858.5 4872.0 4875.3 4930.7 4933.0 4964.3 4966.6 4967.9 4969.3 4835.9 4853.2 4855.3 4889.1 4913.6 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - PROCESS | 3374 | [#5] yui.html Cycles:180 Average:18173.20 Median:499.88 stddev:26712.29 (5343.8%) stddev-sans-first:26754.92 18:32:57 INFO - PROCESS | 3374 | Values: 677.7 697.9 699.7 724.8 762.3 10156.3 11795.4 12578.1 12655.1 13220.3 13766.8 14411.2 14568.5 14580.3 14588.4 623.0 764.1 765.8 788.9 790.0 797.2 803.0 804.5 805.2 805.3 838.1 847.4 849.4 849.6 849.7 839.4 840.3 840.5 841.4 841.8 611.7 612.8 614.5 615.0 615.6 11907.7 11969.2 11973.2 11980.9 12013.5 13202.9 13705.7 13754.6 13881.7 13924.7 10503.5 10550.4 10634.4 10704.7 10753.8 3364.7 3368.5 3373.3 3401.5 3445.9 55844.0 61814.0 69479.7 70429.0 70550.0 10692.8 10767.4 10931.9 11130.7 11132.3 20692.2 20736.1 20773.8 20776.7 20801.7 6530.5 6745.7 6752.8 6769.0 6893.7 3606.6 3627.6 3683.2 3731.4 3732.2 2885.0 2979.2 2979.8 2988.8 2994.4 494.4 496.9 499.5 500.3 505.7 544.6 566.7 569.3 651.2 654.4 8272.6 8310.4 8709.9 8942.9 8945.6 580.0 596.9 626.2 629.5 646.4 3743.6 3780.2 3824.3 3827.8 3876.3 58687.7 91304.2 97221.0 108555.0 108649.5 94460.5 96877.5 96882.0 97170.0 97307.5 49000.5 77376.6 82029.6 82529.6 84024.0 7316.2 7430.2 7479.2 7499.3 7548.1 46074.5 46986.1 47028.3 47353.5 48547.3 59417.7 67378.3 67635.7 68736.0 69287.0 10203.4 10551.7 10686.7 10748.8 10779.6 11528.5 11580.5 11736.6 11770.9 11887.0 10669.0 10737.8 10933.6 11098.4 11423.7 10626.7 10750.2 10889.3 11075.8 11438.4 10842.8 11033.6 11146.9 11179.7 11443.1 8657.7 8854.0 9182.7 9206.3 9395.6 8563.7 8578.9 8699.4 8723.4 8959.3 18:32:57 INFO - PROCESS | 3374 | -------- Summary: end -------- 18:32:57 INFO - PROCESS | 3374 | 18:32:57 INFO - TEST-INFO | 3374: exit 0 18:32:57 INFO - TEST-OK | dromaeo_css | took 1123177ms 18:32:57 INFO - TEST-START | kraken 18:32:57 INFO - operating with platform_type : linux_ 18:32:57 INFO - Initialising browser for kraken test... 18:32:57 INFO - TEST-INFO | started process 3566 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZg_o8G/profile http://localhost:45942/getInfo.html) 18:33:02 INFO - PROCESS | 3566 | __metrics Screen width/height:1600/1200 18:33:02 INFO - PROCESS | 3566 | colorDepth:24 18:33:02 INFO - PROCESS | 3566 | Browser inner width/height: 1024/697 18:33:02 INFO - PROCESS | 3566 | __metrics 18:33:03 INFO - PROCESS | 3566 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:33:03 INFO - PROCESS | 3566 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:33:04 INFO - PROCESS | 3566 | console.error: PushService: 18:33:04 INFO - PROCESS | 3566 | stateChangeProcessEnqueue: Error transitioning state 18:33:04 INFO - PROCESS | 3566 | UnknownError 18:33:04 INFO - TEST-INFO | 3566: exit 0 18:33:04 INFO - Browser initialized. 18:33:04 INFO - Running cycle 1/1 for kraken test... 18:33:04 INFO - TEST-INFO | started process 3646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZg_o8G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:16 INFO - PROCESS | 3646 | RSS: Main: 158859264 18:33:16 INFO - PROCESS | 3646 | 18:33:19 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45942/tests/kraken/driver.html?testName=audio-beat-detection) 18:33:20 INFO - PROCESS | 3646 | RSS: Main: 216866816 18:33:20 INFO - PROCESS | 3646 | 18:33:23 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45942/tests/kraken/driver.html?testName=audio-dft) 18:33:23 INFO - PROCESS | 3646 | RSS: Main: 204640256 18:33:23 INFO - PROCESS | 3646 | 18:33:27 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45942/tests/kraken/driver.html?testName=audio-fft) 18:33:27 INFO - PROCESS | 3646 | RSS: Main: 271106048 18:33:27 INFO - PROCESS | 3646 | 18:33:29 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45942/tests/kraken/driver.html?testName=audio-oscillator) 18:33:30 INFO - PROCESS | 3646 | RSS: Main: 182022144 18:33:30 INFO - PROCESS | 3646 | 18:33:32 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45942/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:33:33 INFO - PROCESS | 3646 | RSS: Main: 186077184 18:33:33 INFO - PROCESS | 3646 | 18:33:36 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45942/tests/kraken/driver.html?testName=imaging-darkroom) 18:33:37 INFO - PROCESS | 3646 | RSS: Main: 222367744 18:33:37 INFO - PROCESS | 3646 | 18:33:40 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45942/tests/kraken/driver.html?testName=imaging-desaturate) 18:33:41 INFO - PROCESS | 3646 | RSS: Main: 217538560 18:33:41 INFO - PROCESS | 3646 | 18:33:44 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45942/tests/kraken/driver.html?testName=json-parse-financial) 18:33:44 INFO - PROCESS | 3646 | RSS: Main: 220635136 18:33:44 INFO - PROCESS | 3646 | 18:33:46 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45942/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:33:47 INFO - PROCESS | 3646 | RSS: Main: 205524992 18:33:47 INFO - PROCESS | 3646 | 18:33:48 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:33:49 INFO - PROCESS | 3646 | RSS: Main: 211148800 18:33:49 INFO - PROCESS | 3646 | 18:33:51 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:33:52 INFO - PROCESS | 3646 | RSS: Main: 209870848 18:33:52 INFO - PROCESS | 3646 | 18:33:55 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:33:55 INFO - PROCESS | 3646 | RSS: Main: 228462592 18:33:55 INFO - PROCESS | 3646 | 18:33:58 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:33:59 INFO - PROCESS | 3646 | RSS: Main: 224960512 18:33:59 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | Cycle 1(1): loaded http://localhost:45942/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45942/tests/kraken/driver.html?testName=ai-astar) 18:34:01 INFO - PROCESS | 3646 | RSS: Main: 267157504 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | __start_tp_report 18:34:01 INFO - PROCESS | 3646 | _x_x_mozilla_page_load 18:34:01 INFO - PROCESS | 3646 | _x_x_mozilla_page_load_details 18:34:01 INFO - PROCESS | 3646 | |i|pagename|runs| 18:34:01 INFO - PROCESS | 3646 | |0;ai-astar;105;117;115;115;117;115;115;116;115;118 18:34:01 INFO - PROCESS | 3646 | |1;audio-beat-detection;156;124;131;174;126;148;144;125;182;123 18:34:01 INFO - PROCESS | 3646 | |2;audio-dft;163;162;162;161;162;160;161;162;162;162 18:34:01 INFO - PROCESS | 3646 | |3;audio-fft;74;76;78;142;77;77;77;77;97;77 18:34:01 INFO - PROCESS | 3646 | |4;audio-oscillator;85;84;110;84;86;87;126;85;86;123 18:34:01 INFO - PROCESS | 3646 | |5;imaging-gaussian-blur;118;117;118;117;118;117;118;118;119;118 18:34:01 INFO - PROCESS | 3646 | |6;imaging-darkroom;148;147;147;147;147;147;146;146;147;147 18:34:01 INFO - PROCESS | 3646 | |7;imaging-desaturate;96;95;95;96;96;95;95;95;95;95 18:34:01 INFO - PROCESS | 3646 | |8;json-parse-financial;65;63;63;64;63;64;62;62;63;62 18:34:01 INFO - PROCESS | 3646 | |9;json-stringify-tinderbox;66;64;66;64;64;64;65;65;65;64 18:34:01 INFO - PROCESS | 3646 | |10;stanford-crypto-aes;79;79;78;88;85;85;80;79;82;81 18:34:01 INFO - PROCESS | 3646 | |11;stanford-crypto-ccm;137;143;131;137;139;137;135;129;166;137 18:34:01 INFO - PROCESS | 3646 | |12;stanford-crypto-pbkdf2;169;172;165;165;169;166;170;170;167;169 18:34:01 INFO - PROCESS | 3646 | |13;stanford-crypto-sha256-iterative;70;68;67;66;67;67;67;66;65;67 18:34:01 INFO - PROCESS | 3646 | __end_tp_report 18:34:01 INFO - PROCESS | 3646 | __start_cc_report 18:34:01 INFO - PROCESS | 3646 | _x_x_mozilla_cycle_collect,1895 18:34:01 INFO - PROCESS | 3646 | __end_cc_report 18:34:01 INFO - PROCESS | 3646 | __startTimestamp1468373641285__endTimestamp 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | ------- Summary: start ------- 18:34:01 INFO - PROCESS | 3646 | Number of tests: 14 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.50 stddev:3.61 (3.1%) stddev-sans-first:1.17 18:34:01 INFO - PROCESS | 3646 | Values: 105.0 117.0 115.0 115.0 117.0 115.0 115.0 116.0 115.0 118.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:146.00 stddev:21.59 (14.8%) stddev-sans-first:22.40 18:34:01 INFO - PROCESS | 3646 | Values: 156.0 124.0 131.0 174.0 126.0 148.0 144.0 125.0 182.0 123.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 18:34:01 INFO - PROCESS | 3646 | Values: 163.0 162.0 162.0 161.0 162.0 160.0 161.0 162.0 162.0 162.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#3] audio-fft Cycles:10 Average:85.20 Median:77.00 stddev:20.99 (27.3%) stddev-sans-first:21.86 18:34:01 INFO - PROCESS | 3646 | Values: 74.0 76.0 78.0 142.0 77.0 77.0 77.0 77.0 97.0 77.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:85.00 stddev:17.11 (20.1%) stddev-sans-first:17.71 18:34:01 INFO - PROCESS | 3646 | Values: 85.0 84.0 110.0 84.0 86.0 87.0 126.0 85.0 86.0 123.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 18:34:01 INFO - PROCESS | 3646 | Values: 118.0 117.0 118.0 117.0 118.0 117.0 118.0 118.0 119.0 118.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 18:34:01 INFO - PROCESS | 3646 | Values: 148.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 147.0 147.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 18:34:01 INFO - PROCESS | 3646 | Values: 96.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.99 (1.6%) stddev-sans-first:0.78 18:34:01 INFO - PROCESS | 3646 | Values: 65.0 63.0 63.0 64.0 63.0 64.0 62.0 62.0 63.0 62.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.73 18:34:01 INFO - PROCESS | 3646 | Values: 66.0 64.0 66.0 64.0 64.0 64.0 65.0 65.0 65.0 64.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.50 stddev:3.34 (4.1%) stddev-sans-first:3.41 18:34:01 INFO - PROCESS | 3646 | Values: 79.0 79.0 78.0 88.0 85.0 85.0 80.0 79.0 82.0 81.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:137.00 stddev:10.22 (7.5%) stddev-sans-first:10.82 18:34:01 INFO - PROCESS | 3646 | Values: 137.0 143.0 131.0 137.0 139.0 137.0 135.0 129.0 166.0 137.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:169.00 stddev:2.35 (1.4%) stddev-sans-first:2.47 18:34:01 INFO - PROCESS | 3646 | Values: 169.0 172.0 165.0 165.0 169.0 166.0 170.0 170.0 167.0 169.0 18:34:01 INFO - PROCESS | 3646 | 18:34:01 INFO - PROCESS | 3646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.33 (2.0%) stddev-sans-first:0.87 18:34:01 INFO - PROCESS | 3646 | Values: 70.0 68.0 67.0 66.0 67.0 67.0 67.0 66.0 65.0 67.0 18:34:01 INFO - PROCESS | 3646 | -------- Summary: end -------- 18:34:01 INFO - PROCESS | 3646 | 18:34:02 INFO - TEST-INFO | 3646: exit 0 18:34:03 INFO - TEST-OK | kraken | took 65325ms 18:34:03 INFO - SUITE-END | took 1188s 18:34:03 INFO - Completed test suite (00:19:48) 18:34:03 INFO - javascript benchmark 18:34:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2549.2861998640365, 2589.98705006475, 2624.435746314542, 2634.2887420396487, 2638.300850274676, 4494.528074552174, 4568.5888270055775, 4571.2228921083415, 4599.4940556538795, 4601.6548758843055, 4552.29439436887, 4616.884577885555, 4653.557911998355, 4692.718436893789, 4723.787429565669, 4176.331786914095, 4182.937255941163, 4186.916261674764, 4271.380649805777, 4277.422547956017, 4238.385434112052, 4257.957420425787, 4331.30699088146, 4337.067530480946, 4360.890977725567, 4139.627433530996, 4170.103427763045, 4251.171021650779, 4267.701260911737, 4295.183915056129, 4232.978835105805, 4241.512226094008, 4242.0, 4242.703010789246, 4246.171996460692, 4200.306949353353, 4210.221109094828, 4224.366345048236, 4231.936520952188, 4235.343521754133, 4513.864584062483, 4541.886452838672, 4547.749873756942, 4582.725036497787, 4620.561046700558, 4838.032393521275, 4867.367242258484, 4870.512948705101, 4886.267059941002, 4922.163232250526, 4794.640401969831, 4897.902041959141, 4949.554540091409, 4952.777125029383, 4961.404631444214, 4584.679072464895, 4585.014221942299, 4641.419822522184, 4659.18464268755, 4702.435707715028, 4998.600111991104, 5020.322256495399, 5063.1645778446145, 5069.898602027939, 5073.34046573943, 4623.144718227139, 4729.077829823152, 4837.806487740521, 4860.246661767432, 4862.0, 4958.281049247911, 4976.253561965734, 4984.651074424755, 5009.924851127236, 5012.949870501321, 4886.747996234063, 4907.116718990616, 4910.263460480885, 4914.262860570943, 4927.211646136601, 4711.882202944886, 4727.905441891143, 4910.288008238786, 4918.2376731606655, 4943.159662857251, 4099.7745124017865, 4101.323281658493, 4107.075907920694, 4108.568600296986, 4112.485939257593, 3622.2936527377437, 3633.182533929845, 3648.087978005499, 3648.543932008499, 3653.598104208535, 4341.34879768031, 4349.956500435019, 4354.281543545279, 4357.302831546937, 4398.538153493901, 4922.827701030446, 4980.576650984634, 4985.277134815431, 4998.175301075355, 5025.447200807908, 4172.5410204877435, 4304.978475107604, 4332.545082766327, 4339.349097635317, 4358.8910277242685, 4457.799399026987, 4481.865544033684, 4488.5960263576435, 4498.347739577743, 4507.256302710081, 4761.452432970253, 4764.618830493552, 4764.952350476589, 4765.69023013503, 4811.422629284578, 4775.808967641395, 4896.583790377857, 4922.704637721748, 4939.308496810521, 5001.174806157089, 4678.157931572349, 4763.833265835679, 4768.403949506312, 4769.189237829508, 4817.566419022165, 4736.0, 4887.095887260868, 4895.922896962663, 4896.632752543503, 4950.77721502526, 4887.60899128076, 4951.9752401237765, 4960.156773348467, 4973.626977976739, 4979.0, 4810.518948105301, 4855.957226079414, 4856.053069651525, 4859.659823812299, 4897.461279259207, 4701.059788042337, 4724.71651700899, 4845.030993801324, 4867.415910090812, 4909.582685471775, 4690.413698287714, 4701.177293973609, 4752.073345697556, 4767.189577771856, 4767.998720268783, 4809.062232864558, 4813.350197723263, 4823.589994850477, 4834.0, 4862.881537246382, 4422.00504886398, 4630.212863813093, 4640.419947506562, 4704.129735998851, 4710.105080034782], "value": 4533.126570713644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.1360745274634, 576.1214148423653, 580.5442727458945, 584.5294537896993, 589.3340525206517, 27597.999999999975, 39757.80121099394, 41130.17739645209, 41130.794346028226, 44901.77549112258, 41575.58424415755, 41809.0, 42074.0, 42125.57874421255, 42156.0, 7724.266194711521, 7875.251851074138, 7878.763637090897, 7879.330256928168, 7896.486728362666, 7711.652975616083, 7761.650725717328, 7775.611219439033, 7809.140994490602, 7812.843743125134, 7773.455858089936, 7826.1391246962785, 7839.02012248469, 7850.764477065697, 7869.370450363957, 7791.961040194791, 7826.413019023539, 7841.176676449005, 7843.176466470995, 7850.568218747967, 7801.102873169544, 7865.528068315919, 7868.606569671493, 7869.685212591489, 7872.055353357577, 38860.0, 39187.21625567501, 39251.8037409814, 39267.0, 39279.214415711525, 3191.872325106993, 3222.7582931280012, 3563.82180890957, 3716.9628303716886, 3792.1657235408165, 29816.254593635334, 30370.69629303723, 30816.8459157705, 31968.840155799306, 32726.509102363485, 3518.286816643302, 3537.2218112015316, 3543.8936831894825, 3566.982165089184, 3592.6186396188486, 261195.0, 265773.0, 265845.0, 266150.6692466545, 266853.0, 55384.0, 55437.0, 55480.16779748307, 55629.0, 56007.43992560104, 33397.499037514455, 34642.30715385678, 58585.70707146437, 58659.70670146622, 58720.7063964686, 15173.393064277305, 15216.239188040554, 15419.383224670888, 15541.145237012073, 15614.219289035504, 11199.496022678999, 11236.719082023015, 11337.546498140147, 11388.487518061542, 11473.942630286796, 18547.72178417325, 18646.160922758507, 18675.346362877233, 18684.0, 18744.25022999065, 6724.495662825308, 6897.41371983377, 6926.757563485254, 6956.304369563003, 7039.964800175966, 774.9690012399553, 777.1140899374717, 833.112734937302, 835.3734698975768, 855.9358048146414, 26997.460050799265, 27006.729932700815, 27515.449691006066, 27834.582481262798, 29014.99517815834, 963.205355581648, 1004.3829434610418, 1029.2177944761886, 1030.7371620236793, 1095.9780804383868, 14745.336459859333, 15384.692306153815, 15442.536723898302, 15593.532194034196, 15658.138802365978, 368584.0, 369148.0, 369782.0, 370210.0, 371730.0, 51201.0, 51566.0, 51842.740786296585, 51886.22170667443, 51924.74037629863, 1044.6865940218056, 1134.489479734123, 1163.203205804027, 1189.8869607387285, 1195.6472840511897, 29270.121896343142, 30164.54753178748, 30312.0, 30338.696613033586, 30511.507034159484, 43037.35443968406, 43470.78264608657, 43655.78172109246, 43665.56334436615, 43679.56320436755, 307853.0, 315139.0, 316048.0, 317646.0, 318791.0, 32332.515012274365, 37689.43465847959, 39150.6084939147, 39640.40539391969, 42725.35911961261, 42592.361114583924, 42978.35532467078, 43637.56362436335, 43725.56274437342, 44110.11779764451, 36222.378270158944, 37162.442563362114, 37553.24893502169, 38053.61946380501, 38246.42630360491, 37116.25767484689, 37619.811900941415, 37903.62096379001, 37936.430953535164, 38243.808780955915, 34863.30273394467, 36162.8191859039, 36293.0, 36599.45100823543, 37101.814490927376, 32197.35605287928, 33148.502772458916, 33278.66721332756, 33952.320953581286, 33993.490097648064, 24877.751222487543, 25688.743112569384, 25766.742332576436, 25928.0, 25978.480430391664], "value": 18017.223160962865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.5576861617131, 370.1782043862625, 376.35079487883405, 377.22668529514505, 391.3952942703523, 12902.80645790314, 16226.675466490678, 16774.41289554864, 18418.631627367446, 18457.63084738308, 17012.48962531126, 17496.912515437438, 17765.55586110348, 17778.199981000853, 17787.199576019077, 5135.666181698187, 5182.844514664566, 5314.734263286839, 5326.92009619856, 5341.4658534146665, 3755.3428150073764, 5266.947330526684, 5702.4012478689765, 6032.27612686476, 6055.6063855849225, 6066.363031881633, 6078.939210607904, 6089.756409743605, 6117.113018612298, 6139.508839292846, 5162.406323272815, 5503.3120859892515, 5512.062949298629, 5515.25544051556, 5520.944790552104, 5291.656042357235, 5345.1447768356875, 5348.786048558053, 5375.838724838261, 5378.650387724785, 12957.09300348976, 12977.0, 12977.41601627929, 18962.336318228932, 21093.683594745937, 1957.862949593529, 1976.4070778766456, 2022.6055919095782, 2125.7236559247353, 2431.1734010436357, 9682.612695492171, 9891.8516222256, 13385.66535836612, 13522.661933451644, 14038.368273427619, 1626.723457012299, 1736.8610511159077, 1749.9912500437417, 1763.3563749231566, 1764.7352897065414, 68460.26158953636, 72730.90903636503, 73149.0, 73360.0, 74189.0, 8008.39937004727, 9389.62441502346, 10066.14437772783, 10203.846942295871, 10477.895221047844, 13656.795148072786, 14119.576412707635, 14163.78754318686, 14228.644283892985, 14254.501092461922, 7183.49715519919, 7239.058922340168, 7250.673719682627, 7257.478996577174, 7386.224446433154, 6517.51118666102, 7263.201047884729, 7318.524295920748, 7323.340899319087, 7483.4761566690895, 4431.180231657154, 4440.555944405533, 4496.21316269658, 4519.7740112994215, 4576.931346029813, 1827.479168436989, 1839.8988055657067, 1877.4555378940227, 1879.6804543227684, 1922.6731455652573, 814.861473549498, 826.2150956591142, 875.8395126457469, 895.0154829687422, 932.2588542109039, 16565.834341656428, 16609.916950415172, 16857.915710421614, 17574.736378954327, 18099.095045247686, 874.8337815814975, 882.2236431939851, 902.7607683963755, 928.679605536089, 945.6264775413811, 10036.046575575308, 10265.640702575374, 10291.639792607222, 10294.794104118026, 10362.689119326433, 398805.0, 407839.0, 410299.0, 410749.0, 411228.0, 83241.58379208065, 84960.0, 85992.14007859842, 86447.0, 86867.0, 938.8685584018243, 942.9245660347293, 960.0927123867878, 961.0725649748026, 967.6855022117699, 15861.841381586037, 16336.836631633532, 16411.58971025734, 16451.91774041122, 16620.916895415445, 38552.614473854905, 39530.0, 40001.0, 40228.7988560067, 41174.79412602918, 55214.72392638011, 55828.44171558395, 56982.430175699374, 57034.7148264256, 57271.42728572661, 7049.647517623996, 7379.8155046124275, 7556.58438785872, 7589.924100759143, 7828.686852525835, 10687.839682404614, 10946.233763636468, 11491.94254028758, 11703.063754899418, 11829.467673954521, 9446.716598501782, 9501.287403444907, 10076.0, 10128.0, 10226.539805708611, 9639.662611808553, 9728.12446879756, 9888.208943284664, 10044.899551004688, 10061.547230374797, 9800.85298720534, 10025.598976040877, 10277.74305642365, 10388.792224155324, 10523.158147348347, 7175.0, 7303.014093097364, 7352.632368381666, 7356.301151390823, 7459.888101678588, 9383.577739001897, 9605.47169905662, 9981.500924953869, 10178.541965611714, 10468.0], "value": 8375.768497129206, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1126.6225814352192, 1163.9883601163986, 1238.616029031, 1290.4193113099104, 1392.7284179584979, 6344.714487848047, 6805.455563554916, 7034.577925324485, 7076.752313669022, 7080.9645951770235, 6864.588124712508, 7125.964370178155, 7154.0342053822715, 7296.598687072209, 7354.742584009561, 1870.69133592957, 2095.7170781944396, 2128.968065479019, 2142.089611914938, 2179.226374637005, 2210.6462965925457, 2217.1353172262748, 2218.678291647718, 2223.943626777284, 2224.2104053061094, 2110.3668899330214, 2151.139544182332, 2175.423512769117, 2177.7495588007423, 2183.4432219783935, 1814.764080669511, 1882.8117188281199, 1882.9925989595638, 2037.113855472874, 2080.552681173547, 2112.2501511963337, 2143.142742902836, 2144.2709478777133, 2145.227718021511, 2145.313499680103, 7152.928470715278, 7186.065811444479, 7188.604626745527, 7190.137183537957, 7191.604461754601, 4328.567143285678, 4476.462824461053, 4477.3731677565165, 4481.193385190664, 4484.663650226246, 2741.2872653110135, 2795.888164473419, 2836.957445638317, 2839.318563544755, 2856.7286107819723, 857.4512312120248, 880.4629176202511, 889.8220355928776, 903.46695449685, 908.8364094462994, 9703.272254580865, 9927.205823534172, 9928.354656947347, 9947.1544918682, 9950.0, 3896.649301562873, 3973.4834471518516, 4124.711270211056, 4134.834606615702, 4136.607022332873, 8380.371472139614, 8430.662773489114, 8437.240648341556, 8447.324214062983, 8453.196946290092, 4521.547845215452, 4534.727916325031, 4543.227651299287, 4547.704399214107, 4548.7725613719185, 3613.765105268149, 3922.2155568886337, 4015.5382131054716, 4024.9597504024587, 4087.1621317629815, 3565.826414491105, 3609.6751292383287, 3651.1602331463914, 3661.0664280608808, 3719.367707489733, 1040.6565833275001, 1122.085500317239, 1148.26511032939, 1167.5738355500182, 1183.4319526627266, 735.5549892315179, 777.1373775109623, 836.3977935886152, 842.6966292134827, 850.2815121222557, 4872.561469467765, 4880.414350277918, 5126.307948426989, 5146.639735218499, 5298.81454149103, 826.574314228173, 864.3776480933718, 881.6649673124172, 882.9028806831243, 888.6356593796512, 3342.782719123249, 3513.3675938330834, 3524.577050753926, 3537.398642230827, 3607.0441333046765, 8861.822763544693, 9152.679656212, 9229.077092290852, 9251.167394934353, 9267.582958766872, 9161.862572061424, 9173.862392064124, 9192.21866141372, 9193.080691930887, 9193.218576421079, 6954.0, 6961.79114626562, 7016.596680663888, 7079.115110611174, 7085.468589855782, 5002.299678045149, 5015.724135172601, 5066.746662666926, 5176.275321454924, 5227.71920879387, 8011.319037881845, 8379.203975622306, 8379.413441059067, 8391.496510209407, 8392.87410688828, 8412.696032115033, 8442.831143377221, 8453.746387608382, 8575.785605359666, 8599.14008599135, 6029.095635654687, 6123.4488895999575, 6131.110988906673, 6179.073139029181, 6221.906671399842, 7133.357997780225, 7177.354038136593, 7185.317394847481, 7188.388986936169, 7202.279772022755, 6519.478441724746, 6642.0, 6659.966700166468, 6666.666666666744, 6796.184457865088, 6468.514861385321, 6520.152380190545, 6525.3800888915475, 6613.570117942319, 6695.263521012587, 6026.0, 6033.577649564489, 6074.574779765374, 6080.087986802015, 6092.360302168208, 4533.886652833706, 4597.7241365518175, 4603.263477843529, 4644.674872758875, 4689.249720044776, 4568.70303430276, 4601.562851529099, 4671.579557839811, 4681.6722829401615, 4712.151812673615], "value": 3961.6831868328727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.2647906712286, 297.2776153945911, 302.9636443626765, 306.5539639824056, 306.60601127551104, 4517.683762136652, 4675.8363457279, 4684.227102528083, 4699.154152252593, 4765.142274390609, 4465.106978604276, 4621.74580398078, 4625.144348295567, 4631.7915693793775, 4654.138984287901, 3704.259148170363, 3707.5921648618632, 3710.703143748494, 3725.143217060078, 3732.4401339798974, 3530.717542596599, 3573.446115852034, 3696.796676182809, 3715.8699445519414, 3724.664780169784, 3658.213484100918, 3678.2459595782934, 3695.445683147536, 3696.2977034363385, 3699.9630003699885, 3672.394054980925, 3682.797446140461, 3696.759710618828, 3696.963030369716, 3709.2210635766523, 3299.967000330014, 3301.943378119003, 3306.5205545196054, 3320.236345640492, 3321.1199032256472, 4345.69580129391, 4519.0058187198765, 4535.229011068126, 4581.908361832778, 4643.3731446255, 4098.856540021085, 4102.979485102555, 4118.897027574335, 4122.37231236513, 4122.732022418563, 4804.063207674505, 4822.228443449067, 4824.662273640846, 4832.323474713543, 4835.564799168092, 3324.6535153262967, 3493.2314890724, 3493.318802833423, 3495.5455790747296, 3519.7008254298416, 5065.721385323805, 5105.463926287724, 5118.283440318359, 5118.769655365518, 5144.279800827887, 3591.012471570339, 3592.1199306169633, 3600.6579374959338, 3610.494530765695, 3629.8366573504254, 3681.153334732973, 3682.2635472905527, 3683.778973261613, 3687.0966613179944, 3694.0606181754792, 3933.9803300983885, 4125.546189919107, 4141.446957390978, 4146.69858228686, 4183.372494125844, 4596.517365676623, 4861.0, 4885.438174609894, 4886.291487734258, 4896.926546101811, 1209.38925842449, 1247.2952781678323, 1262.3309645887693, 1267.182667179664, 1269.8095285707034, 805.2792750488397, 817.0277369929764, 830.4519017448455, 836.60679480644, 839.299185180369, 3958.3572817280506, 3972.3048466518244, 3975.264576053439, 3984.3703155916955, 3993.72043956926, 4447.777611119496, 4451.0, 4451.287793952952, 4456.242438785414, 4471.731696098244, 3965.2862484753023, 3968.333300014001, 3972.5698748450536, 3999.200159968018, 4009.91980160401, 2341.8360714750143, 2382.213869423086, 2414.082648593523, 2435.695538057743, 2463.4334103156375, 4290.914181716411, 4369.213541562549, 4374.934375984363, 4375.212461756914, 4402.801873915681, 4576.931346029813, 4583.197940360424, 4640.628749700016, 4644.3265726470145, 4644.419447569054, 4320.027993701455, 4325.399602147226, 4357.0, 4526.321051842184, 4953.578945789648, 5080.568151706999, 5103.209002604603, 5115.667481613683, 5132.640715149903, 5136.30659860914, 5014.420457555858, 5020.673656212334, 5036.269740887624, 5052.267421223975, 5062.443466049622, 4708.623310135113, 4858.514148585114, 4871.171900776805, 4940.60475161979, 4946.0, 3595.2742683511538, 3605.963940360563, 3610.187707765837, 3625.329314076904, 3627.492151098901, 4889.364382630235, 5103.336566246513, 5111.8466446005195, 5129.358830146231, 5153.845384638467, 4832.540908613676, 4935.728534930613, 4938.75306234694, 4944.307796908363, 4949.0, 4831.0, 4893.825481884251, 4910.961950790382, 4916.360873086619, 4972.129877271391, 4610.976945115253, 4858.542437268735, 4871.95128048715, 4875.341828853201, 4930.704157750546, 4932.975335123301, 4964.280179374043, 4966.553010229097, 4967.925481117858, 4969.254611808257, 4835.903281934271, 4853.174960256694, 4855.295982082507, 4889.119958407378, 4913.606911447004], "value": 3647.739540223832, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.7322957431815, 697.9197392299884, 699.7236091743762, 724.7644515532453, 762.3320611255317, 10156.2890597658, 11795.351255680935, 12578.119531632788, 12655.114142010063, 13220.338983050857, 13766.793498097511, 14411.207383593897, 14568.490102846403, 14580.270986450689, 14588.416463341453, 622.987540249197, 764.1174443517746, 765.7549584133079, 788.8619491588978, 789.9842003159933, 797.2227078598378, 802.995985020074, 804.493169303338, 805.2028491793142, 805.2591615713558, 838.1116017021976, 847.3729440214258, 849.4478588917223, 849.6261644876265, 849.7280870121564, 839.4207996482469, 840.315143158324, 840.504102579481, 841.3563623827777, 841.8484672758901, 611.703323887914, 612.8498517863152, 614.5145335185198, 615.0313256620796, 615.5721773367511, 11907.702307442296, 11969.162158648898, 11973.221740586834, 11980.940095299467, 12013.519459221621, 13202.933985330108, 13705.657358566015, 13754.587362379145, 13881.722365552632, 13924.721505569933, 10503.474826258733, 10550.366977981268, 10634.36193828365, 10704.661917260342, 10753.78492430147, 3364.680355366236, 3368.4610462326154, 3373.274745929637, 3401.540791993074, 3445.9483107753404, 55844.0, 61814.0, 69479.65260173718, 70429.0, 70550.0, 10692.786144277072, 10767.385255571102, 10931.945340273407, 11130.666080017612, 11132.276402033842, 20692.172313107607, 20736.06687699057, 20773.792262077186, 20776.68834967477, 20801.687974680393, 6530.542861999615, 6745.696443659949, 6752.831179220559, 6768.9661551691925, 6893.724251030003, 3606.5672119345845, 3627.5863439055306, 3683.226522430266, 3731.384321586962, 3732.234891847165, 2885.0, 2979.1956171833485, 2979.8361090139815, 2988.775841811873, 2994.431058098954, 494.3721473728345, 496.85839535732146, 499.4506043352283, 500.30276258450084, 505.70921720010404, 544.6160456877892, 566.7392999220405, 569.3367227180289, 651.2315467748008, 654.4142992022114, 8272.586370681442, 8310.418270721113, 8709.91290087091, 8942.910570894208, 8945.552722363856, 580.0215891397703, 596.8686888884439, 626.2215308523814, 629.5404354821006, 646.3956200952031, 3743.5694895086867, 3780.243951209769, 3824.330742120118, 3827.8086095695103, 3876.3022655922205, 58687.70656146777, 91304.19737707087, 97221.02778972262, 108555.0, 108649.4567527173, 94460.52769736107, 96877.5156124229, 96882.0, 97170.0, 97307.51346243224, 49000.509994900305, 77376.61311693405, 82029.58985205035, 82529.58735206406, 84024.0, 7316.195218526064, 7430.219826918095, 7479.214682458263, 7499.315665049147, 7548.131964824115, 46074.53925460702, 46986.06027879355, 47028.29457558208, 47353.52646473491, 48547.27179092387, 59417.70291148689, 67378.3262167372, 67635.66182169058, 68736.0, 69287.0, 10203.38779673222, 10551.683449496528, 10686.679399618024, 10748.838767418338, 10779.622713205, 11528.538858445569, 11580.536778528765, 11736.591609006633, 11770.88229117732, 11887.0, 10668.98644628759, 10737.785244295226, 10933.617323393642, 11098.445077745919, 11423.714407139556, 10626.71525621995, 10750.24748267645, 10889.346639201673, 11075.778484430428, 11438.370889601152, 10842.837357439486, 11033.598026980151, 11146.944265278622, 11179.66461006171, 11443.14176436754, 8657.740267791976, 8853.955730221307, 9182.67860624875, 9206.309526785384, 9395.577199026196, 8563.657453702031, 8578.91421085781, 8699.391042626956, 8723.432976856484, 8959.283257339266], "value": 6565.039770982289, "unit": "score"}], "name": "dromaeo_css", "value": 6339.443541509129, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 115.0, 115.0, 117.0, 115.0, 115.0, 116.0, 115.0, 118.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 131.0, 174.0, 126.0, 148.0, 144.0, 125.0, 182.0, 123.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 162.0, 160.0, 161.0, 162.0, 162.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 78.0, 142.0, 77.0, 77.0, 77.0, 77.0, 97.0, 77.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 110.0, 84.0, 86.0, 87.0, 126.0, 85.0, 86.0, 123.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 118.0, 117.0, 118.0, 118.0, 119.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 64.0, 63.0, 64.0, 62.0, 62.0, 63.0, 62.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 88.0, 85.0, 85.0, 80.0, 79.0, 82.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 143.0, 131.0, 137.0, 139.0, 137.0, 135.0, 129.0, 166.0, 137.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 165.0, 165.0, 169.0, 166.0, 170.0, 170.0, 167.0, 169.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 66.0, 67.0, 67.0, 67.0, 66.0, 65.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1544.2999999999997, "alertThreshold": 2.0}]} 18:34:03 INFO - Return code: 0 18:34:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:34:03 INFO - # TBPL SUCCESS # 18:34:03 INFO - Running post-action listener: _resource_record_post_action 18:34:03 INFO - ##### 18:34:03 INFO - ##### Finished run-tests step (success) 18:34:03 INFO - ##### 18:34:03 INFO - Running post-run listener: _resource_record_post_run 18:34:03 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 7811072; Write bytes: 462868480; Read time: 2296; Write time: 555508 18:34:03 INFO - TinderboxPrint: CPU usage
13.0% 18:34:03 INFO - TinderboxPrint: I/O read bytes / time
7,811,072 / 2,296 18:34:03 INFO - TinderboxPrint: I/O write bytes / time
462,868,480 / 555,508 18:34:03 INFO - TinderboxPrint: CPU idle
8,370.3 (87.0%) 18:34:03 INFO - TinderboxPrint: CPU user
1,204.5 (12.5%) 18:34:03 INFO - TinderboxPrint: Swap in / out
0 / 0 18:34:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 9670656; Read time: 0; Write time: 11316 18:34:03 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 7806976; Write bytes: 415170560; Read time: 2232; Write time: 527272 18:34:04 INFO - Running post-run listener: _upload_blobber_files 18:34:04 INFO - Blob upload gear active. 18:34:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:34:04 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 18:34:04 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:34:04 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:34:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:34:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:34:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:34:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:07 INFO - (blobuploader) - INFO - Done attempting. 18:34:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:34:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:34:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:08 INFO - (blobuploader) - INFO - Done attempting. 18:34:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:34:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:34:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:09 INFO - (blobuploader) - INFO - Done attempting. 18:34:09 INFO - (blobuploader) - INFO - Iteration through files over. 18:34:09 INFO - Return code: 0 18:34:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:34:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:34:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d4784c481b7adb4059375b5bfc0fb66c4bff6c94691194f380a73ce155e755f872beb22f422403e8033e234b92a7e746ee5a2063c973cc9f0dcc343b5ea4588b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db35612388e49cb81d20e0f7221d6499dbd2652a6a00fb5bdffaa43b980f21f6ca215dded046161919d1e5a52ec7b7356d91699c1ea98d1abdc4c1816310d9cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f75c793c7a6669d0f13c8a4936fbfad3f2aee3345f6458101c22dcb1a5b87343ab54f162734f905b4e83f25812824034b719823e7d1eb9a8dc05efbe5485478"} 18:34:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:34:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:34:09 INFO - Contents: 18:34:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d4784c481b7adb4059375b5bfc0fb66c4bff6c94691194f380a73ce155e755f872beb22f422403e8033e234b92a7e746ee5a2063c973cc9f0dcc343b5ea4588b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db35612388e49cb81d20e0f7221d6499dbd2652a6a00fb5bdffaa43b980f21f6ca215dded046161919d1e5a52ec7b7356d91699c1ea98d1abdc4c1816310d9cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f75c793c7a6669d0f13c8a4936fbfad3f2aee3345f6458101c22dcb1a5b87343ab54f162734f905b4e83f25812824034b719823e7d1eb9a8dc05efbe5485478"} 18:34:09 INFO - Running post-run listener: copy_logs_to_upload_dir 18:34:09 INFO - Copying logs to upload dir... 18:34:09 INFO - mkdir: /builds/slave/test/build/upload/logs 18:34:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.358278 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-12 18:34:09.454517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:34:09.458069) ========= 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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d4784c481b7adb4059375b5bfc0fb66c4bff6c94691194f380a73ce155e755f872beb22f422403e8033e234b92a7e746ee5a2063c973cc9f0dcc343b5ea4588b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db35612388e49cb81d20e0f7221d6499dbd2652a6a00fb5bdffaa43b980f21f6ca215dded046161919d1e5a52ec7b7356d91699c1ea98d1abdc4c1816310d9cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f75c793c7a6669d0f13c8a4936fbfad3f2aee3345f6458101c22dcb1a5b87343ab54f162734f905b4e83f25812824034b719823e7d1eb9a8dc05efbe5485478"} build_url:https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/WhtSup3gQe6O-Yj-EKDjoQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d4784c481b7adb4059375b5bfc0fb66c4bff6c94691194f380a73ce155e755f872beb22f422403e8033e234b92a7e746ee5a2063c973cc9f0dcc343b5ea4588b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/db35612388e49cb81d20e0f7221d6499dbd2652a6a00fb5bdffaa43b980f21f6ca215dded046161919d1e5a52ec7b7356d91699c1ea98d1abdc4c1816310d9cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f75c793c7a6669d0f13c8a4936fbfad3f2aee3345f6458101c22dcb1a5b87343ab54f162734f905b4e83f25812824034b719823e7d1eb9a8dc05efbe5485478"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 18:34:09.483697) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:34:09.484043) ========= 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=52faae308a556c440401f8e5000001e8-1468370779.783339-2004812149 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004413 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 18:34:09.568784) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:34:09.569128) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 18:34:09.569463) ========= ========= Total master_lag: 0.20 =========