builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1470808044.43 results: success (0) buildid: 20160809210458 builduid: c0cce048ef7b4e618e942baafc15adc6 revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.432266) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.432744) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.433081) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.456093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.456412) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-09 22:47:24-- 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% 222M=0s 2016-08-09 22:47:24 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.635294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.635589) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019992 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.690997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:24.691329) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c9bbdb627b7804fee47aa6a6708647e6e589d09c --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 c9bbdb627b7804fee47aa6a6708647e6e589d09c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-09 22:47:24,945 truncating revision to first 12 chars 2016-08-09 22:47:24,945 Setting DEBUG logging. 2016-08-09 22:47:24,946 attempt 1/10 2016-08-09 22:47:24,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c9bbdb627b78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 22:47:25,243 unpacking tar archive at: fx-team-c9bbdb627b78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643064 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:25.349269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:25.349572) ========= script_repo_revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:25.349956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:25.350235) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:25.361880) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-09 22:47:25.362173) ========= /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=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:47:25 INFO - MultiFileLogger online at 20160809 22:47:25 in /builds/slave/test 22:47:25 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 22:47:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:25 INFO - {'append_to_log': False, 22:47:25 INFO - 'base_work_dir': '/builds/slave/test', 22:47:25 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:47:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:25 INFO - 'branch': 'Fx-Team-Non-PGO', 22:47:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:25 INFO - 'config_files': ('talos/linux_config.py',), 22:47:25 INFO - 'default_actions': ('clobber', 22:47:25 INFO - 'read-buildbot-config', 22:47:25 INFO - 'download-and-extract', 22:47:25 INFO - 'populate-webroot', 22:47:25 INFO - 'create-virtualenv', 22:47:25 INFO - 'install', 22:47:25 INFO - 'run-tests'), 22:47:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:25 INFO - 'download_minidump_stackwalk': True, 22:47:25 INFO - 'download_symbols': 'ondemand', 22:47:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:25 INFO - 'installer_path': 'installer.exe', 22:47:25 INFO - 'log_level': 'info', 22:47:25 INFO - 'log_name': 'talos', 22:47:25 INFO - 'log_to_console': True, 22:47:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:47:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:47:25 INFO - 'opt_config_files': (), 22:47:25 INFO - 'pip_index': False, 22:47:25 INFO - 'sps_profile': False, 22:47:25 INFO - 'sps_profile_interval': 0, 22:47:25 INFO - 'suite': 'dromaeojs', 22:47:25 INFO - 'system_bits': '32', 22:47:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:25 INFO - 'title': 'talos-linux64-ix-036', 22:47:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:25 INFO - 'use_talos_json': True, 22:47:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:25 INFO - 'work_dir': 'build'} 22:47:25 INFO - [mozharness: 2016-08-10 05:47:25.705868Z] Running clobber step. 22:47:25 INFO - Running pre-action listener: _resource_record_pre_action 22:47:25 INFO - Running main action method: clobber 22:47:25 INFO - rmtree: /builds/slave/test/build 22:47:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:26 INFO - Running post-action listener: _resource_record_post_action 22:47:26 INFO - [mozharness: 2016-08-10 05:47:26.666668Z] Finished clobber step (success) 22:47:26 INFO - [mozharness: 2016-08-10 05:47:26.666767Z] Running read-buildbot-config step. 22:47:26 INFO - Running pre-action listener: _resource_record_pre_action 22:47:26 INFO - Running main action method: read_buildbot_config 22:47:26 INFO - Using buildbot properties: 22:47:26 INFO - { 22:47:26 INFO - "project": "", 22:47:26 INFO - "product": "firefox", 22:47:26 INFO - "script_repo_revision": "production", 22:47:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:47:26 INFO - "repository": "", 22:47:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:47:26 INFO - "buildid": "20160809210458", 22:47:26 INFO - "pgo_build": "False", 22:47:26 INFO - "basedir": "/builds/slave/test", 22:47:26 INFO - "buildnumber": 2088, 22:47:26 INFO - "slavename": "talos-linux64-ix-036", 22:47:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:47:26 INFO - "platform": "ubuntu64_hw", 22:47:26 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 22:47:26 INFO - "branch": "fx-team", 22:47:26 INFO - "repo_path": "integration/fx-team", 22:47:26 INFO - "revision": "c9bbdb627b7804fee47aa6a6708647e6e589d09c", 22:47:26 INFO - "stage_platform": "linux64", 22:47:26 INFO - "builduid": "c0cce048ef7b4e618e942baafc15adc6", 22:47:26 INFO - "slavebuilddir": "test" 22:47:26 INFO - } 22:47:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:47:26 INFO - Running post-action listener: _resource_record_post_action 22:47:26 INFO - [mozharness: 2016-08-10 05:47:26.669182Z] Finished read-buildbot-config step (success) 22:47:26 INFO - [mozharness: 2016-08-10 05:47:26.669271Z] Running download-and-extract step. 22:47:26 INFO - Running pre-action listener: _resource_record_pre_action 22:47:26 INFO - Running main action method: download_and_extract 22:47:26 INFO - mkdir: /builds/slave/test/build/tests 22:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:26 INFO - trying https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:47:26 INFO - Downloading https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:47:27 INFO - Downloaded 1361 bytes. 22:47:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:47:27 INFO - Using the following test package requirements: 22:47:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:47:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:47:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'jsshell-linux-x86_64.zip'], 22:47:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:47:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:47:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:47:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:47:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:47:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:27 INFO - trying https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:47:27 INFO - Downloading https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:47:28 INFO - Downloaded 20259579 bytes. 22:47:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:47:29 INFO - Return code: 0 22:47:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:29 INFO - trying https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:47:29 INFO - Downloading https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:47:30 INFO - Downloaded 20259579 bytes. 22:47:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:47:32 INFO - Return code: 0 22:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:32 INFO - trying https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:32 INFO - Downloading https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:47:33 INFO - Downloaded 14413723 bytes. 22:47:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:47:33 INFO - Return code: 0 22:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:33 INFO - trying https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:47:33 INFO - Downloading https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:47:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:47:36 INFO - Downloaded 55632208 bytes. 22:47:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:47:36 INFO - mkdir: /builds/slave/test/properties 22:47:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:47:36 INFO - Writing to file /builds/slave/test/properties/build_url 22:47:36 INFO - Contents: 22:47:36 INFO - build_url:https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:47:36 INFO - Running post-action listener: _resource_record_post_action 22:47:36 INFO - Running post-action listener: set_extra_try_arguments 22:47:36 INFO - [mozharness: 2016-08-10 05:47:36.995379Z] Finished download-and-extract step (success) 22:47:36 INFO - [mozharness: 2016-08-10 05:47:36.995612Z] Running populate-webroot step. 22:47:36 INFO - Running pre-action listener: _resource_record_pre_action 22:47:36 INFO - Running main action method: populate_webroot 22:47:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:47:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:47:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:47:37 INFO - '--tppagecycles', 22:47:37 INFO - '7'], 22:47:37 INFO - 'tests': ['tsvgm']}}, 22:47:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:47:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:47:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:47:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:47:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['damp', 'tps']}, 22:47:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['damp', 'tps']}, 22:47:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['dromaeo_dom']}, 22:47:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:47:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['basic_compositor_video']}, 22:47:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:47:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['a11yr', 22:47:37 INFO - 'ts_paint', 22:47:37 INFO - 'tpaint', 22:47:37 INFO - 'sessionrestore', 22:47:37 INFO - 'sessionrestore_no_auto_restore', 22:47:37 INFO - 'tabpaint']}, 22:47:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tsvgx', 22:47:37 INFO - 'tsvgr_opacity', 22:47:37 INFO - 'tart', 22:47:37 INFO - 'tscrollx', 22:47:37 INFO - 'cart']}, 22:47:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:47:37 INFO - 'tsvgr_opacity', 22:47:37 INFO - 'tart', 22:47:37 INFO - 'tscrollx', 22:47:37 INFO - 'cart']}, 22:47:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s'], 22:47:37 INFO - 'tests': ['tp5o']}, 22:47:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'tests': ['tp5o']}, 22:47:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--disable-e10s', 22:47:37 INFO - '--xperf_path', 22:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:37 INFO - 'tests': ['tp5n']}, 22:47:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:37 INFO - 'talos_options': ['--xperf_path', 22:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:37 INFO - 'tests': ['tp5n']}}, 22:47:37 INFO - 'talos.zip': {'path': '', 22:47:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:47:37 INFO - Running post-action listener: _resource_record_post_action 22:47:37 INFO - [mozharness: 2016-08-10 05:47:37.017343Z] Finished populate-webroot step (success) 22:47:37 INFO - [mozharness: 2016-08-10 05:47:37.017432Z] Running create-virtualenv step. 22:47:37 INFO - Running pre-action listener: _resource_record_pre_action 22:47:37 INFO - Running main action method: create_virtualenv 22:47:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:47:37 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 22:47:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:47:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:47:37 INFO - Using real prefix '/usr' 22:47:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:47:37 INFO - Installing distribute.............................................................................................................................................................................................done. 22:47:39 INFO - Installing pip.................done. 22:47:39 INFO - Return code: 0 22:47:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:39 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:39 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 22:47:39 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 22:47:39 INFO - Using env: {'DISPLAY': ':0', 22:47:39 INFO - 'HOME': '/home/cltbld', 22:47:39 INFO - 'LANG': 'en_US.UTF-8', 22:47:39 INFO - 'LANGUAGE': 'en_US:en', 22:47:39 INFO - 'LOGNAME': 'cltbld', 22:47:39 INFO - 'MAIL': '/var/mail/cltbld', 22:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:39 INFO - 'MOZ_NO_REMOTE': '1', 22:47:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:39 INFO - 'NO_EM_RESTART': '1', 22:47:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:39 INFO - 'PWD': '/builds/slave/test', 22:47:39 INFO - 'SHELL': '/bin/bash', 22:47:39 INFO - 'SHLVL': '1', 22:47:39 INFO - 'TERM': 'linux', 22:47:39 INFO - 'TMOUT': '86400', 22:47:39 INFO - 'USER': 'cltbld', 22:47:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:39 INFO - '_': '/tools/buildbot/bin/python'} 22:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:39 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 22:47:39 INFO - Cleaning up... 22:47:39 INFO - Return code: 0 22:47:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:39 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:39 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 22:47:39 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 22:47:39 INFO - Using env: {'DISPLAY': ':0', 22:47:39 INFO - 'HOME': '/home/cltbld', 22:47:39 INFO - 'LANG': 'en_US.UTF-8', 22:47:39 INFO - 'LANGUAGE': 'en_US:en', 22:47:39 INFO - 'LOGNAME': 'cltbld', 22:47:39 INFO - 'MAIL': '/var/mail/cltbld', 22:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:39 INFO - 'MOZ_NO_REMOTE': '1', 22:47:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:39 INFO - 'NO_EM_RESTART': '1', 22:47:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:39 INFO - 'PWD': '/builds/slave/test', 22:47:39 INFO - 'SHELL': '/bin/bash', 22:47:39 INFO - 'SHLVL': '1', 22:47:39 INFO - 'TERM': 'linux', 22:47:39 INFO - 'TMOUT': '86400', 22:47:39 INFO - 'USER': 'cltbld', 22:47:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:39 INFO - '_': '/tools/buildbot/bin/python'} 22:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:40 INFO - Downloading/unpacking psutil>=3.1.1 22:47:40 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 22:47:40 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 22:47:40 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 22:47:40 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 22:47:40 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 22:47:40 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 22:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:42 INFO - Installing collected packages: psutil 22:47:42 INFO - Running setup.py install for psutil 22:47:42 INFO - building 'psutil._psutil_linux' extension 22:47:42 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 22:47:42 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 22:47:42 INFO - building 'psutil._psutil_posix' extension 22:47:42 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 22:47:42 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 22:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:42 INFO - Successfully installed psutil 22:47:42 INFO - Cleaning up... 22:47:42 INFO - Return code: 0 22:47:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:42 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:42 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 22:47:42 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 22:47:42 INFO - Using env: {'DISPLAY': ':0', 22:47:42 INFO - 'HOME': '/home/cltbld', 22:47:42 INFO - 'LANG': 'en_US.UTF-8', 22:47:42 INFO - 'LANGUAGE': 'en_US:en', 22:47:42 INFO - 'LOGNAME': 'cltbld', 22:47:42 INFO - 'MAIL': '/var/mail/cltbld', 22:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:42 INFO - 'MOZ_NO_REMOTE': '1', 22:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:42 INFO - 'NO_EM_RESTART': '1', 22:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:42 INFO - 'PWD': '/builds/slave/test', 22:47:42 INFO - 'SHELL': '/bin/bash', 22:47:42 INFO - 'SHLVL': '1', 22:47:42 INFO - 'TERM': 'linux', 22:47:42 INFO - 'TMOUT': '86400', 22:47:42 INFO - 'USER': 'cltbld', 22:47:42 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:42 INFO - '_': '/tools/buildbot/bin/python'} 22:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:47:42 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 22:47:42 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 22:47:42 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 22:47:42 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 22:47:42 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 22:47:42 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 22:47:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:47:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:47:44 INFO - Installing collected packages: mozsystemmonitor 22:47:44 INFO - Running setup.py install for mozsystemmonitor 22:47:44 INFO - Successfully installed mozsystemmonitor 22:47:44 INFO - Cleaning up... 22:47:44 INFO - Return code: 0 22:47:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:44 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:44 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 22:47:44 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 22:47:44 INFO - Using env: {'DISPLAY': ':0', 22:47:44 INFO - 'HOME': '/home/cltbld', 22:47:44 INFO - 'LANG': 'en_US.UTF-8', 22:47:44 INFO - 'LANGUAGE': 'en_US:en', 22:47:44 INFO - 'LOGNAME': 'cltbld', 22:47:44 INFO - 'MAIL': '/var/mail/cltbld', 22:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:44 INFO - 'MOZ_NO_REMOTE': '1', 22:47:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:44 INFO - 'NO_EM_RESTART': '1', 22:47:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:44 INFO - 'PWD': '/builds/slave/test', 22:47:44 INFO - 'SHELL': '/bin/bash', 22:47:44 INFO - 'SHLVL': '1', 22:47:44 INFO - 'TERM': 'linux', 22:47:44 INFO - 'TMOUT': '86400', 22:47:44 INFO - 'USER': 'cltbld', 22:47:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:44 INFO - '_': '/tools/buildbot/bin/python'} 22:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:45 INFO - Downloading/unpacking jsonschema==2.5.1 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:47:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:47 INFO - Downloading functools32-3.2.3-2.tar.gz 22:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:47:47 INFO - warning: no files found matching '*.txt' 22:47:47 INFO - no previously-included directories found matching 'build' 22:47:47 INFO - no previously-included directories found matching 'dist' 22:47:47 INFO - no previously-included directories found matching '.git*' 22:47:47 INFO - Installing collected packages: jsonschema, functools32 22:47:47 INFO - Running setup.py install for functools32 22:47:47 INFO - warning: no files found matching '*.txt' 22:47:47 INFO - no previously-included directories found matching 'build' 22:47:47 INFO - no previously-included directories found matching 'dist' 22:47:47 INFO - no previously-included directories found matching '.git*' 22:47:47 INFO - Successfully installed jsonschema functools32 22:47:47 INFO - Cleaning up... 22:47:47 INFO - Return code: 0 22:47:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:47:47 INFO - Using env: {'DISPLAY': ':0', 22:47:47 INFO - 'HOME': '/home/cltbld', 22:47:47 INFO - 'LANG': 'en_US.UTF-8', 22:47:47 INFO - 'LANGUAGE': 'en_US:en', 22:47:47 INFO - 'LOGNAME': 'cltbld', 22:47:47 INFO - 'MAIL': '/var/mail/cltbld', 22:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:47 INFO - 'MOZ_NO_REMOTE': '1', 22:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:47 INFO - 'NO_EM_RESTART': '1', 22:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:47 INFO - 'PWD': '/builds/slave/test', 22:47:47 INFO - 'SHELL': '/bin/bash', 22:47:47 INFO - 'SHLVL': '1', 22:47:47 INFO - 'TERM': 'linux', 22:47:47 INFO - 'TMOUT': '86400', 22:47:47 INFO - 'USER': 'cltbld', 22:47:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:47 INFO - '_': '/tools/buildbot/bin/python'} 22:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:47:47 INFO - Cleaning up... 22:47:47 INFO - Return code: 0 22:47:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:47 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:47 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 22:47:47 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 22:47:47 INFO - Using env: {'DISPLAY': ':0', 22:47:47 INFO - 'HOME': '/home/cltbld', 22:47:47 INFO - 'LANG': 'en_US.UTF-8', 22:47:47 INFO - 'LANGUAGE': 'en_US:en', 22:47:47 INFO - 'LOGNAME': 'cltbld', 22:47:47 INFO - 'MAIL': '/var/mail/cltbld', 22:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:47 INFO - 'MOZ_NO_REMOTE': '1', 22:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:47 INFO - 'NO_EM_RESTART': '1', 22:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:47 INFO - 'PWD': '/builds/slave/test', 22:47:47 INFO - 'SHELL': '/bin/bash', 22:47:47 INFO - 'SHLVL': '1', 22:47:47 INFO - 'TERM': 'linux', 22:47:47 INFO - 'TMOUT': '86400', 22:47:47 INFO - 'USER': 'cltbld', 22:47:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:47 INFO - '_': '/tools/buildbot/bin/python'} 22:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:47 INFO - Downloading/unpacking blobuploader==1.2.4 22:47:47 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 22:47:47 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 22:47:47 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 22:47:47 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 22:47:47 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 22:47:47 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 22:47:49 INFO - Downloading blobuploader-1.2.4.tar.gz 22:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:47:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:47:49 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 22:47:49 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 22:47:49 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 22:47:49 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 22:47:49 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 22:47:49 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 22:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:47:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:47:50 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 22:47:50 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 22:47:50 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 22:47:50 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 22:47:50 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 22:47:50 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 22:47:50 INFO - Downloading docopt-0.6.1.tar.gz 22:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:47:50 INFO - Installing collected packages: blobuploader, requests, docopt 22:47:50 INFO - Running setup.py install for blobuploader 22:47:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:47:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:47:50 INFO - Running setup.py install for requests 22:47:50 INFO - Running setup.py install for docopt 22:47:51 INFO - Successfully installed blobuploader requests docopt 22:47:51 INFO - Cleaning up... 22:47:51 INFO - Return code: 0 22:47:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:51 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:51 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 22:47:51 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 22:47:51 INFO - Using env: {'DISPLAY': ':0', 22:47:51 INFO - 'HOME': '/home/cltbld', 22:47:51 INFO - 'LANG': 'en_US.UTF-8', 22:47:51 INFO - 'LANGUAGE': 'en_US:en', 22:47:51 INFO - 'LOGNAME': 'cltbld', 22:47:51 INFO - 'MAIL': '/var/mail/cltbld', 22:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:51 INFO - 'MOZ_NO_REMOTE': '1', 22:47:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:51 INFO - 'NO_EM_RESTART': '1', 22:47:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:51 INFO - 'PWD': '/builds/slave/test', 22:47:51 INFO - 'SHELL': '/bin/bash', 22:47:51 INFO - 'SHLVL': '1', 22:47:51 INFO - 'TERM': 'linux', 22:47:51 INFO - 'TMOUT': '86400', 22:47:51 INFO - 'USER': 'cltbld', 22:47:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:51 INFO - '_': '/tools/buildbot/bin/python'} 22:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:47:51 INFO - Running setup.py (path:/tmp/pip-03HyWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:47:51 INFO - Running setup.py (path:/tmp/pip-L4FQVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:47:51 INFO - Running setup.py (path:/tmp/pip-RQksLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:47:51 INFO - Running setup.py (path:/tmp/pip-G6QRFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:47:51 INFO - Running setup.py (path:/tmp/pip-qziXjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:47:51 INFO - Running setup.py (path:/tmp/pip-9Ovl5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:47:51 INFO - Running setup.py (path:/tmp/pip-U8gfDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:47:51 INFO - Running setup.py (path:/tmp/pip-z_Ttd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:47:51 INFO - Running setup.py (path:/tmp/pip-j1WeeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:47:51 INFO - Running setup.py (path:/tmp/pip-IoJn13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:47:51 INFO - Running setup.py (path:/tmp/pip-ekR3jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:47:52 INFO - Running setup.py (path:/tmp/pip-NByyzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:47:52 INFO - Running setup.py (path:/tmp/pip-Gknn1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:47:52 INFO - Running setup.py (path:/tmp/pip-_o94Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:52 INFO - Running setup.py (path:/tmp/pip-__xzQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:47:52 INFO - Running setup.py (path:/tmp/pip-1RcK91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:47:52 INFO - Running setup.py (path:/tmp/pip-Mh3wmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:47:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:47:52 INFO - Running setup.py install for manifestparser 22:47:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:47:52 INFO - Running setup.py install for mozcrash 22:47:52 INFO - Running setup.py install for mozdebug 22:47:52 INFO - Running setup.py install for mozdevice 22:47:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:47:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:47:52 INFO - Running setup.py install for mozfile 22:47:53 INFO - Running setup.py install for mozhttpd 22:47:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozinfo 22:47:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozInstall 22:47:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozleak 22:47:53 INFO - Running setup.py install for mozlog 22:47:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for moznetwork 22:47:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozprocess 22:47:53 INFO - Running setup.py install for mozprofile 22:47:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozrunner 22:47:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:47:53 INFO - Running setup.py install for mozscreenshot 22:47:54 INFO - Running setup.py install for moztest 22:47:54 INFO - Running setup.py install for mozversion 22:47:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:47:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:47:54 INFO - Cleaning up... 22:47:54 INFO - Return code: 0 22:47:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:54 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:54 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 22:47:54 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 22:47:54 INFO - Using env: {'DISPLAY': ':0', 22:47:54 INFO - 'HOME': '/home/cltbld', 22:47:54 INFO - 'LANG': 'en_US.UTF-8', 22:47:54 INFO - 'LANGUAGE': 'en_US:en', 22:47:54 INFO - 'LOGNAME': 'cltbld', 22:47:54 INFO - 'MAIL': '/var/mail/cltbld', 22:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:54 INFO - 'MOZ_NO_REMOTE': '1', 22:47:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:54 INFO - 'NO_EM_RESTART': '1', 22:47:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:54 INFO - 'PWD': '/builds/slave/test', 22:47:54 INFO - 'SHELL': '/bin/bash', 22:47:54 INFO - 'SHLVL': '1', 22:47:54 INFO - 'TERM': 'linux', 22:47:54 INFO - 'TMOUT': '86400', 22:47:54 INFO - 'USER': 'cltbld', 22:47:54 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:54 INFO - '_': '/tools/buildbot/bin/python'} 22:47:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:47:54 INFO - Running setup.py (path:/tmp/pip-0rcZ97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:47:54 INFO - Running setup.py (path:/tmp/pip-1PFLgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:47:54 INFO - Running setup.py (path:/tmp/pip-CYxyWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:47:54 INFO - Running setup.py (path:/tmp/pip-TYKz9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:47:54 INFO - Running setup.py (path:/tmp/pip-VHQooy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:47:54 INFO - Running setup.py (path:/tmp/pip-PjQwPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:47:54 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)) 22:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:47:54 INFO - Running setup.py (path:/tmp/pip-AWyyG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:47:55 INFO - Running setup.py (path:/tmp/pip-Q6dAAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:47:55 INFO - Running setup.py (path:/tmp/pip-PZSBSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:47:55 INFO - Running setup.py (path:/tmp/pip-LTGxF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:47:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:47:55 INFO - Running setup.py (path:/tmp/pip-pnk4e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:47:55 INFO - Running setup.py (path:/tmp/pip-XbI6zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:47:55 INFO - Running setup.py (path:/tmp/pip-1Xkhey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:47:55 INFO - Running setup.py (path:/tmp/pip-jPSXP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:55 INFO - Running setup.py (path:/tmp/pip-Cm6930-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:47:55 INFO - Running setup.py (path:/tmp/pip-Vh_0Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:47:55 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)) 22:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:47:55 INFO - Running setup.py (path:/tmp/pip-OTdC0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:47:55 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)) 22:47:55 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)) 22:47:55 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)) 22:47:55 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)) 22:47:55 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)) 22:47:55 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)) 22:47:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:47:55 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 22:47:55 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 22:47:55 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 22:47:55 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 22:47:55 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 22:47:55 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 22:47:57 INFO - Downloading blessings-1.6.tar.gz 22:47:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:47:57 INFO - Installing collected packages: blessings 22:47:57 INFO - Running setup.py install for blessings 22:47:57 INFO - Successfully installed blessings 22:47:57 INFO - Cleaning up... 22:47:57 INFO - Return code: 0 22:47:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:47:58 INFO - Reading from file tmpfile_stdout 22:47:58 INFO - Current package versions: 22:47:58 INFO - argparse == 1.2.1 22:47:58 INFO - blessings == 1.6 22:47:58 INFO - blobuploader == 1.2.4 22:47:58 INFO - docopt == 0.6.1 22:47:58 INFO - functools32 == 3.2.3-2 22:47:58 INFO - jsonschema == 2.5.1 22:47:58 INFO - manifestparser == 1.1 22:47:58 INFO - mozInstall == 1.12 22:47:58 INFO - mozcrash == 0.17 22:47:58 INFO - mozdebug == 0.1 22:47:58 INFO - mozdevice == 0.48 22:47:58 INFO - mozfile == 1.2 22:47:58 INFO - mozhttpd == 0.7 22:47:58 INFO - mozinfo == 0.9 22:47:58 INFO - mozleak == 0.1 22:47:58 INFO - mozlog == 3.3 22:47:58 INFO - moznetwork == 0.27 22:47:58 INFO - mozprocess == 0.23 22:47:58 INFO - mozprofile == 0.28 22:47:58 INFO - mozrunner == 6.12 22:47:58 INFO - mozscreenshot == 0.1 22:47:58 INFO - mozsystemmonitor == 0.3 22:47:58 INFO - moztest == 0.7 22:47:58 INFO - mozversion == 1.4 22:47:58 INFO - psutil == 3.1.1 22:47:58 INFO - requests == 1.2.3 22:47:58 INFO - wsgiref == 0.1.2 22:47:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:58 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 22:47:58 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 22:47:58 INFO - Using env: {'DISPLAY': ':0', 22:47:58 INFO - 'HOME': '/home/cltbld', 22:47:58 INFO - 'LANG': 'en_US.UTF-8', 22:47:58 INFO - 'LANGUAGE': 'en_US:en', 22:47:58 INFO - 'LOGNAME': 'cltbld', 22:47:58 INFO - 'MAIL': '/var/mail/cltbld', 22:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:58 INFO - 'MOZ_NO_REMOTE': '1', 22:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:58 INFO - 'NO_EM_RESTART': '1', 22:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:58 INFO - 'PWD': '/builds/slave/test', 22:47:58 INFO - 'SHELL': '/bin/bash', 22:47:58 INFO - 'SHLVL': '1', 22:47:58 INFO - 'TERM': 'linux', 22:47:58 INFO - 'TMOUT': '86400', 22:47:58 INFO - 'USER': 'cltbld', 22:47:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:58 INFO - '_': '/tools/buildbot/bin/python'} 22:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 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)) 22:47:58 INFO - Cleaning up... 22:47:58 INFO - Return code: 0 22:47:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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 0x1a20030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ce1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', '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 22:47:58 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 22:47:58 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 22:47:58 INFO - Using env: {'DISPLAY': ':0', 22:47:58 INFO - 'HOME': '/home/cltbld', 22:47:58 INFO - 'LANG': 'en_US.UTF-8', 22:47:58 INFO - 'LANGUAGE': 'en_US:en', 22:47:58 INFO - 'LOGNAME': 'cltbld', 22:47:58 INFO - 'MAIL': '/var/mail/cltbld', 22:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:58 INFO - 'MOZ_NO_REMOTE': '1', 22:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:58 INFO - 'NO_EM_RESTART': '1', 22:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:58 INFO - 'PWD': '/builds/slave/test', 22:47:58 INFO - 'SHELL': '/bin/bash', 22:47:58 INFO - 'SHLVL': '1', 22:47:58 INFO - 'TERM': 'linux', 22:47:58 INFO - 'TMOUT': '86400', 22:47:58 INFO - 'USER': 'cltbld', 22:47:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:58 INFO - '_': '/tools/buildbot/bin/python'} 22:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:47:58 INFO - Cleaning up... 22:47:58 INFO - Return code: 0 22:47:58 INFO - Running post-action listener: _resource_record_post_action 22:47:58 INFO - Running post-action listener: _start_resource_monitoring 22:47:58 INFO - Starting resource monitoring. 22:47:58 INFO - [mozharness: 2016-08-10 05:47:58.577631Z] Finished create-virtualenv step (success) 22:47:58 INFO - [mozharness: 2016-08-10 05:47:58.578071Z] Running install step. 22:47:58 INFO - Running pre-action listener: _resource_record_pre_action 22:47:58 INFO - Running main action method: install 22:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:47:58 INFO - Reading from file tmpfile_stdout 22:47:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:47:58 INFO - Reading from file tmpfile_stdout 22:47:58 INFO - Output received: 22:47:58 INFO - Usage: mozinstall [options] installer 22:47:58 INFO - Options: 22:47:58 INFO - -h, --help show this help message and exit 22:47:58 INFO - -d DEST, --destination=DEST 22:47:58 INFO - Directory to install application into. [default: 22:47:58 INFO - "/builds/slave/test"] 22:47:58 INFO - --app=APP Application being installed. [default: firefox] 22:47:58 INFO - mkdir: /builds/slave/test/build/application 22:47:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:48:15 INFO - Reading from file tmpfile_stdout 22:48:15 INFO - Output received: 22:48:15 INFO - /builds/slave/test/build/application/firefox/firefox 22:48:15 INFO - Running post-action listener: _resource_record_post_action 22:48:15 INFO - [mozharness: 2016-08-10 05:48:15.242641Z] Finished install step (success) 22:48:15 INFO - [mozharness: 2016-08-10 05:48:15.242888Z] Running run-tests step. 22:48:15 INFO - Running pre-action listener: _resource_record_pre_action 22:48:15 INFO - Running main action method: run_tests 22:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:48:15 INFO - Python 2.7.3 22:48:15 INFO - Return code: 0 22:48:15 INFO - grabbing minidump binary from tooltool 22:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:15 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 0x1cdf3b0>, '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 0x1ce02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ce0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:48:15 INFO - Return code: 0 22:48:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:48:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:48:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:48:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:48:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:48:15 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/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:48:15 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/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:48:15 INFO - Using env: {'DISPLAY': ':0', 22:48:15 INFO - 'HOME': '/home/cltbld', 22:48:15 INFO - 'LANG': 'en_US.UTF-8', 22:48:15 INFO - 'LANGUAGE': 'en_US:en', 22:48:15 INFO - 'LOGNAME': 'cltbld', 22:48:15 INFO - 'MAIL': '/var/mail/cltbld', 22:48:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:48:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:15 INFO - 'MOZ_NO_REMOTE': '1', 22:48:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:15 INFO - 'NO_EM_RESTART': '1', 22:48:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:15 INFO - 'PWD': '/builds/slave/test', 22:48:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:48:15 INFO - 'SHELL': '/bin/bash', 22:48:15 INFO - 'SHLVL': '1', 22:48:15 INFO - 'TERM': 'linux', 22:48:15 INFO - 'TMOUT': '86400', 22:48:15 INFO - 'USER': 'cltbld', 22:48:15 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892', 22:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:15 INFO - '_': '/tools/buildbot/bin/python'} 22:48:15 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/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:48:15 INFO - mozversion application_buildid: 20160809210458 22:48:15 INFO - mozversion application_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:48:15 INFO - mozversion application_display_name: Nightly 22:48:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:48:15 INFO - mozversion application_name: Firefox 22:48:15 INFO - mozversion application_remotingname: firefox 22:48:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:48:15 INFO - mozversion application_vendor: Mozilla 22:48:15 INFO - mozversion application_version: 51.0a1 22:48:15 INFO - mozversion platform_buildid: 20160809210458 22:48:15 INFO - mozversion platform_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:48:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:48:15 INFO - mozversion platform_version: 51.0a1 22:48:15 INFO - using testdate: 1470808095 22:48:15 INFO - actual date: 1470808095 22:48:15 INFO - starting webserver on 'localhost:53461' 22:48:15 INFO - SUITE-START | Running 2 tests 22:48:15 INFO - TEST-START | dromaeo_css 22:48:15 INFO - operating with platform_type : linux_ 22:48:15 INFO - Initialising browser for dromaeo_css test... 22:48:15 INFO - TEST-INFO | started process 2756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptQXgmN/profile http://localhost:53461/getInfo.html) 22:48:19 INFO - PROCESS | 2756 | __metrics Screen width/height:1600/1200 22:48:19 INFO - PROCESS | 2756 | colorDepth:24 22:48:19 INFO - PROCESS | 2756 | Browser inner width/height: 1024/697 22:48:19 INFO - PROCESS | 2756 | __metrics 22:48:20 INFO - PROCESS | 2756 | 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" 22:48:20 INFO - PROCESS | 2756 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:48:21 INFO - TEST-INFO | 2756: exit 0 22:48:21 INFO - Browser initialized. 22:48:21 INFO - Running cycle 1/1 for dromaeo_css test... 22:48:21 INFO - TEST-INFO | started process 2891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptQXgmN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:48:33 INFO - PROCESS | 2891 | RSS: Main: 157057024 22:48:33 INFO - PROCESS | 2891 | 22:49:25 INFO - PROCESS | 2891 | 1470808165260 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] 22:51:37 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53461/tests/dromaeo/cssquery-ext.html) 22:51:38 INFO - PROCESS | 2891 | RSS: Main: 247033856 22:51:38 INFO - PROCESS | 2891 | 22:54:40 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-ext.html (next: http://localhost:53461/tests/dromaeo/cssquery-jquery.html) 22:54:41 INFO - PROCESS | 2891 | RSS: Main: 247812096 22:54:41 INFO - PROCESS | 2891 | 22:57:43 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53461/tests/dromaeo/cssquery-mootools.html) 22:57:44 INFO - PROCESS | 2891 | RSS: Main: 189014016 22:57:44 INFO - PROCESS | 2891 | 23:00:46 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53461/tests/dromaeo/cssquery-prototype.html) 23:00:47 INFO - PROCESS | 2891 | RSS: Main: 189222912 23:00:47 INFO - PROCESS | 2891 | 23:03:50 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53461/tests/dromaeo/cssquery-yui.html) 23:03:50 INFO - PROCESS | 2891 | RSS: Main: 189108224 23:03:50 INFO - PROCESS | 2891 | 23:06:53 INFO - PROCESS | 2891 | Cycle 1(1): loaded http://localhost:53461/tests/dromaeo/cssquery-yui.html (next: http://localhost:53461/tests/dromaeo/cssquery-dojo.html) 23:06:54 INFO - PROCESS | 2891 | RSS: Main: 281784320 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | __start_tp_report 23:06:54 INFO - PROCESS | 2891 | _x_x_mozilla_page_load 23:06:54 INFO - PROCESS | 2891 | _x_x_mozilla_page_load_details 23:06:54 INFO - PROCESS | 2891 | |i|pagename|runs| 23:06:54 INFO - PROCESS | 2891 | |0;dojo.html;2619.6070589411597;2622.5410553153197;2649.920502384928;2654.2170059832347;2661.135131082398;4483.6861419700635;4543.863684089474;4712.340272361872;4750.952490475094;4771.689840160391;4548.295014272793;4567.65742569307;4635.930461043087;4723.669343145981;4754.953910139764;4344.587264209895;4378.124375124972;4401.911961760763;4403.361512580674;4427.623651989585;4352.12957408518;4376.5623437656295;4425.269830477967;4448.666350023745;4485.730856148642;4437.35658329542;4441.689081764278;4466.441694788152;4472.329150627415;4490.461144662629;4165.91668166638;4361.4112094867105;4393.472783265996;4401.713888597263;4403.933940990888;4192.748435093904;4221.957780422218;4225.577442255781;4261.4673165854265;4326.480822301344;4639.559241872017;4697.154512187839;4699.224627936386;4703.905921881543;4709.646776491777;5025.522575355335;5061.670991385585;5066.392032956032;5067.037262920034;5077;4840.685355451919;4887.584555312767;4996.950030499721;5041.319421878036;5055.5702765265;4764.714117152913;4777.283407488835;4816.54242846929;4822.059698358787;4822.204336284473;5254.290670759398;5260.053190425683;5268.130758424893;5268.420473747885;5271.868203294948;4812.711237325771;4854.83008094722;4984.601231901439;5005.199168133081;5009.649324547319;4926.113299606105;4962.379702537183;4963.60291176705;5015.072211640858;5016.423111342169;4905.9264111038365;4914.483979182206;4914.967856750122;4918.8770280743265;4959.603231741452;4779.593734532604;4782.115308667908;4891.706497610155;4908.288298196741;4955.653104282737;4230.555791641833;4244.3633454982;4251.234777740036;4255.659547236213;4287.699861009762;3754.2866855297952;3795.449659799314;3797.430385442206;3807.2956503047026;3829.7702137871815;4295.591918767712;4299.441072660597;4301.677374196947;4340.131973605228;4343.04453020335;4836.613070954315;4919.3358896549225;5063.569596584258;5074.213496907985;5112.795488180505;4252.808623611945;4264.061906380592;4289.292266775947;4348.934765978513;4356.803943822536;4577.427821522309;4588.403507543998;4591.65562582801;4593.724376537419;4640.767961601907;4753.738544380092;4761.83347056914;4762.928556071731;4765.9523404765505;4814.374131362971;4769.403824521934;4988.226824842155;5052.797888084436;5060.089183946925;5135.563477104488;4750.429948406075;4751.524847515221;4758.8096476141955;4763.190257656138;4778.546038126373;4986.376702912136;4988.45127036033;4993.201087825931;5007.524285192901;5058.215976523645;4759.214729569582;4970.378702662168;5030.773615187252;5033.899322013613;5074.188129899198;4901.09329773993;4932.284818701197;4934.9753251233515;4940.2342636891335;4981.925271120864;4910.189818680021;4922.778474968707;4937.136001199704;4942.308076869169;4979.427365852974;4621.745803980679;4797.40032495938;4845.394325709286;4867.805287788448;4885.6180616190595;4957.206846904478;4970.378702662168;4972;4972.980538989426;5044.646874718735;4879.634027447884;4886.682365646222;4891.19295316283;4891.461939186615;4934.037862616755 23:06:54 INFO - PROCESS | 2891 | |1;ext.html;570.6290910907912;576.374633522628;577.9306483222014;580.3529065092421;590.2739630254787;29369.559456608167;39809.80095099524;41403.79298103509;41572.37641435381;45142.77428612852;41743;42132;42235.78882105585;42257.788711056404;42265.36601950965;7688.269614386639;7885.8028549286155;7895.723649672263;7896.091949425803;7896.249856263646;7727.806804829868;7788.376929845627;7807.804804879866;7837.960810195941;7840.294373506384;7822.178671239523;7866.646000929972;7878.054633443995;7880.448368614199;7883.014623172104;7824.687012519521;7850.214978502104;7861.292483676497;7867.449278550504;7871.881921771178;7649.732259370895;7707.229277072304;7711.190325015848;7713.884291735629;7722.459427840054;39186.412203816966;39474.407883881766;39519.60480395217;39552.80223598892;39589.20821583581;3128.1084890806237;3135.372925414926;3327.15157634804;3341.4319565673895;3449.2066824630224;29095.41809163805;29217.70782292171;29597.260068498246;30362.84818575915;30805.53791693104;3586.8027258500774;3588;3588.3002814451193;3604.6215147409407;3632.54593175853;190630;191368;191723;192178;192481;32175.678243217735;53923.46076539263;54269.72865135728;54286.72856635691;54306;32715;34779.652203478145;34828;34829;34837;14061.578152655436;14228.502002429865;14230.28848557568;14246.430142794172;14250.786238206436;10659.680209593724;10662.253642245123;10665;10680.145588352912;10680.679579612468;7895.368370530344;7955.2840244378285;7988.281054705104;8065.6370463329295;8087.878681819778;3097.7521798256084;3133.984330078335;3149.0867648382173;3149.889753858604;3161.636411812671;871.4422769427572;915.5056269614333;984.4290311619244;1016.2832926787048;1026.3687831983277;27586.448271034467;27736.583951240344;28015.579766303115;28228.294292642848;28264;904.5838914099456;944.1644144931785;969.4474149734724;977.6773664690777;1004.467632154959;13553.796693049611;13597.32013399326;13658.521951731642;13816;13904;419548;422474;423314;424674;426314;49125.50874491281;49560.256596151085;49596.75201623969;49693.25460118101;49722;1125.870524889648;1154.9480273387549;1183.597576823884;1202.1344631865043;1251.5244207201206;26465.735342646327;29204.853975729984;29667.85166074156;30166.69833301639;30368.69631303747;42858.35712464378;43486.34770478508;43573.12853742971;43604.78197609119;43615.345769814114;332858;334306;336822;337151;337981;25543.744562554137;25755.871220643778;26214.34464138412;26526.734732652425;26535.734642653326;42178.113612216504;42284.788576056926;42735.1452970945;42905.35641965435;43504.564954350055;35600.64399356077;35972.4604130933;36358.8182059088;36709.26581468302;37115.628843711216;35849;35966.640333596326;36604.816975916016;36699.63300367069;36765;27704.86147569249;35239.647603523634;36153.819230903675;37283.81358093192;37323.253534929696;30971.225719357197;32371.838140809145;33076.83461582677;34067.318653626295;34236.14409639779;25747.871260643577;25970.87014564915;26070;26148.34629134287;26170 23:06:54 INFO - PROCESS | 2891 | |2;jquery.html;363.3260302139531;370.48502581411844;370.5608853508593;381.3745457449781;388.4910766895367;16138.919305403471;16711.331546738133;17317;17341.7398739019;17347.132643367844;15688.21558922055;16482.670346593062;16855.83144168558;17079.231434585443;17087.57281067974;4860.2709593560985;5089.51649593288;5089.7455127243675;5092.337996060507;5096.413912400066;3701.0932321581117;3890.2414029264305;5016.899662006757;5637.492625663689;5897.675627840467;5980.850478738044;5985.221921150266;5987.790427335044;5997.130416089664;6001;5589.105743081108;5593.972030139864;5600.887982240332;5618.775248990036;5624.3531993820825;4849.951500484985;4911.557959783637;4925.729084900329;4934.087193869145;4936.111499930011;20399;20507.28224512135;20637.380878573516;20720.06759695817;20820.37538873836;1867.9813201868078;1965.8623896327263;1972.5660354721938;2170.739511258659;2320.6867072945106;12903.225806451643;12908.741825163537;12938;13297;13745;1696.898186108825;1700.6343636118295;1726.6115124097103;1739.4417674876508;1748.9212985415686;69997.65001175013;70690.38775887662;70936.6453167736;71018;72274.2772572278;9450.952745236229;9453.101955314234;9476.620935162655;9514.38156519824;9903.405795652285;13139.34303284832;13257.204567725967;13264.933675331562;13284.402201900937;13285.26931018784;7218.422526197892;7420.517666351778;7486.700531978769;7525.2098529654695;7542.773716788505;7028;7049.68276427562;7213.495055346076;7254.216351374224;7560.470767046364;5836.591438599257;5874.412558744177;6000.699265771011;6013.278406591237;6031.577789554777;1542.7994360733032;1661.418503523757;1732.930682772675;1738.4089409600797;1782.4741701197918;792.7978365516872;803.7347675267148;828.7099515169763;831.3847752663107;865.9437136586101;16378.181090945644;17294.4811655648;17472.91263543674;17639.382621608183;17715.468535943943;889.9688510902088;906.2976193449997;907.5326207003399;915.4004127296631;958.7459323279337;9045.638174473079;9926.553305101288;10001.649942251986;10028.799424011624;10197.847032294521;391014;396420;401101;401521;406289;76639;80840;83650;84223;84457;836.3058661311097;890.7372325163963;891.4784850862318;930.6789157740704;932.9160375566231;11882.643520694393;14530.782038269646;15265.439485954941;15425.69148616999;16069.517914462585;38807.805960970014;38828.2234355317;39062.2187556253;39416.80291598524;39723;52368.73815630897;52988;55446;55621.72189139028;56461;6690.732370705117;6995.4753393494675;7026.262242464468;7120.786376408716;7211.855762884608;6812.420944219797;9113.316501262565;9691.70924872255;11434.485448154686;11835.28988260707;9568.186704130227;9735.853962190715;9777.80444391094;10112.039356261437;10175.338602990782;9922.801543968935;10121.746956326151;10268.743281418025;10321.793564128826;10726.141908647134;9519.714408567754;9782;9787.412755234709;9870.753231169278;10091.69724908254;7191.604461754654;7219.891701624585;7292.197858235484;7297.854042919218;7404.851902961803;9692.41845489273;10046.146077583488;10252.077313041862;10272.126869215901;10417.739556510845 23:06:54 INFO - PROCESS | 2891 | |3;mootools.html;1353.15427857589;1409.4221369238614;1477.830049544302;1650.0512205481843;1660.900345979242;7184.20973692894;7740.45816792824;7744.535327880317;7750.53496790193;7751.341136003432;6651.833704157399;7319.341259286676;7505.437092218078;7533.209013053633;7580.545167289966;2168.2194410012344;2187.103287652063;2193.6270833958265;2198.4393979535275;2199.747029091651;2132.530843214498;2159.1039718516795;2196.3850121965875;2199.626063569197;2205.547862688145;1791.8387345138935;2099.9265025724103;2158.5251244726214;2164.350694791564;2181.2365672014826;2158.266189495579;2177.6080305544997;2193.1775584155944;2195.747489038765;2195.9890200548975;2139.732533433321;2148.0441203664377;2148.473623962124;2151.064287035145;2152.5802468518646;7931.563763992978;7973.162817904153;7979.48133371329;7984.161663025415;7989.161138080476;4434.623057040123;4548.454185497729;4561.384213131218;4581.816727330903;4583.335416364642;2656.9165143205364;2773.62556054934;2779.277387879146;2786.0527420677067;2786.303424143964;824.4599787139433;851.5699571716306;867.1631875240361;869.7869022089566;871.9869201961976;10628.681139565826;10675.466226688712;10686.358818470917;10687.251892367538;10707.089897358734;3913.178232571192;4056.513218413809;4197.013701780079;4200.264953633163;4222.387753775685;9163.633454661738;9168.770780730401;9178.81642367149;9180.357374983822;9187.678431255008;4669.159551280802;4686.5782079613;4699.412573428322;4701.130290896195;4715.858524244278;4105.055837157411;4259.893502662396;4266.0188156724125;4270.10327831159;4278.807453664593;3612.8916132516065;3642.2897534980425;3711.183539621279;3734.3091528067393;3742.045778326509;1140.520981187903;1166.03801863463;1176.3823992404057;1182.7634473105413;1184.5261895241972;571.1353978103256;733.5818583407406;782.941279404047;824.6536454689045;863.7883718489011;5095.566876815512;5146.845594632167;5245.9737701312015;5259.579233661298;5285.2600635911;868.0928429790883;891.5497673674882;902.1384577728254;903.5075883643431;913.0641092879719;3458;3522.5244591980268;3542.5217595624777;3613.457981302826;3618.131648404364;9370.39092458988;9568.234541236685;9756.463394513228;9762.853557196648;9812.950935245279;9691.757706057264;9701.369410988404;9703.320767546345;9727.9027209727;9745.415275083518;7294.379977701955;7523.285287897641;7564.583947882919;7576.772696819105;7581.355584775356;5109.267754457019;5122.078025955364;5221.843344699665;5282.630215884851;5377.838664840061;9003.279737620844;9128.817423651622;9136.269098471974;9138.725838224864;9199.586018629045;8595.226429621363;8934.329925255503;9218.354715169873;9307.627694892399;9460.243180545678;6182.258129024546;6297.748090076346;6303.905441418291;6437.839054023687;6503.44720698727;7437.739679111205;7486.251374862471;7489.588072655838;7499.66251518672;7524.2851929066655;6699.698513566804;6760.729570817309;6842.794716158523;6903.309669033057;7020.824479388056;6693.397594216543;6742.73029078851;6768.390844823793;6769.221539523018;6792.388685018372;6022.367651396716;6025.849353766015;6074.66589337573;6086.452219300284;6086.878262434815;4460.531644177445;4460.6431485481735;4460.977695111504;4503.819847206075;4547.09058188357;4431.446069241345;4488;4577.954220457753;4637.049404871926;4704.92942605868 23:06:54 INFO - PROCESS | 2891 | |4;prototype.html;288.14852112009015;294.65378180637754;297.7558402110268;298.8490812139868;302.85462977770663;4741.9288710669325;4778.136526754569;4789.592884604813;4798.352222449968;4803.375561177051;4753.786079626417;4762.261849413338;4775.14047471455;4778.880527986802;4782.521747825216;3613.2773445310977;3705.4256590228424;3728.552573691161;3741.4761933329355;3762.830672619725;3610.151614370621;3703.6666699996995;3712.1462063725357;3751.5685696144883;3760.84956601737;3692.353838078339;3714.3499887519574;3720.683741881943;3730.4963829883027;3733.1227161617;3548.449990251515;3705.3886108792017;3708.5178926739486;3714.888553343404;3729.9627003730025;3289;3289.8190599517015;3297.7032067114073;3300.6864347887154;3311.8012919224925;4673.228917228687;4673.551199128281;4680.953190468086;4690.7654617269;4705.694129881546;3993.24128415603;4125.484314460692;4160;4169.395437661552;4313.374560688682;4965.950340496585;4966.453690094087;4969.627277954168;4977.502249774993;4978.601711863079;3450.3789317922765;3461.653834616518;3465.28961562879;3468.791872487659;3481.686648201674;5123.590112790967;5126.205438157054;5163.612729045299;5225.790968361261;5238.397584277781;3651.233241019416;3654.9766065501703;3657.8902632921054;3658.908527286839;3667.743257971916;3695.5380577427823;3708.610595887447;3717.219383929351;3720.795356255432;3725.0501122213664;4223.577642235752;4227.87316380509;4235.618794308527;4239.236937351245;4245.936310955276;4680.765961701902;4895.4370247422;4981.850544483671;4985.4017517897355;5005.249212618136;1263.1852455166365;1276.910616256853;1277.3230188000375;1277.9105462617713;1282.1537785062;779.5244900610624;800.3677095094927;810.8864758933754;812.244612510371;813.3858936502902;4217.62041416274;4259.126878989861;4359.694821362475;4399.274119770202;4402.7358358498595;5104.591632669378;5115.7442127893455;5141.691498510101;5142.408623008327;5155.664881782672;4353.651707863363;4370.147821174904;4370.972821386971;4373.584509471595;4380.518143004267;2606;2685.825421347606;2688.8252263602803;2700.9594856077174;2719.7552220300267;4776.665633405628;4782.713037217778;4808.422989241313;4811.975940120277;4836.395450568679;5260.395054568697;5275.894482110413;5287.863109431491;5301.310829592206;5303;4573.291139873325;4856.975715121402;4886.267059940967;4888.315635810989;4895.8041678332465;5170.508801663836;5184.870378240575;5189.766460509361;5194.402643696023;5225.190095535198;4932.186189278729;5123.820666276662;5125.641205115606;5130.12787826078;5158.329417175743;4841.273808928689;4877.244027175793;4893.09477746618;4895;4898.559129678346;3722.683571896419;3739.1586892948867;3747.437884317374;3772.490713753608;3772.773633581994;5229.686218826882;5233.973830130825;5255.710935898563;5262.50018744656;5264.9210261846865;4764.97553026101;5007.474215207497;5044.9243261350375;5047.343845300087;5055.696658200372;5040.4455509894615;5045.763787871995;5050.6009645418135;5064.670796398223;5104.540591346797;4887.120318342732;5034.496550344937;5047.141985862486;5048;5053.418856831511;4845.927311090407;4928.654994150375;5016.2224855147515;5063.468335824686;5070;4911.950880491149;4932.383452068491;4934.777934992862;4946.851594452172;4947.60419166473 23:06:54 INFO - PROCESS | 2891 | |5;yui.html;609.4119174996127;617.3918690090258;666.9032990216418;707.8478127202653;713.6931119618564;13192.73614527709;13732.450701971922;13755.105918115316;13761.724765504683;13771.173729576232;13641.931790341034;13861.376238069286;13870.583882483517;13872.30638468075;13881.4447422103;621.1676353686059;761.2577736706722;766.6741634805213;780.9492382995115;789.9763007109767;781.3241546062671;790.632355954481;790.8022994251437;791.0546896458734;792.3977776889577;839.9496030238174;840.5587066789923;840.848647243496;841.3521588376976;844.4131328726537;659.0575477067791;798.528867967896;821.650798410679;836.3184005035877;836.4646626159263;601.3054921565599;602.0096940532843;602.6083046020078;602.7106988645431;602.7588964414225;12616.243025418413;12644.936775316157;12668.80996785049;12681;12683.746325073447;12713.237205767684;13104.475820967149;13461.932690336584;13620.863791362059;13650.557323647417;11845.881541184564;12048.277103373826;12116.09129315296;12161.574344897886;12445.128840981135;3910.648041676263;4038.858639947617;4080.8367665293354;4099.631033206996;4102.589741025873;64290.05549632812;64643;65911.340886591;66665.66667166633;67751.322486775;9986.850197247048;10012.24908131893;10169.593216271267;10196.490175491195;10231;17211.31154753796;17351.219195136247;17600.559986000197;17600.735988960176;17715.29138834432;6650.800475985729;6706.664666766642;6897.655117244118;6930.410915072275;7057.964710176417;3685.0971511979737;3695.3902606069696;3704.870329538453;3775.0751065989007;3813.7521061130938;2810.4800611886867;2922.853857307126;2925.3564215872475;3347.5648165738785;3509.6841284284537;552.9806456773994;556.1407625219047;560.9158626206101;570.5264630356877;571.9685417302005;598.5510866849862;606.2300877885061;619.767587154817;626.2172284644195;641.9614823110628;8589.957050214709;8781.560921954004;8824.51465169409;8831.3376496763;9245.953770231106;469.15083698505816;605.0319488817856;613.4387035862136;620.8359326263256;629.697745082363;4034.172994536113;4119.752814831059;4151.937720934188;4226.556211597738;4328.935065974013;104681;105774;106208.46895765472;106464;110625.44687276513;89717;89884;91084;91773.54113229392;92604;48111;63329;79507;81851;82422.58788706257;7935.761927142195;7959.363250940027;8311.613664067096;8328.375371847256;8342.207490288412;47596.7620161897;48988;49403.25895111504;50003.49996500134;50208.24687629615;62745.37254627578;63557;69812.65093674499;69865.65067174632;69879.65060174666;9303;9533.332666713264;9758.658446954607;9772.12050915421;9786.168175705145;11906.769723612408;12088.576899808464;12269.447874845475;12579.433925473193;12960.157589756636;10656;10662.306950048222;10766;11050.33697978124;11176.832347514957;10588.205884558534;10816;11021.779564408827;11507.252028618113;11734.119941004288;11077.667669969915;11129.610463633733;11790.705232369259;11921.880781192078;11969.281843089442;8314.667413303641;8786.428882122642;8793.560321984003;9024.548772561477;9026.593803278736;9177.495237761987;9431;10007.549660265451;10015.749606259902;10036.397816131055 23:06:54 INFO - PROCESS | 2891 | __end_tp_report 23:06:54 INFO - PROCESS | 2891 | __start_cc_report 23:06:54 INFO - PROCESS | 2891 | _x_x_mozilla_cycle_collect,995 23:06:54 INFO - PROCESS | 2891 | __end_cc_report 23:06:54 INFO - PROCESS | 2891 | __startTimestamp1470809214238__endTimestamp 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | ------- Summary: start ------- 23:06:54 INFO - PROCESS | 2891 | Number of tests: 6 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#0] dojo.html Cycles:165 Average:4655.14 Median:4777.28 stddev:478.88 (10.0%) stddev-sans-first:452.94 23:06:54 INFO - PROCESS | 2891 | Values: 2619.6 2622.5 2649.9 2654.2 2661.1 4483.7 4543.9 4712.3 4751.0 4771.7 4548.3 4567.7 4635.9 4723.7 4755.0 4344.6 4378.1 4401.9 4403.4 4427.6 4352.1 4376.6 4425.3 4448.7 4485.7 4437.4 4441.7 4466.4 4472.3 4490.5 4165.9 4361.4 4393.5 4401.7 4403.9 4192.7 4222.0 4225.6 4261.5 4326.5 4639.6 4697.2 4699.2 4703.9 4709.6 5025.5 5061.7 5066.4 5067.0 5077.0 4840.7 4887.6 4997.0 5041.3 5055.6 4764.7 4777.3 4816.5 4822.1 4822.2 5254.3 5260.1 5268.1 5268.4 5271.9 4812.7 4854.8 4984.6 5005.2 5009.6 4926.1 4962.4 4963.6 5015.1 5016.4 4905.9 4914.5 4915.0 4918.9 4959.6 4779.6 4782.1 4891.7 4908.3 4955.7 4230.6 4244.4 4251.2 4255.7 4287.7 3754.3 3795.4 3797.4 3807.3 3829.8 4295.6 4299.4 4301.7 4340.1 4343.0 4836.6 4919.3 5063.6 5074.2 5112.8 4252.8 4264.1 4289.3 4348.9 4356.8 4577.4 4588.4 4591.7 4593.7 4640.8 4753.7 4761.8 4762.9 4766.0 4814.4 4769.4 4988.2 5052.8 5060.1 5135.6 4750.4 4751.5 4758.8 4763.2 4778.5 4986.4 4988.5 4993.2 5007.5 5058.2 4759.2 4970.4 5030.8 5033.9 5074.2 4901.1 4932.3 4935.0 4940.2 4981.9 4910.2 4922.8 4937.1 4942.3 4979.4 4621.7 4797.4 4845.4 4867.8 4885.6 4957.2 4970.4 4972.0 4973.0 5044.6 4879.6 4886.7 4891.2 4891.5 4934.0 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#1] ext.html Cycles:180 Average:46404.29 Median:19893.18 stddev:87760.58 (441.2%) stddev-sans-first:87939.30 23:06:54 INFO - PROCESS | 2891 | Values: 570.6 576.4 577.9 580.4 590.3 29369.6 39809.8 41403.8 41572.4 45142.8 41743.0 42132.0 42235.8 42257.8 42265.4 7688.3 7885.8 7895.7 7896.1 7896.2 7727.8 7788.4 7807.8 7838.0 7840.3 7822.2 7866.6 7878.1 7880.4 7883.0 7824.7 7850.2 7861.3 7867.4 7871.9 7649.7 7707.2 7711.2 7713.9 7722.5 39186.4 39474.4 39519.6 39552.8 39589.2 3128.1 3135.4 3327.2 3341.4 3449.2 29095.4 29217.7 29597.3 30362.8 30805.5 3586.8 3588.0 3588.3 3604.6 3632.5 190630.0 191368.0 191723.0 192178.0 192481.0 32175.7 53923.5 54269.7 54286.7 54306.0 32715.0 34779.7 34828.0 34829.0 34837.0 14061.6 14228.5 14230.3 14246.4 14250.8 10659.7 10662.3 10665.0 10680.1 10680.7 7895.4 7955.3 7988.3 8065.6 8087.9 3097.8 3134.0 3149.1 3149.9 3161.6 871.4 915.5 984.4 1016.3 1026.4 27586.4 27736.6 28015.6 28228.3 28264.0 904.6 944.2 969.4 977.7 1004.5 13553.8 13597.3 13658.5 13816.0 13904.0 419548.0 422474.0 423314.0 424674.0 426314.0 49125.5 49560.3 49596.8 49693.3 49722.0 1125.9 1154.9 1183.6 1202.1 1251.5 26465.7 29204.9 29667.9 30166.7 30368.7 42858.4 43486.3 43573.1 43604.8 43615.3 332858.0 334306.0 336822.0 337151.0 337981.0 25543.7 25755.9 26214.3 26526.7 26535.7 42178.1 42284.8 42735.1 42905.4 43504.6 35600.6 35972.5 36358.8 36709.3 37115.6 35849.0 35966.6 36604.8 36699.6 36765.0 27704.9 35239.6 36153.8 37283.8 37323.3 30971.2 32371.8 33076.8 34067.3 34236.1 25747.9 25970.9 26070.0 26148.3 26170.0 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#2] jquery.html Cycles:180 Average:24932.31 Median:5089.63 stddev:66072.70 (1298.2%) stddev-sans-first:66232.29 23:06:54 INFO - PROCESS | 2891 | Values: 363.3 370.5 370.6 381.4 388.5 16138.9 16711.3 17317.0 17341.7 17347.1 15688.2 16482.7 16855.8 17079.2 17087.6 4860.3 5089.5 5089.7 5092.3 5096.4 3701.1 3890.2 5016.9 5637.5 5897.7 5980.9 5985.2 5987.8 5997.1 6001.0 5589.1 5594.0 5600.9 5618.8 5624.4 4850.0 4911.6 4925.7 4934.1 4936.1 20399.0 20507.3 20637.4 20720.1 20820.4 1868.0 1965.9 1972.6 2170.7 2320.7 12903.2 12908.7 12938.0 13297.0 13745.0 1696.9 1700.6 1726.6 1739.4 1748.9 69997.7 70690.4 70936.6 71018.0 72274.3 9451.0 9453.1 9476.6 9514.4 9903.4 13139.3 13257.2 13264.9 13284.4 13285.3 7218.4 7420.5 7486.7 7525.2 7542.8 7028.0 7049.7 7213.5 7254.2 7560.5 5836.6 5874.4 6000.7 6013.3 6031.6 1542.8 1661.4 1732.9 1738.4 1782.5 792.8 803.7 828.7 831.4 865.9 16378.2 17294.5 17472.9 17639.4 17715.5 890.0 906.3 907.5 915.4 958.7 9045.6 9926.6 10001.6 10028.8 10197.8 391014.0 396420.0 401101.0 401521.0 406289.0 76639.0 80840.0 83650.0 84223.0 84457.0 836.3 890.7 891.5 930.7 932.9 11882.6 14530.8 15265.4 15425.7 16069.5 38807.8 38828.2 39062.2 39416.8 39723.0 52368.7 52988.0 55446.0 55621.7 56461.0 6690.7 6995.5 7026.3 7120.8 7211.9 6812.4 9113.3 9691.7 11434.5 11835.3 9568.2 9735.9 9777.8 10112.0 10175.3 9922.8 10121.7 10268.7 10321.8 10726.1 9519.7 9782.0 9787.4 9870.8 10091.7 7191.6 7219.9 7292.2 7297.9 7404.9 9692.4 10046.1 10252.1 10272.1 10417.7 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#3] mootools.html Cycles:180 Average:5122.37 Median:5252.78 stddev:2898.15 (55.2%) stddev-sans-first:2892.44 23:06:54 INFO - PROCESS | 2891 | Values: 1353.2 1409.4 1477.8 1650.1 1660.9 7184.2 7740.5 7744.5 7750.5 7751.3 6651.8 7319.3 7505.4 7533.2 7580.5 2168.2 2187.1 2193.6 2198.4 2199.7 2132.5 2159.1 2196.4 2199.6 2205.5 1791.8 2099.9 2158.5 2164.4 2181.2 2158.3 2177.6 2193.2 2195.7 2196.0 2139.7 2148.0 2148.5 2151.1 2152.6 7931.6 7973.2 7979.5 7984.2 7989.2 4434.6 4548.5 4561.4 4581.8 4583.3 2656.9 2773.6 2779.3 2786.1 2786.3 824.5 851.6 867.2 869.8 872.0 10628.7 10675.5 10686.4 10687.3 10707.1 3913.2 4056.5 4197.0 4200.3 4222.4 9163.6 9168.8 9178.8 9180.4 9187.7 4669.2 4686.6 4699.4 4701.1 4715.9 4105.1 4259.9 4266.0 4270.1 4278.8 3612.9 3642.3 3711.2 3734.3 3742.0 1140.5 1166.0 1176.4 1182.8 1184.5 571.1 733.6 782.9 824.7 863.8 5095.6 5146.8 5246.0 5259.6 5285.3 868.1 891.5 902.1 903.5 913.1 3458.0 3522.5 3542.5 3613.5 3618.1 9370.4 9568.2 9756.5 9762.9 9813.0 9691.8 9701.4 9703.3 9727.9 9745.4 7294.4 7523.3 7564.6 7576.8 7581.4 5109.3 5122.1 5221.8 5282.6 5377.8 9003.3 9128.8 9136.3 9138.7 9199.6 8595.2 8934.3 9218.4 9307.6 9460.2 6182.3 6297.7 6303.9 6437.8 6503.4 7437.7 7486.3 7489.6 7499.7 7524.3 6699.7 6760.7 6842.8 6903.3 7020.8 6693.4 6742.7 6768.4 6769.2 6792.4 6022.4 6025.8 6074.7 6086.5 6086.9 4460.5 4460.6 4461.0 4503.8 4547.1 4431.4 4488.0 4578.0 4637.0 4704.9 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#4] prototype.html Cycles:180 Average:4154.86 Median:4770.06 stddev:1217.29 (25.5%) stddev-sans-first:1185.60 23:06:54 INFO - PROCESS | 2891 | Values: 288.1 294.7 297.8 298.8 302.9 4741.9 4778.1 4789.6 4798.4 4803.4 4753.8 4762.3 4775.1 4778.9 4782.5 3613.3 3705.4 3728.6 3741.5 3762.8 3610.2 3703.7 3712.1 3751.6 3760.8 3692.4 3714.3 3720.7 3730.5 3733.1 3548.4 3705.4 3708.5 3714.9 3730.0 3289.0 3289.8 3297.7 3300.7 3311.8 4673.2 4673.6 4681.0 4690.8 4705.7 3993.2 4125.5 4160.0 4169.4 4313.4 4966.0 4966.5 4969.6 4977.5 4978.6 3450.4 3461.7 3465.3 3468.8 3481.7 5123.6 5126.2 5163.6 5225.8 5238.4 3651.2 3655.0 3657.9 3658.9 3667.7 3695.5 3708.6 3717.2 3720.8 3725.1 4223.6 4227.9 4235.6 4239.2 4245.9 4680.8 4895.4 4981.9 4985.4 5005.2 1263.2 1276.9 1277.3 1277.9 1282.2 779.5 800.4 810.9 812.2 813.4 4217.6 4259.1 4359.7 4399.3 4402.7 5104.6 5115.7 5141.7 5142.4 5155.7 4353.7 4370.1 4371.0 4373.6 4380.5 2606.0 2685.8 2688.8 2701.0 2719.8 4776.7 4782.7 4808.4 4812.0 4836.4 5260.4 5275.9 5287.9 5301.3 5303.0 4573.3 4857.0 4886.3 4888.3 4895.8 5170.5 5184.9 5189.8 5194.4 5225.2 4932.2 5123.8 5125.6 5130.1 5158.3 4841.3 4877.2 4893.1 4895.0 4898.6 3722.7 3739.2 3747.4 3772.5 3772.8 5229.7 5234.0 5255.7 5262.5 5264.9 4765.0 5007.5 5044.9 5047.3 5055.7 5040.4 5045.8 5050.6 5064.7 5104.5 4887.1 5034.5 5047.1 5048.0 5053.4 4845.9 4928.7 5016.2 5063.5 5070.0 4912.0 4932.4 4934.8 4946.9 4947.6 23:06:54 INFO - PROCESS | 2891 | 23:06:54 INFO - PROCESS | 2891 | [#5] yui.html Cycles:180 Average:18429.72 Median:558.53 stddev:27099.02 (4851.9%) stddev-sans-first:27142.00 23:06:54 INFO - PROCESS | 2891 | Values: 609.4 617.4 666.9 707.8 713.7 13192.7 13732.5 13755.1 13761.7 13771.2 13641.9 13861.4 13870.6 13872.3 13881.4 621.2 761.3 766.7 780.9 790.0 781.3 790.6 790.8 791.1 792.4 839.9 840.6 840.8 841.4 844.4 659.1 798.5 821.7 836.3 836.5 601.3 602.0 602.6 602.7 602.8 12616.2 12644.9 12668.8 12681.0 12683.7 12713.2 13104.5 13461.9 13620.9 13650.6 11845.9 12048.3 12116.1 12161.6 12445.1 3910.6 4038.9 4080.8 4099.6 4102.6 64290.1 64643.0 65911.3 66665.7 67751.3 9986.9 10012.2 10169.6 10196.5 10231.0 17211.3 17351.2 17600.6 17600.7 17715.3 6650.8 6706.7 6897.7 6930.4 7058.0 3685.1 3695.4 3704.9 3775.1 3813.8 2810.5 2922.9 2925.4 3347.6 3509.7 553.0 556.1 560.9 570.5 572.0 598.6 606.2 619.8 626.2 642.0 8590.0 8781.6 8824.5 8831.3 9246.0 469.2 605.0 613.4 620.8 629.7 4034.2 4119.8 4151.9 4226.6 4328.9 104681.0 105774.0 106208.5 106464.0 110625.4 89717.0 89884.0 91084.0 91773.5 92604.0 48111.0 63329.0 79507.0 81851.0 82422.6 7935.8 7959.4 8311.6 8328.4 8342.2 47596.8 48988.0 49403.3 50003.5 50208.2 62745.4 63557.0 69812.7 69865.7 69879.7 9303.0 9533.3 9758.7 9772.1 9786.2 11906.8 12088.6 12269.4 12579.4 12960.2 10656.0 10662.3 10766.0 11050.3 11176.8 10588.2 10816.0 11021.8 11507.3 11734.1 11077.7 11129.6 11790.7 11921.9 11969.3 8314.7 8786.4 8793.6 9024.5 9026.6 9177.5 9431.0 10007.5 10015.7 10036.4 23:06:54 INFO - PROCESS | 2891 | -------- Summary: end -------- 23:06:54 INFO - PROCESS | 2891 | 23:06:55 INFO - TEST-INFO | 2891: exit 0 23:06:55 INFO - TEST-OK | dromaeo_css | took 1119549ms 23:06:55 INFO - TEST-START | kraken 23:06:55 INFO - operating with platform_type : linux_ 23:06:55 INFO - Initialising browser for kraken test... 23:06:55 INFO - TEST-INFO | started process 3082 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Q4xZr/profile http://localhost:53461/getInfo.html) 23:06:59 INFO - PROCESS | 3082 | __metrics Screen width/height:1600/1200 23:06:59 INFO - PROCESS | 3082 | colorDepth:24 23:06:59 INFO - PROCESS | 3082 | Browser inner width/height: 1024/697 23:06:59 INFO - PROCESS | 3082 | __metrics 23:07:00 INFO - PROCESS | 3082 | 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" 23:07:00 INFO - PROCESS | 3082 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:07:01 INFO - TEST-INFO | 3082: exit 0 23:07:01 INFO - Browser initialized. 23:07:01 INFO - Running cycle 1/1 for kraken test... 23:07:01 INFO - TEST-INFO | started process 3163 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Q4xZr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:13 INFO - PROCESS | 3163 | RSS: Main: 156884992 23:07:13 INFO - PROCESS | 3163 | 23:07:16 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53461/tests/kraken/driver.html?testName=audio-beat-detection) 23:07:17 INFO - PROCESS | 3163 | RSS: Main: 212738048 23:07:17 INFO - PROCESS | 3163 | 23:07:19 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53461/tests/kraken/driver.html?testName=audio-dft) 23:07:20 INFO - PROCESS | 3163 | RSS: Main: 191107072 23:07:20 INFO - PROCESS | 3163 | 23:07:23 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53461/tests/kraken/driver.html?testName=audio-fft) 23:07:24 INFO - PROCESS | 3163 | RSS: Main: 270184448 23:07:24 INFO - PROCESS | 3163 | 23:07:26 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53461/tests/kraken/driver.html?testName=audio-oscillator) 23:07:27 INFO - PROCESS | 3163 | RSS: Main: 179429376 23:07:27 INFO - PROCESS | 3163 | 23:07:29 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53461/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:07:29 INFO - PROCESS | 3163 | RSS: Main: 184266752 23:07:29 INFO - PROCESS | 3163 | 23:07:33 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53461/tests/kraken/driver.html?testName=imaging-darkroom) 23:07:33 INFO - PROCESS | 3163 | RSS: Main: 220176384 23:07:33 INFO - PROCESS | 3163 | 23:07:37 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53461/tests/kraken/driver.html?testName=imaging-desaturate) 23:07:37 INFO - PROCESS | 3163 | RSS: Main: 214568960 23:07:37 INFO - PROCESS | 3163 | 23:07:40 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53461/tests/kraken/driver.html?testName=json-parse-financial) 23:07:41 INFO - PROCESS | 3163 | RSS: Main: 216760320 23:07:41 INFO - PROCESS | 3163 | 23:07:43 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53461/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:07:43 INFO - PROCESS | 3163 | RSS: Main: 182767616 23:07:43 INFO - PROCESS | 3163 | 23:07:45 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:07:46 INFO - PROCESS | 3163 | RSS: Main: 188948480 23:07:46 INFO - PROCESS | 3163 | 23:07:48 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:07:49 INFO - PROCESS | 3163 | RSS: Main: 193576960 23:07:49 INFO - PROCESS | 3163 | 23:07:51 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:07:52 INFO - PROCESS | 3163 | RSS: Main: 188284928 23:07:52 INFO - PROCESS | 3163 | 23:07:55 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:07:55 INFO - PROCESS | 3163 | RSS: Main: 191270912 23:07:55 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | Cycle 1(1): loaded http://localhost:53461/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53461/tests/kraken/driver.html?testName=ai-astar) 23:07:57 INFO - PROCESS | 3163 | RSS: Main: 242016256 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | __start_tp_report 23:07:57 INFO - PROCESS | 3163 | _x_x_mozilla_page_load 23:07:57 INFO - PROCESS | 3163 | _x_x_mozilla_page_load_details 23:07:57 INFO - PROCESS | 3163 | |i|pagename|runs| 23:07:57 INFO - PROCESS | 3163 | |0;ai-astar;104;119;115;114;116;116;113;115;115;114 23:07:57 INFO - PROCESS | 3163 | |1;audio-beat-detection;148;121;125;196;128;123;148;122;169;125 23:07:57 INFO - PROCESS | 3163 | |2;audio-dft;162;160;161;161;161;160;161;160;160;161 23:07:57 INFO - PROCESS | 3163 | |3;audio-fft;77;75;75;113;77;77;75;75;109;77 23:07:57 INFO - PROCESS | 3163 | |4;audio-oscillator;85;86;135;86;86;86;127;86;86;124 23:07:57 INFO - PROCESS | 3163 | |5;imaging-gaussian-blur;117;117;117;118;117;117;116;117;117;117 23:07:57 INFO - PROCESS | 3163 | |6;imaging-darkroom;147;148;146;146;146;147;146;147;146;145 23:07:57 INFO - PROCESS | 3163 | |7;imaging-desaturate;95;95;95;95;96;95;96;95;96;95 23:07:57 INFO - PROCESS | 3163 | |8;json-parse-financial;64;61;62;64;62;63;62;63;62;62 23:07:57 INFO - PROCESS | 3163 | |9;json-stringify-tinderbox;65;66;65;66;65;66;65;65;67;65 23:07:57 INFO - PROCESS | 3163 | |10;stanford-crypto-aes;84;85;79;79;80;83;80;85;80;78 23:07:57 INFO - PROCESS | 3163 | |11;stanford-crypto-ccm;129;131;129;130;131;129;128;131;149;130 23:07:57 INFO - PROCESS | 3163 | |12;stanford-crypto-pbkdf2;169;171;166;170;170;166;169;171;166;168 23:07:57 INFO - PROCESS | 3163 | |13;stanford-crypto-sha256-iterative;68;68;69;67;67;68;68;67;68;67 23:07:57 INFO - PROCESS | 3163 | __end_tp_report 23:07:57 INFO - PROCESS | 3163 | __start_cc_report 23:07:57 INFO - PROCESS | 3163 | _x_x_mozilla_cycle_collect,1956 23:07:57 INFO - PROCESS | 3163 | __end_cc_report 23:07:57 INFO - PROCESS | 3163 | __startTimestamp1470809277926__endTimestamp 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | ------- Summary: start ------- 23:07:57 INFO - PROCESS | 3163 | Number of tests: 14 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.90 (3.4%) stddev-sans-first:1.72 23:07:57 INFO - PROCESS | 3163 | Values: 104.0 119.0 115.0 114.0 116.0 116.0 113.0 115.0 115.0 114.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:138.00 stddev:25.10 (18.2%) stddev-sans-first:26.48 23:07:57 INFO - PROCESS | 3163 | Values: 148.0 121.0 125.0 196.0 128.0 123.0 148.0 122.0 169.0 125.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.53 23:07:57 INFO - PROCESS | 3163 | Values: 162.0 160.0 161.0 161.0 161.0 160.0 161.0 160.0 160.0 161.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.00 stddev:14.82 (19.5%) stddev-sans-first:15.56 23:07:57 INFO - PROCESS | 3163 | Values: 77.0 75.0 75.0 113.0 77.0 77.0 75.0 75.0 109.0 77.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:86.00 stddev:20.85 (24.2%) stddev-sans-first:21.52 23:07:57 INFO - PROCESS | 3163 | Values: 85.0 86.0 135.0 86.0 86.0 86.0 127.0 86.0 86.0 124.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 23:07:57 INFO - PROCESS | 3163 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 23:07:57 INFO - PROCESS | 3163 | Values: 147.0 148.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 145.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:07:57 INFO - PROCESS | 3163 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.50 stddev:0.97 (1.6%) stddev-sans-first:0.87 23:07:57 INFO - PROCESS | 3163 | Values: 64.0 61.0 62.0 64.0 62.0 63.0 62.0 63.0 62.0 62.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 23:07:57 INFO - PROCESS | 3163 | Values: 65.0 66.0 65.0 66.0 65.0 66.0 65.0 65.0 67.0 65.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:2.67 (3.3%) stddev-sans-first:2.65 23:07:57 INFO - PROCESS | 3163 | Values: 84.0 85.0 79.0 79.0 80.0 83.0 80.0 85.0 80.0 78.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:130.50 stddev:6.17 (4.7%) stddev-sans-first:6.46 23:07:57 INFO - PROCESS | 3163 | Values: 129.0 131.0 129.0 130.0 131.0 129.0 128.0 131.0 149.0 130.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.50 stddev:2.01 (1.2%) stddev-sans-first:2.13 23:07:57 INFO - PROCESS | 3163 | Values: 169.0 171.0 166.0 170.0 170.0 166.0 169.0 171.0 166.0 168.0 23:07:57 INFO - PROCESS | 3163 | 23:07:57 INFO - PROCESS | 3163 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 23:07:57 INFO - PROCESS | 3163 | Values: 68.0 68.0 69.0 67.0 67.0 68.0 68.0 67.0 68.0 67.0 23:07:57 INFO - PROCESS | 3163 | -------- Summary: end -------- 23:07:57 INFO - PROCESS | 3163 | 23:07:58 INFO - TEST-INFO | 3163: exit 0 23:07:59 INFO - TEST-OK | kraken | took 64028ms 23:07:59 INFO - SUITE-END | took 1183s 23:07:59 INFO - Completed test suite (00:19:44) 23:07:59 INFO - javascript benchmark 23:07:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2619.6070589411597, 2622.5410553153197, 2649.920502384928, 2654.2170059832347, 2661.135131082398, 4483.6861419700635, 4543.863684089474, 4712.340272361872, 4750.952490475094, 4771.689840160391, 4548.295014272793, 4567.65742569307, 4635.930461043087, 4723.669343145981, 4754.953910139764, 4344.587264209895, 4378.124375124972, 4401.911961760763, 4403.361512580674, 4427.623651989585, 4352.12957408518, 4376.5623437656295, 4425.269830477967, 4448.666350023745, 4485.730856148642, 4437.35658329542, 4441.689081764278, 4466.441694788152, 4472.329150627415, 4490.461144662629, 4165.91668166638, 4361.4112094867105, 4393.472783265996, 4401.713888597263, 4403.933940990888, 4192.748435093904, 4221.957780422218, 4225.577442255781, 4261.4673165854265, 4326.480822301344, 4639.559241872017, 4697.154512187839, 4699.224627936386, 4703.905921881543, 4709.646776491777, 5025.522575355335, 5061.670991385585, 5066.392032956032, 5067.037262920034, 5077.0, 4840.685355451919, 4887.584555312767, 4996.950030499721, 5041.319421878036, 5055.5702765265, 4764.714117152913, 4777.283407488835, 4816.54242846929, 4822.059698358787, 4822.204336284473, 5254.290670759398, 5260.053190425683, 5268.130758424893, 5268.420473747885, 5271.868203294948, 4812.711237325771, 4854.83008094722, 4984.601231901439, 5005.199168133081, 5009.649324547319, 4926.113299606105, 4962.379702537183, 4963.60291176705, 5015.072211640858, 5016.423111342169, 4905.9264111038365, 4914.483979182206, 4914.967856750122, 4918.8770280743265, 4959.603231741452, 4779.593734532604, 4782.115308667908, 4891.706497610155, 4908.288298196741, 4955.653104282737, 4230.555791641833, 4244.3633454982, 4251.234777740036, 4255.659547236213, 4287.699861009762, 3754.2866855297952, 3795.449659799314, 3797.430385442206, 3807.2956503047026, 3829.7702137871815, 4295.591918767712, 4299.441072660597, 4301.677374196947, 4340.131973605228, 4343.04453020335, 4836.613070954315, 4919.3358896549225, 5063.569596584258, 5074.213496907985, 5112.795488180505, 4252.808623611945, 4264.061906380592, 4289.292266775947, 4348.934765978513, 4356.803943822536, 4577.427821522309, 4588.403507543998, 4591.65562582801, 4593.724376537419, 4640.767961601907, 4753.738544380092, 4761.83347056914, 4762.928556071731, 4765.9523404765505, 4814.374131362971, 4769.403824521934, 4988.226824842155, 5052.797888084436, 5060.089183946925, 5135.563477104488, 4750.429948406075, 4751.524847515221, 4758.8096476141955, 4763.190257656138, 4778.546038126373, 4986.376702912136, 4988.45127036033, 4993.201087825931, 5007.524285192901, 5058.215976523645, 4759.214729569582, 4970.378702662168, 5030.773615187252, 5033.899322013613, 5074.188129899198, 4901.09329773993, 4932.284818701197, 4934.9753251233515, 4940.2342636891335, 4981.925271120864, 4910.189818680021, 4922.778474968707, 4937.136001199704, 4942.308076869169, 4979.427365852974, 4621.745803980679, 4797.40032495938, 4845.394325709286, 4867.805287788448, 4885.6180616190595, 4957.206846904478, 4970.378702662168, 4972.0, 4972.980538989426, 5044.646874718735, 4879.634027447884, 4886.682365646222, 4891.19295316283, 4891.461939186615, 4934.037862616755], "value": 4625.224200185472, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.6290910907912, 576.374633522628, 577.9306483222014, 580.3529065092421, 590.2739630254787, 29369.559456608167, 39809.80095099524, 41403.79298103509, 41572.37641435381, 45142.77428612852, 41743.0, 42132.0, 42235.78882105585, 42257.788711056404, 42265.36601950965, 7688.269614386639, 7885.8028549286155, 7895.723649672263, 7896.091949425803, 7896.249856263646, 7727.806804829868, 7788.376929845627, 7807.804804879866, 7837.960810195941, 7840.294373506384, 7822.178671239523, 7866.646000929972, 7878.054633443995, 7880.448368614199, 7883.014623172104, 7824.687012519521, 7850.214978502104, 7861.292483676497, 7867.449278550504, 7871.881921771178, 7649.732259370895, 7707.229277072304, 7711.190325015848, 7713.884291735629, 7722.459427840054, 39186.412203816966, 39474.407883881766, 39519.60480395217, 39552.80223598892, 39589.20821583581, 3128.1084890806237, 3135.372925414926, 3327.15157634804, 3341.4319565673895, 3449.2066824630224, 29095.41809163805, 29217.70782292171, 29597.260068498246, 30362.84818575915, 30805.53791693104, 3586.8027258500774, 3588.0, 3588.3002814451193, 3604.6215147409407, 3632.54593175853, 190630.0, 191368.0, 191723.0, 192178.0, 192481.0, 32175.678243217735, 53923.46076539263, 54269.72865135728, 54286.72856635691, 54306.0, 32715.0, 34779.652203478145, 34828.0, 34829.0, 34837.0, 14061.578152655436, 14228.502002429865, 14230.28848557568, 14246.430142794172, 14250.786238206436, 10659.680209593724, 10662.253642245123, 10665.0, 10680.145588352912, 10680.679579612468, 7895.368370530344, 7955.2840244378285, 7988.281054705104, 8065.6370463329295, 8087.878681819778, 3097.7521798256084, 3133.984330078335, 3149.0867648382173, 3149.889753858604, 3161.636411812671, 871.4422769427572, 915.5056269614333, 984.4290311619244, 1016.2832926787048, 1026.3687831983277, 27586.448271034467, 27736.583951240344, 28015.579766303115, 28228.294292642848, 28264.0, 904.5838914099456, 944.1644144931785, 969.4474149734724, 977.6773664690777, 1004.467632154959, 13553.796693049611, 13597.32013399326, 13658.521951731642, 13816.0, 13904.0, 419548.0, 422474.0, 423314.0, 424674.0, 426314.0, 49125.50874491281, 49560.256596151085, 49596.75201623969, 49693.25460118101, 49722.0, 1125.870524889648, 1154.9480273387549, 1183.597576823884, 1202.1344631865043, 1251.5244207201206, 26465.735342646327, 29204.853975729984, 29667.85166074156, 30166.69833301639, 30368.69631303747, 42858.35712464378, 43486.34770478508, 43573.12853742971, 43604.78197609119, 43615.345769814114, 332858.0, 334306.0, 336822.0, 337151.0, 337981.0, 25543.744562554137, 25755.871220643778, 26214.34464138412, 26526.734732652425, 26535.734642653326, 42178.113612216504, 42284.788576056926, 42735.1452970945, 42905.35641965435, 43504.564954350055, 35600.64399356077, 35972.4604130933, 36358.8182059088, 36709.26581468302, 37115.628843711216, 35849.0, 35966.640333596326, 36604.816975916016, 36699.63300367069, 36765.0, 27704.86147569249, 35239.647603523634, 36153.819230903675, 37283.81358093192, 37323.253534929696, 30971.225719357197, 32371.838140809145, 33076.83461582677, 34067.318653626295, 34236.14409639779, 25747.871260643577, 25970.87014564915, 26070.0, 26148.34629134287, 26170.0], "value": 16579.87450653628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.3260302139531, 370.48502581411844, 370.5608853508593, 381.3745457449781, 388.4910766895367, 16138.919305403471, 16711.331546738133, 17317.0, 17341.7398739019, 17347.132643367844, 15688.21558922055, 16482.670346593062, 16855.83144168558, 17079.231434585443, 17087.57281067974, 4860.2709593560985, 5089.51649593288, 5089.7455127243675, 5092.337996060507, 5096.413912400066, 3701.0932321581117, 3890.2414029264305, 5016.899662006757, 5637.492625663689, 5897.675627840467, 5980.850478738044, 5985.221921150266, 5987.790427335044, 5997.130416089664, 6001.0, 5589.105743081108, 5593.972030139864, 5600.887982240332, 5618.775248990036, 5624.3531993820825, 4849.951500484985, 4911.557959783637, 4925.729084900329, 4934.087193869145, 4936.111499930011, 20399.0, 20507.28224512135, 20637.380878573516, 20720.06759695817, 20820.37538873836, 1867.9813201868078, 1965.8623896327263, 1972.5660354721938, 2170.739511258659, 2320.6867072945106, 12903.225806451643, 12908.741825163537, 12938.0, 13297.0, 13745.0, 1696.898186108825, 1700.6343636118295, 1726.6115124097103, 1739.4417674876508, 1748.9212985415686, 69997.65001175013, 70690.38775887662, 70936.6453167736, 71018.0, 72274.2772572278, 9450.952745236229, 9453.101955314234, 9476.620935162655, 9514.38156519824, 9903.405795652285, 13139.34303284832, 13257.204567725967, 13264.933675331562, 13284.402201900937, 13285.26931018784, 7218.422526197892, 7420.517666351778, 7486.700531978769, 7525.2098529654695, 7542.773716788505, 7028.0, 7049.68276427562, 7213.495055346076, 7254.216351374224, 7560.470767046364, 5836.591438599257, 5874.412558744177, 6000.699265771011, 6013.278406591237, 6031.577789554777, 1542.7994360733032, 1661.418503523757, 1732.930682772675, 1738.4089409600797, 1782.4741701197918, 792.7978365516872, 803.7347675267148, 828.7099515169763, 831.3847752663107, 865.9437136586101, 16378.181090945644, 17294.4811655648, 17472.91263543674, 17639.382621608183, 17715.468535943943, 889.9688510902088, 906.2976193449997, 907.5326207003399, 915.4004127296631, 958.7459323279337, 9045.638174473079, 9926.553305101288, 10001.649942251986, 10028.799424011624, 10197.847032294521, 391014.0, 396420.0, 401101.0, 401521.0, 406289.0, 76639.0, 80840.0, 83650.0, 84223.0, 84457.0, 836.3058661311097, 890.7372325163963, 891.4784850862318, 930.6789157740704, 932.9160375566231, 11882.643520694393, 14530.782038269646, 15265.439485954941, 15425.69148616999, 16069.517914462585, 38807.805960970014, 38828.2234355317, 39062.2187556253, 39416.80291598524, 39723.0, 52368.73815630897, 52988.0, 55446.0, 55621.72189139028, 56461.0, 6690.732370705117, 6995.4753393494675, 7026.262242464468, 7120.786376408716, 7211.855762884608, 6812.420944219797, 9113.316501262565, 9691.70924872255, 11434.485448154686, 11835.28988260707, 9568.186704130227, 9735.853962190715, 9777.80444391094, 10112.039356261437, 10175.338602990782, 9922.801543968935, 10121.746956326151, 10268.743281418025, 10321.793564128826, 10726.141908647134, 9519.714408567754, 9782.0, 9787.412755234709, 9870.753231169278, 10091.69724908254, 7191.604461754654, 7219.891701624585, 7292.197858235484, 7297.854042919218, 7404.851902961803, 9692.41845489273, 10046.146077583488, 10252.077313041862, 10272.126869215901, 10417.739556510845], "value": 8293.718851814521, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.15427857589, 1409.4221369238614, 1477.830049544302, 1650.0512205481843, 1660.900345979242, 7184.20973692894, 7740.45816792824, 7744.535327880317, 7750.53496790193, 7751.341136003432, 6651.833704157399, 7319.341259286676, 7505.437092218078, 7533.209013053633, 7580.545167289966, 2168.2194410012344, 2187.103287652063, 2193.6270833958265, 2198.4393979535275, 2199.747029091651, 2132.530843214498, 2159.1039718516795, 2196.3850121965875, 2199.626063569197, 2205.547862688145, 1791.8387345138935, 2099.9265025724103, 2158.5251244726214, 2164.350694791564, 2181.2365672014826, 2158.266189495579, 2177.6080305544997, 2193.1775584155944, 2195.747489038765, 2195.9890200548975, 2139.732533433321, 2148.0441203664377, 2148.473623962124, 2151.064287035145, 2152.5802468518646, 7931.563763992978, 7973.162817904153, 7979.48133371329, 7984.161663025415, 7989.161138080476, 4434.623057040123, 4548.454185497729, 4561.384213131218, 4581.816727330903, 4583.335416364642, 2656.9165143205364, 2773.62556054934, 2779.277387879146, 2786.0527420677067, 2786.303424143964, 824.4599787139433, 851.5699571716306, 867.1631875240361, 869.7869022089566, 871.9869201961976, 10628.681139565826, 10675.466226688712, 10686.358818470917, 10687.251892367538, 10707.089897358734, 3913.178232571192, 4056.513218413809, 4197.013701780079, 4200.264953633163, 4222.387753775685, 9163.633454661738, 9168.770780730401, 9178.81642367149, 9180.357374983822, 9187.678431255008, 4669.159551280802, 4686.5782079613, 4699.412573428322, 4701.130290896195, 4715.858524244278, 4105.055837157411, 4259.893502662396, 4266.0188156724125, 4270.10327831159, 4278.807453664593, 3612.8916132516065, 3642.2897534980425, 3711.183539621279, 3734.3091528067393, 3742.045778326509, 1140.520981187903, 1166.03801863463, 1176.3823992404057, 1182.7634473105413, 1184.5261895241972, 571.1353978103256, 733.5818583407406, 782.941279404047, 824.6536454689045, 863.7883718489011, 5095.566876815512, 5146.845594632167, 5245.9737701312015, 5259.579233661298, 5285.2600635911, 868.0928429790883, 891.5497673674882, 902.1384577728254, 903.5075883643431, 913.0641092879719, 3458.0, 3522.5244591980268, 3542.5217595624777, 3613.457981302826, 3618.131648404364, 9370.39092458988, 9568.234541236685, 9756.463394513228, 9762.853557196648, 9812.950935245279, 9691.757706057264, 9701.369410988404, 9703.320767546345, 9727.9027209727, 9745.415275083518, 7294.379977701955, 7523.285287897641, 7564.583947882919, 7576.772696819105, 7581.355584775356, 5109.267754457019, 5122.078025955364, 5221.843344699665, 5282.630215884851, 5377.838664840061, 9003.279737620844, 9128.817423651622, 9136.269098471974, 9138.725838224864, 9199.586018629045, 8595.226429621363, 8934.329925255503, 9218.354715169873, 9307.627694892399, 9460.243180545678, 6182.258129024546, 6297.748090076346, 6303.905441418291, 6437.839054023687, 6503.44720698727, 7437.739679111205, 7486.251374862471, 7489.588072655838, 7499.66251518672, 7524.2851929066655, 6699.698513566804, 6760.729570817309, 6842.794716158523, 6903.309669033057, 7020.824479388056, 6693.397594216543, 6742.73029078851, 6768.390844823793, 6769.221539523018, 6792.388685018372, 6022.367651396716, 6025.849353766015, 6074.66589337573, 6086.452219300284, 6086.878262434815, 4460.531644177445, 4460.6431485481735, 4460.977695111504, 4503.819847206075, 4547.09058188357, 4431.446069241345, 4488.0, 4577.954220457753, 4637.049404871926, 4704.92942605868], "value": 4106.360568062955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.14852112009015, 294.65378180637754, 297.7558402110268, 298.8490812139868, 302.85462977770663, 4741.9288710669325, 4778.136526754569, 4789.592884604813, 4798.352222449968, 4803.375561177051, 4753.786079626417, 4762.261849413338, 4775.14047471455, 4778.880527986802, 4782.521747825216, 3613.2773445310977, 3705.4256590228424, 3728.552573691161, 3741.4761933329355, 3762.830672619725, 3610.151614370621, 3703.6666699996995, 3712.1462063725357, 3751.5685696144883, 3760.84956601737, 3692.353838078339, 3714.3499887519574, 3720.683741881943, 3730.4963829883027, 3733.1227161617, 3548.449990251515, 3705.3886108792017, 3708.5178926739486, 3714.888553343404, 3729.9627003730025, 3289.0, 3289.8190599517015, 3297.7032067114073, 3300.6864347887154, 3311.8012919224925, 4673.228917228687, 4673.551199128281, 4680.953190468086, 4690.7654617269, 4705.694129881546, 3993.24128415603, 4125.484314460692, 4160.0, 4169.395437661552, 4313.374560688682, 4965.950340496585, 4966.453690094087, 4969.627277954168, 4977.502249774993, 4978.601711863079, 3450.3789317922765, 3461.653834616518, 3465.28961562879, 3468.791872487659, 3481.686648201674, 5123.590112790967, 5126.205438157054, 5163.612729045299, 5225.790968361261, 5238.397584277781, 3651.233241019416, 3654.9766065501703, 3657.8902632921054, 3658.908527286839, 3667.743257971916, 3695.5380577427823, 3708.610595887447, 3717.219383929351, 3720.795356255432, 3725.0501122213664, 4223.577642235752, 4227.87316380509, 4235.618794308527, 4239.236937351245, 4245.936310955276, 4680.765961701902, 4895.4370247422, 4981.850544483671, 4985.4017517897355, 5005.249212618136, 1263.1852455166365, 1276.910616256853, 1277.3230188000375, 1277.9105462617713, 1282.1537785062, 779.5244900610624, 800.3677095094927, 810.8864758933754, 812.244612510371, 813.3858936502902, 4217.62041416274, 4259.126878989861, 4359.694821362475, 4399.274119770202, 4402.7358358498595, 5104.591632669378, 5115.7442127893455, 5141.691498510101, 5142.408623008327, 5155.664881782672, 4353.651707863363, 4370.147821174904, 4370.972821386971, 4373.584509471595, 4380.518143004267, 2606.0, 2685.825421347606, 2688.8252263602803, 2700.9594856077174, 2719.7552220300267, 4776.665633405628, 4782.713037217778, 4808.422989241313, 4811.975940120277, 4836.395450568679, 5260.395054568697, 5275.894482110413, 5287.863109431491, 5301.310829592206, 5303.0, 4573.291139873325, 4856.975715121402, 4886.267059940967, 4888.315635810989, 4895.8041678332465, 5170.508801663836, 5184.870378240575, 5189.766460509361, 5194.402643696023, 5225.190095535198, 4932.186189278729, 5123.820666276662, 5125.641205115606, 5130.12787826078, 5158.329417175743, 4841.273808928689, 4877.244027175793, 4893.09477746618, 4895.0, 4898.559129678346, 3722.683571896419, 3739.1586892948867, 3747.437884317374, 3772.490713753608, 3772.773633581994, 5229.686218826882, 5233.973830130825, 5255.710935898563, 5262.50018744656, 5264.9210261846865, 4764.97553026101, 5007.474215207497, 5044.9243261350375, 5047.343845300087, 5055.696658200372, 5040.4455509894615, 5045.763787871995, 5050.6009645418135, 5064.670796398223, 5104.540591346797, 4887.120318342732, 5034.496550344937, 5047.141985862486, 5048.0, 5053.418856831511, 4845.927311090407, 4928.654994150375, 5016.2224855147515, 5063.468335824686, 5070.0, 4911.950880491149, 4932.383452068491, 4934.777934992862, 4946.851594452172, 4947.60419166473], "value": 3764.7338937049735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.4119174996127, 617.3918690090258, 666.9032990216418, 707.8478127202653, 713.6931119618564, 13192.73614527709, 13732.450701971922, 13755.105918115316, 13761.724765504683, 13771.173729576232, 13641.931790341034, 13861.376238069286, 13870.583882483517, 13872.30638468075, 13881.4447422103, 621.1676353686059, 761.2577736706722, 766.6741634805213, 780.9492382995115, 789.9763007109767, 781.3241546062671, 790.632355954481, 790.8022994251437, 791.0546896458734, 792.3977776889577, 839.9496030238174, 840.5587066789923, 840.848647243496, 841.3521588376976, 844.4131328726537, 659.0575477067791, 798.528867967896, 821.650798410679, 836.3184005035877, 836.4646626159263, 601.3054921565599, 602.0096940532843, 602.6083046020078, 602.7106988645431, 602.7588964414225, 12616.243025418413, 12644.936775316157, 12668.80996785049, 12681.0, 12683.746325073447, 12713.237205767684, 13104.475820967149, 13461.932690336584, 13620.863791362059, 13650.557323647417, 11845.881541184564, 12048.277103373826, 12116.09129315296, 12161.574344897886, 12445.128840981135, 3910.648041676263, 4038.858639947617, 4080.8367665293354, 4099.631033206996, 4102.589741025873, 64290.05549632812, 64643.0, 65911.340886591, 66665.66667166633, 67751.322486775, 9986.850197247048, 10012.24908131893, 10169.593216271267, 10196.490175491195, 10231.0, 17211.31154753796, 17351.219195136247, 17600.559986000197, 17600.735988960176, 17715.29138834432, 6650.800475985729, 6706.664666766642, 6897.655117244118, 6930.410915072275, 7057.964710176417, 3685.0971511979737, 3695.3902606069696, 3704.870329538453, 3775.0751065989007, 3813.7521061130938, 2810.4800611886867, 2922.853857307126, 2925.3564215872475, 3347.5648165738785, 3509.6841284284537, 552.9806456773994, 556.1407625219047, 560.9158626206101, 570.5264630356877, 571.9685417302005, 598.5510866849862, 606.2300877885061, 619.767587154817, 626.2172284644195, 641.9614823110628, 8589.957050214709, 8781.560921954004, 8824.51465169409, 8831.3376496763, 9245.953770231106, 469.15083698505816, 605.0319488817856, 613.4387035862136, 620.8359326263256, 629.697745082363, 4034.172994536113, 4119.752814831059, 4151.937720934188, 4226.556211597738, 4328.935065974013, 104681.0, 105774.0, 106208.46895765472, 106464.0, 110625.44687276513, 89717.0, 89884.0, 91084.0, 91773.54113229392, 92604.0, 48111.0, 63329.0, 79507.0, 81851.0, 82422.58788706257, 7935.761927142195, 7959.363250940027, 8311.613664067096, 8328.375371847256, 8342.207490288412, 47596.7620161897, 48988.0, 49403.25895111504, 50003.49996500134, 50208.24687629615, 62745.37254627578, 63557.0, 69812.65093674499, 69865.65067174632, 69879.65060174666, 9303.0, 9533.332666713264, 9758.658446954607, 9772.12050915421, 9786.168175705145, 11906.769723612408, 12088.576899808464, 12269.447874845475, 12579.433925473193, 12960.157589756636, 10656.0, 10662.306950048222, 10766.0, 11050.33697978124, 11176.832347514957, 10588.205884558534, 10816.0, 11021.779564408827, 11507.252028618113, 11734.119941004288, 11077.667669969915, 11129.610463633733, 11790.705232369259, 11921.880781192078, 11969.281843089442, 8314.667413303641, 8786.428882122642, 8793.560321984003, 9024.548772561477, 9026.593803278736, 9177.495237761987, 9431.0, 10007.549660265451, 10015.749606259902, 10036.397816131055], "value": 6666.4089103035885, "unit": "score"}], "name": "dromaeo_css", "value": 6349.884595692322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 119.0, 115.0, 114.0, 116.0, 116.0, 113.0, 115.0, 115.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 121.0, 125.0, 196.0, 128.0, 123.0, 148.0, 122.0, 169.0, 125.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 113.0, 77.0, 77.0, 75.0, 75.0, 109.0, 77.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 135.0, 86.0, 86.0, 86.0, 127.0, 86.0, 86.0, 124.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 64.0, 62.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 67.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 79.0, 79.0, 80.0, 83.0, 80.0, 85.0, 80.0, 78.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 129.0, 130.0, 131.0, 129.0, 128.0, 131.0, 149.0, 130.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 166.0, 170.0, 170.0, 166.0, 169.0, 171.0, 166.0, 168.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1532.9999999999998, "alertThreshold": 2.0}]} 23:07:59 INFO - Return code: 0 23:07:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:07:59 INFO - # TBPL SUCCESS # 23:07:59 INFO - Running post-action listener: _resource_record_post_action 23:07:59 INFO - [mozharness: 2016-08-10 06:07:59.726150Z] Finished run-tests step (success) 23:07:59 INFO - Running post-run listener: _resource_record_post_run 23:07:59 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548122624; Read time: 16; Write time: 677912 23:07:59 INFO - TinderboxPrint: CPU usage
13.0% 23:07:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:07:59 INFO - TinderboxPrint: I/O write bytes / time
548,122,624 / 677,912 23:07:59 INFO - TinderboxPrint: CPU idle
8,341.5 (87.0%) 23:07:59 INFO - TinderboxPrint: CPU user
1,198.1 (12.5%) 23:07:59 INFO - TinderboxPrint: Swap in / out
0 / 0 23:07:59 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 255664128; Read time: 0; Write time: 350116 23:07:59 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 292188160; Read time: 0; Write time: 327772 23:08:00 INFO - Running post-run listener: _upload_blobber_files 23:08:00 INFO - Blob upload gear active. 23:08:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:00 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 23:08:00 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'] 23:08:00 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 23:08:00 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:01 INFO - (blobuploader) - INFO - Done attempting. 23:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:08:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:02 INFO - (blobuploader) - INFO - Done attempting. 23:08:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:06 INFO - (blobuploader) - INFO - Done attempting. 23:08:06 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:06 INFO - Return code: 0 23:08:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d64407c803cf8935863108777101c3f4e7fe4c857fcf959634c74daff1d9c8b4473014f859271bac60db3b03c72f60cd19e698f6ec4ff729dc4677b5b41a69a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ba1975515895c592cca2872240f2d35f44a6cd9438bef6efaf7dcda6d3df2436c326d5fe64811e09847c20b383ed6d298782dd1cca9c827709aad96508a7916d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/762f3878c05f96fe19c840579662b056f65590f2a5719c7c7d7196dee48916fb3f9da9dbb42b63cf460eaba7ffb7f969cb846ceaac88819fff8b5bc24d677ceb"} 23:08:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:06 INFO - Contents: 23:08:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d64407c803cf8935863108777101c3f4e7fe4c857fcf959634c74daff1d9c8b4473014f859271bac60db3b03c72f60cd19e698f6ec4ff729dc4677b5b41a69a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ba1975515895c592cca2872240f2d35f44a6cd9438bef6efaf7dcda6d3df2436c326d5fe64811e09847c20b383ed6d298782dd1cca9c827709aad96508a7916d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/762f3878c05f96fe19c840579662b056f65590f2a5719c7c7d7196dee48916fb3f9da9dbb42b63cf460eaba7ffb7f969cb846ceaac88819fff8b5bc24d677ceb"} 23:08:06 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:06 INFO - Copying logs to upload dir... 23:08:06 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.066431 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-09 23:08:06.449983) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:06.453700) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 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/d64407c803cf8935863108777101c3f4e7fe4c857fcf959634c74daff1d9c8b4473014f859271bac60db3b03c72f60cd19e698f6ec4ff729dc4677b5b41a69a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ba1975515895c592cca2872240f2d35f44a6cd9438bef6efaf7dcda6d3df2436c326d5fe64811e09847c20b383ed6d298782dd1cca9c827709aad96508a7916d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/762f3878c05f96fe19c840579662b056f65590f2a5719c7c7d7196dee48916fb3f9da9dbb42b63cf460eaba7ffb7f969cb846ceaac88819fff8b5bc24d677ceb"} build_url:https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/bbvU3ozqQ6SDon-SflJQ9Q/artifacts/public/build/firefox-51.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/d64407c803cf8935863108777101c3f4e7fe4c857fcf959634c74daff1d9c8b4473014f859271bac60db3b03c72f60cd19e698f6ec4ff729dc4677b5b41a69a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ba1975515895c592cca2872240f2d35f44a6cd9438bef6efaf7dcda6d3df2436c326d5fe64811e09847c20b383ed6d298782dd1cca9c827709aad96508a7916d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/762f3878c05f96fe19c840579662b056f65590f2a5719c7c7d7196dee48916fb3f9da9dbb42b63cf460eaba7ffb7f969cb846ceaac88819fff8b5bc24d677ceb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:06.479166) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:06.479505) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1470807545.244625-137207892 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004405 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:06.563360) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:08:06.563687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:08:06.564043) ========= ========= Total master_lag: 0.18 =========