builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1470612984.31 results: success (0) buildid: 20160807150652 builduid: 5cdf8e2fabc041a3b0e7c69a7e386686 revision: 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.305577) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.306191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.306596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.323674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.323989) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 16:36: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-07 16:36:25 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.456319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.456828) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.506974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:24.507360) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c --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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 16:36:25,146 truncating revision to first 12 chars 2016-08-07 16:36:25,146 Setting DEBUG logging. 2016-08-07 16:36:25,146 attempt 1/10 2016-08-07 16:36:25,146 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/763fe887c37c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 16:36:25,460 unpacking tar archive at: autoland-763fe887c37c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.694488 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:25.215012) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:25.215290) ========= script_repo_revision: 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:25.215651) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:25.215915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:36:25.226319) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-07 16:36:25.226581) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:36:25 INFO - MultiFileLogger online at 20160807 16:36:25 in /builds/slave/test 16:36:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 16:36:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:36:25 INFO - {'append_to_log': False, 16:36:25 INFO - 'base_work_dir': '/builds/slave/test', 16:36:25 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 16:36:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:36:25 INFO - 'branch': 'Autoland-Non-PGO', 16:36:25 INFO - 'buildbot_json_path': 'buildprops.json', 16:36:25 INFO - 'config_files': ('talos/linux_config.py',), 16:36:25 INFO - 'default_actions': ('clobber', 16:36:25 INFO - 'read-buildbot-config', 16:36:25 INFO - 'download-and-extract', 16:36:25 INFO - 'populate-webroot', 16:36:25 INFO - 'create-virtualenv', 16:36:25 INFO - 'install', 16:36:25 INFO - 'run-tests'), 16:36:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:36:25 INFO - 'download_minidump_stackwalk': True, 16:36:25 INFO - 'download_symbols': 'ondemand', 16:36:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:36:25 INFO - 'tooltool.py': '/tools/tooltool.py', 16:36:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:36:25 INFO - '/tools/misc-python/virtualenv.py')}, 16:36:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:36:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:36:25 INFO - 'installer_path': 'installer.exe', 16:36:25 INFO - 'log_level': 'info', 16:36:25 INFO - 'log_name': 'talos', 16:36:25 INFO - 'log_to_console': True, 16:36:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:36:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:36:25 INFO - 'opt_config_files': (), 16:36:25 INFO - 'pip_index': False, 16:36:25 INFO - 'sps_profile': False, 16:36:25 INFO - 'sps_profile_interval': 0, 16:36:25 INFO - 'suite': 'dromaeojs', 16:36:25 INFO - 'system_bits': '32', 16:36:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:36:25 INFO - 'title': 'talos-linux64-ix-037', 16:36:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:36:25 INFO - 'use_talos_json': True, 16:36:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:36:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:36:25 INFO - 'work_dir': 'build'} 16:36:25 INFO - [mozharness: 2016-08-07 23:36:25.954922Z] Running clobber step. 16:36:25 INFO - Running pre-action listener: _resource_record_pre_action 16:36:25 INFO - Running main action method: clobber 16:36:25 INFO - rmtree: /builds/slave/test/build 16:36:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:36:26 INFO - Running post-action listener: _resource_record_post_action 16:36:26 INFO - [mozharness: 2016-08-07 23:36:26.408646Z] Finished clobber step (success) 16:36:26 INFO - [mozharness: 2016-08-07 23:36:26.408745Z] Running read-buildbot-config step. 16:36:26 INFO - Running pre-action listener: _resource_record_pre_action 16:36:26 INFO - Running main action method: read_buildbot_config 16:36:26 INFO - Using buildbot properties: 16:36:26 INFO - { 16:36:26 INFO - "project": "", 16:36:26 INFO - "product": "firefox", 16:36:26 INFO - "script_repo_revision": "production", 16:36:26 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 16:36:26 INFO - "repository": "", 16:36:26 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 16:36:26 INFO - "buildid": "20160807150652", 16:36:26 INFO - "pgo_build": "False", 16:36:26 INFO - "basedir": "/builds/slave/test", 16:36:26 INFO - "buildnumber": 186, 16:36:26 INFO - "slavename": "talos-linux64-ix-037", 16:36:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:36:26 INFO - "platform": "ubuntu64_hw", 16:36:26 INFO - "scheduler": "tests-autoland-linux64-talos", 16:36:26 INFO - "branch": "autoland", 16:36:26 INFO - "repo_path": "integration/autoland", 16:36:26 INFO - "revision": "763fe887c37cee5fcfe0f00e94fdffc84a41ea1c", 16:36:26 INFO - "stage_platform": "linux64", 16:36:26 INFO - "builduid": "5cdf8e2fabc041a3b0e7c69a7e386686", 16:36:26 INFO - "slavebuilddir": "test" 16:36:26 INFO - } 16:36:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:36:26 INFO - Running post-action listener: _resource_record_post_action 16:36:26 INFO - [mozharness: 2016-08-07 23:36:26.411205Z] Finished read-buildbot-config step (success) 16:36:26 INFO - [mozharness: 2016-08-07 23:36:26.411294Z] Running download-and-extract step. 16:36:26 INFO - Running pre-action listener: _resource_record_pre_action 16:36:26 INFO - Running main action method: download_and_extract 16:36:26 INFO - mkdir: /builds/slave/test/build/tests 16:36: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')]} 16:36:26 INFO - trying https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:36:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:26 INFO - Downloaded 1361 bytes. 16:36:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:36:26 INFO - Using the following test package requirements: 16:36:26 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:36:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:36:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'jsshell-linux-x86_64.zip'], 16:36:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:36:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:36:26 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:36:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:36:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:36:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:36: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')]} 16:36:26 INFO - trying https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:36:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:28 INFO - Downloaded 20257815 bytes. 16:36: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'] 16:36: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 16:36:29 INFO - Return code: 0 16:36: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 16:36: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')]} 16:36:29 INFO - trying https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:36:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:30 INFO - Downloaded 20257815 bytes. 16:36: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'] 16:36: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 16:36:32 INFO - Return code: 0 16:36: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')]} 16:36:32 INFO - trying https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:36:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:32 INFO - Downloaded 14413723 bytes. 16:36:32 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'] 16:36:32 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 16:36:33 INFO - Return code: 0 16:36: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')]} 16:36:33 INFO - trying https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:36:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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 16:36:34 INFO - Downloaded 56129669 bytes. 16:36:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:36:34 INFO - mkdir: /builds/slave/test/properties 16:36:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:36:34 INFO - Writing to file /builds/slave/test/properties/build_url 16:36:34 INFO - Contents: 16:36:34 INFO - build_url:https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:36:34 INFO - Running post-action listener: _resource_record_post_action 16:36:34 INFO - Running post-action listener: set_extra_try_arguments 16:36:34 INFO - [mozharness: 2016-08-07 23:36:34.990129Z] Finished download-and-extract step (success) 16:36:34 INFO - [mozharness: 2016-08-07 23:36:34.990360Z] Running populate-webroot step. 16:36:34 INFO - Running pre-action listener: _resource_record_pre_action 16:36:34 INFO - Running main action method: populate_webroot 16:36:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:36:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:36:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:36:35 INFO - '--tppagecycles', 16:36:35 INFO - '7'], 16:36:35 INFO - 'tests': ['tsvgm']}}, 16:36:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:36:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:36:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:36:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:36:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:36:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:36:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['damp', 'tps']}, 16:36:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'tests': ['damp', 'tps']}, 16:36:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['dromaeo_dom']}, 16:36:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:36:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['basic_compositor_video']}, 16:36:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:36:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'other-e10s': {'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['a11yr', 16:36:35 INFO - 'ts_paint', 16:36:35 INFO - 'tpaint', 16:36:35 INFO - 'sessionrestore', 16:36:35 INFO - 'sessionrestore_no_auto_restore', 16:36:35 INFO - 'tabpaint']}, 16:36:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['tsvgx', 16:36:35 INFO - 'tsvgr_opacity', 16:36:35 INFO - 'tart', 16:36:35 INFO - 'tscrollx', 16:36:35 INFO - 'cart']}, 16:36:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:36:35 INFO - 'tsvgr_opacity', 16:36:35 INFO - 'tart', 16:36:35 INFO - 'tscrollx', 16:36:35 INFO - 'cart']}, 16:36:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'talos_options': ['--disable-e10s'], 16:36:35 INFO - 'tests': ['tp5o']}, 16:36:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'tests': ['tp5o']}, 16:36:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'talos_options': ['--disable-e10s', 16:36:35 INFO - '--xperf_path', 16:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:36:35 INFO - 'tests': ['tp5n']}, 16:36:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:35 INFO - 'talos_options': ['--xperf_path', 16:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:36:35 INFO - 'tests': ['tp5n']}}, 16:36:35 INFO - 'talos.zip': {'path': '', 16:36:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:36:35 INFO - Running post-action listener: _resource_record_post_action 16:36:35 INFO - [mozharness: 2016-08-07 23:36:35.010771Z] Finished populate-webroot step (success) 16:36:35 INFO - [mozharness: 2016-08-07 23:36:35.010859Z] Running create-virtualenv step. 16:36:35 INFO - Running pre-action listener: _resource_record_pre_action 16:36:35 INFO - Running main action method: create_virtualenv 16:36:35 INFO - Creating virtualenv /builds/slave/test/build/venv 16:36:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:36:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:36:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:36:35 INFO - Using real prefix '/usr' 16:36:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:36:35 INFO - Installing distribute.............................................................................................................................................................................................done. 16:36:37 INFO - Installing pip.................done. 16:36:37 INFO - Return code: 0 16:36:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:37 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 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:36:37 INFO - Using env: {'DISPLAY': ':0', 16:36:37 INFO - 'HOME': '/home/cltbld', 16:36:37 INFO - 'LANG': 'en_US.UTF-8', 16:36:37 INFO - 'LANGUAGE': 'en_US:en', 16:36:37 INFO - 'LOGNAME': 'cltbld', 16:36:37 INFO - 'MAIL': '/var/mail/cltbld', 16:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:37 INFO - 'MOZ_NO_REMOTE': '1', 16:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:37 INFO - 'NO_EM_RESTART': '1', 16:36:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:37 INFO - 'PWD': '/builds/slave/test', 16:36:37 INFO - 'SHELL': '/bin/bash', 16:36:37 INFO - 'SHLVL': '1', 16:36:37 INFO - 'TERM': 'linux', 16:36:37 INFO - 'TMOUT': '86400', 16:36:37 INFO - 'USER': 'cltbld', 16:36:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:37 INFO - '_': '/tools/buildbot/bin/python'} 16:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:36:37 INFO - Cleaning up... 16:36:37 INFO - Return code: 0 16:36:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:37 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 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:36:37 INFO - Using env: {'DISPLAY': ':0', 16:36:37 INFO - 'HOME': '/home/cltbld', 16:36:37 INFO - 'LANG': 'en_US.UTF-8', 16:36:37 INFO - 'LANGUAGE': 'en_US:en', 16:36:37 INFO - 'LOGNAME': 'cltbld', 16:36:37 INFO - 'MAIL': '/var/mail/cltbld', 16:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:37 INFO - 'MOZ_NO_REMOTE': '1', 16:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:37 INFO - 'NO_EM_RESTART': '1', 16:36:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:37 INFO - 'PWD': '/builds/slave/test', 16:36:37 INFO - 'SHELL': '/bin/bash', 16:36:37 INFO - 'SHLVL': '1', 16:36:37 INFO - 'TERM': 'linux', 16:36:37 INFO - 'TMOUT': '86400', 16:36:37 INFO - 'USER': 'cltbld', 16:36:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:37 INFO - '_': '/tools/buildbot/bin/python'} 16:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:38 INFO - Downloading/unpacking psutil>=3.1.1 16:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:36:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:36:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:36:40 INFO - Installing collected packages: psutil 16:36:40 INFO - Running setup.py install for psutil 16:36:40 INFO - building 'psutil._psutil_linux' extension 16:36:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:36:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:36:40 INFO - building 'psutil._psutil_posix' extension 16:36:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:36:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:36:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:36:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:36:40 INFO - Successfully installed psutil 16:36:40 INFO - Cleaning up... 16:36:40 INFO - Return code: 0 16:36:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:36:40 INFO - Using env: {'DISPLAY': ':0', 16:36:40 INFO - 'HOME': '/home/cltbld', 16:36:40 INFO - 'LANG': 'en_US.UTF-8', 16:36:40 INFO - 'LANGUAGE': 'en_US:en', 16:36:40 INFO - 'LOGNAME': 'cltbld', 16:36:40 INFO - 'MAIL': '/var/mail/cltbld', 16:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:40 INFO - 'MOZ_NO_REMOTE': '1', 16:36:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:40 INFO - 'NO_EM_RESTART': '1', 16:36:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:40 INFO - 'PWD': '/builds/slave/test', 16:36:40 INFO - 'SHELL': '/bin/bash', 16:36:40 INFO - 'SHLVL': '1', 16:36:40 INFO - 'TERM': 'linux', 16:36:40 INFO - 'TMOUT': '86400', 16:36:40 INFO - 'USER': 'cltbld', 16:36:40 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:40 INFO - '_': '/tools/buildbot/bin/python'} 16:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:36:42 INFO - Installing collected packages: mozsystemmonitor 16:36:42 INFO - Running setup.py install for mozsystemmonitor 16:36:42 INFO - Successfully installed mozsystemmonitor 16:36:42 INFO - Cleaning up... 16:36:42 INFO - Return code: 0 16:36:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:36: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')]} 16:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36: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')]} 16:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36: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', '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 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:36: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 jsonschema==2.5.1 16:36:42 INFO - Using env: {'DISPLAY': ':0', 16:36:42 INFO - 'HOME': '/home/cltbld', 16:36:42 INFO - 'LANG': 'en_US.UTF-8', 16:36:42 INFO - 'LANGUAGE': 'en_US:en', 16:36:42 INFO - 'LOGNAME': 'cltbld', 16:36:42 INFO - 'MAIL': '/var/mail/cltbld', 16:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:42 INFO - 'MOZ_NO_REMOTE': '1', 16:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:42 INFO - 'NO_EM_RESTART': '1', 16:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:42 INFO - 'PWD': '/builds/slave/test', 16:36:42 INFO - 'SHELL': '/bin/bash', 16:36:42 INFO - 'SHLVL': '1', 16:36:42 INFO - 'TERM': 'linux', 16:36:42 INFO - 'TMOUT': '86400', 16:36:42 INFO - 'USER': 'cltbld', 16:36:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:42 INFO - '_': '/tools/buildbot/bin/python'} 16:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:43 INFO - Downloading/unpacking jsonschema==2.5.1 16:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:36:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36:45 INFO - Downloading functools32-3.2.3-2.tar.gz 16:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:36:45 INFO - warning: no files found matching '*.txt' 16:36:45 INFO - no previously-included directories found matching 'build' 16:36:45 INFO - no previously-included directories found matching 'dist' 16:36:45 INFO - no previously-included directories found matching '.git*' 16:36:45 INFO - Installing collected packages: jsonschema, functools32 16:36:45 INFO - Running setup.py install for functools32 16:36:45 INFO - warning: no files found matching '*.txt' 16:36:45 INFO - no previously-included directories found matching 'build' 16:36:45 INFO - no previously-included directories found matching 'dist' 16:36:45 INFO - no previously-included directories found matching '.git*' 16:36:45 INFO - Successfully installed jsonschema functools32 16:36:45 INFO - Cleaning up... 16:36:45 INFO - Return code: 0 16:36:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:36:45 INFO - Using env: {'DISPLAY': ':0', 16:36:45 INFO - 'HOME': '/home/cltbld', 16:36:45 INFO - 'LANG': 'en_US.UTF-8', 16:36:45 INFO - 'LANGUAGE': 'en_US:en', 16:36:45 INFO - 'LOGNAME': 'cltbld', 16:36:45 INFO - 'MAIL': '/var/mail/cltbld', 16:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:45 INFO - 'MOZ_NO_REMOTE': '1', 16:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:45 INFO - 'NO_EM_RESTART': '1', 16:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:45 INFO - 'PWD': '/builds/slave/test', 16:36:45 INFO - 'SHELL': '/bin/bash', 16:36:45 INFO - 'SHLVL': '1', 16:36:45 INFO - 'TERM': 'linux', 16:36:45 INFO - 'TMOUT': '86400', 16:36:45 INFO - 'USER': 'cltbld', 16:36:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:45 INFO - '_': '/tools/buildbot/bin/python'} 16:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:36:45 INFO - Cleaning up... 16:36:45 INFO - Return code: 0 16:36:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:36:45 INFO - Using env: {'DISPLAY': ':0', 16:36:45 INFO - 'HOME': '/home/cltbld', 16:36:45 INFO - 'LANG': 'en_US.UTF-8', 16:36:45 INFO - 'LANGUAGE': 'en_US:en', 16:36:45 INFO - 'LOGNAME': 'cltbld', 16:36:45 INFO - 'MAIL': '/var/mail/cltbld', 16:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:45 INFO - 'MOZ_NO_REMOTE': '1', 16:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:45 INFO - 'NO_EM_RESTART': '1', 16:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:45 INFO - 'PWD': '/builds/slave/test', 16:36:45 INFO - 'SHELL': '/bin/bash', 16:36:45 INFO - 'SHLVL': '1', 16:36:45 INFO - 'TERM': 'linux', 16:36:45 INFO - 'TMOUT': '86400', 16:36:45 INFO - 'USER': 'cltbld', 16:36:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:45 INFO - '_': '/tools/buildbot/bin/python'} 16:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:45 INFO - Downloading/unpacking blobuploader==1.2.4 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36:47 INFO - Downloading blobuploader-1.2.4.tar.gz 16:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:36:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36: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 16:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:36:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:48 INFO - Downloading docopt-0.6.1.tar.gz 16:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:36:48 INFO - Installing collected packages: blobuploader, requests, docopt 16:36:48 INFO - Running setup.py install for blobuploader 16:36:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:36:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:36:48 INFO - Running setup.py install for requests 16:36:49 INFO - Running setup.py install for docopt 16:36:49 INFO - Successfully installed blobuploader requests docopt 16:36:49 INFO - Cleaning up... 16:36:49 INFO - Return code: 0 16:36:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:36:49 INFO - Using env: {'DISPLAY': ':0', 16:36:49 INFO - 'HOME': '/home/cltbld', 16:36:49 INFO - 'LANG': 'en_US.UTF-8', 16:36:49 INFO - 'LANGUAGE': 'en_US:en', 16:36:49 INFO - 'LOGNAME': 'cltbld', 16:36:49 INFO - 'MAIL': '/var/mail/cltbld', 16:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:49 INFO - 'MOZ_NO_REMOTE': '1', 16:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:49 INFO - 'NO_EM_RESTART': '1', 16:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:49 INFO - 'PWD': '/builds/slave/test', 16:36:49 INFO - 'SHELL': '/bin/bash', 16:36:49 INFO - 'SHLVL': '1', 16:36:49 INFO - 'TERM': 'linux', 16:36:49 INFO - 'TMOUT': '86400', 16:36:49 INFO - 'USER': 'cltbld', 16:36:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:49 INFO - '_': '/tools/buildbot/bin/python'} 16:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:36:49 INFO - Running setup.py (path:/tmp/pip-GwpTG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:36:49 INFO - Running setup.py (path:/tmp/pip-ORrB18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:36:49 INFO - Running setup.py (path:/tmp/pip-iYvRrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:36:49 INFO - Running setup.py (path:/tmp/pip-rmaL1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:36:49 INFO - Running setup.py (path:/tmp/pip-D3FcmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:36:49 INFO - Running setup.py (path:/tmp/pip-7AJNXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:36:49 INFO - Running setup.py (path:/tmp/pip-RDlCeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:36:49 INFO - Running setup.py (path:/tmp/pip-mE219D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:36:49 INFO - Running setup.py (path:/tmp/pip-cbBQK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:36:50 INFO - Running setup.py (path:/tmp/pip-RpKoMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:36:50 INFO - Running setup.py (path:/tmp/pip-D0jWIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:36:50 INFO - Running setup.py (path:/tmp/pip-SxlurA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:36:50 INFO - Running setup.py (path:/tmp/pip-E3myQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:36:50 INFO - Running setup.py (path:/tmp/pip-fiE18O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:50 INFO - Running setup.py (path:/tmp/pip-sZ0NTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:36:50 INFO - Running setup.py (path:/tmp/pip-Tqf5Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:36:50 INFO - Running setup.py (path:/tmp/pip-vfXNAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:36:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:36:50 INFO - Running setup.py install for manifestparser 16:36:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:36:50 INFO - Running setup.py install for mozcrash 16:36:50 INFO - Running setup.py install for mozdebug 16:36:50 INFO - Running setup.py install for mozdevice 16:36:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozfile 16:36:51 INFO - Running setup.py install for mozhttpd 16:36:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozinfo 16:36:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozInstall 16:36:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozleak 16:36:51 INFO - Running setup.py install for mozlog 16:36:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for moznetwork 16:36:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozprocess 16:36:51 INFO - Running setup.py install for mozprofile 16:36:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:36:51 INFO - Running setup.py install for mozrunner 16:36:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:36:52 INFO - Running setup.py install for mozscreenshot 16:36:52 INFO - Running setup.py install for moztest 16:36:52 INFO - Running setup.py install for mozversion 16:36:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:36:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:36:52 INFO - Cleaning up... 16:36:52 INFO - Return code: 0 16:36:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:36:52 INFO - Using env: {'DISPLAY': ':0', 16:36:52 INFO - 'HOME': '/home/cltbld', 16:36:52 INFO - 'LANG': 'en_US.UTF-8', 16:36:52 INFO - 'LANGUAGE': 'en_US:en', 16:36:52 INFO - 'LOGNAME': 'cltbld', 16:36:52 INFO - 'MAIL': '/var/mail/cltbld', 16:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:52 INFO - 'MOZ_NO_REMOTE': '1', 16:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:52 INFO - 'NO_EM_RESTART': '1', 16:36:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:52 INFO - 'PWD': '/builds/slave/test', 16:36:52 INFO - 'SHELL': '/bin/bash', 16:36:52 INFO - 'SHLVL': '1', 16:36:52 INFO - 'TERM': 'linux', 16:36:52 INFO - 'TMOUT': '86400', 16:36:52 INFO - 'USER': 'cltbld', 16:36:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:52 INFO - '_': '/tools/buildbot/bin/python'} 16:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:36:52 INFO - Running setup.py (path:/tmp/pip-bshaVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:36:52 INFO - Running setup.py (path:/tmp/pip-MAkGyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:36:52 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)) 16:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:36:52 INFO - Running setup.py (path:/tmp/pip-ArqVhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:36:52 INFO - Running setup.py (path:/tmp/pip-JzmgEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:36:52 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)) 16:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:36:52 INFO - Running setup.py (path:/tmp/pip-COfd5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:36:53 INFO - Running setup.py (path:/tmp/pip-E1TCsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:36:53 INFO - Running setup.py (path:/tmp/pip-O5Jbab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:36:53 INFO - Running setup.py (path:/tmp/pip-FR_8fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:36:53 INFO - Running setup.py (path:/tmp/pip-EjVSmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:36:53 INFO - Running setup.py (path:/tmp/pip-o2Z9Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:36:53 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)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:36:53 INFO - Running setup.py (path:/tmp/pip-mFALbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:36:53 INFO - Running setup.py (path:/tmp/pip-8R5Wc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:36:53 INFO - Running setup.py (path:/tmp/pip-9mXKlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:36:53 INFO - Running setup.py (path:/tmp/pip-w1ELkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:53 INFO - Running setup.py (path:/tmp/pip-9fzLlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:36:53 INFO - Running setup.py (path:/tmp/pip-OOCRaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:36:53 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)) 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:36:53 INFO - Running setup.py (path:/tmp/pip-vEGYl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:36:53 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)) 16:36:53 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)) 16:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:36:53 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)) 16:36:53 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)) 16:36:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:55 INFO - Downloading blessings-1.6.tar.gz 16:36:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:36:55 INFO - Installing collected packages: blessings 16:36:55 INFO - Running setup.py install for blessings 16:36:55 INFO - Successfully installed blessings 16:36:55 INFO - Cleaning up... 16:36:56 INFO - Return code: 0 16:36:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:36:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:36:56 INFO - Reading from file tmpfile_stdout 16:36:56 INFO - Current package versions: 16:36:56 INFO - argparse == 1.2.1 16:36:56 INFO - blessings == 1.6 16:36:56 INFO - blobuploader == 1.2.4 16:36:56 INFO - docopt == 0.6.1 16:36:56 INFO - functools32 == 3.2.3-2 16:36:56 INFO - jsonschema == 2.5.1 16:36:56 INFO - manifestparser == 1.1 16:36:56 INFO - mozInstall == 1.12 16:36:56 INFO - mozcrash == 0.17 16:36:56 INFO - mozdebug == 0.1 16:36:56 INFO - mozdevice == 0.48 16:36:56 INFO - mozfile == 1.2 16:36:56 INFO - mozhttpd == 0.7 16:36:56 INFO - mozinfo == 0.9 16:36:56 INFO - mozleak == 0.1 16:36:56 INFO - mozlog == 3.3 16:36:56 INFO - moznetwork == 0.27 16:36:56 INFO - mozprocess == 0.23 16:36:56 INFO - mozprofile == 0.28 16:36:56 INFO - mozrunner == 6.12 16:36:56 INFO - mozscreenshot == 0.1 16:36:56 INFO - mozsystemmonitor == 0.3 16:36:56 INFO - moztest == 0.7 16:36:56 INFO - mozversion == 1.4 16:36:56 INFO - psutil == 3.1.1 16:36:56 INFO - requests == 1.2.3 16:36:56 INFO - wsgiref == 0.1.2 16:36:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:36:56 INFO - Using env: {'DISPLAY': ':0', 16:36:56 INFO - 'HOME': '/home/cltbld', 16:36:56 INFO - 'LANG': 'en_US.UTF-8', 16:36:56 INFO - 'LANGUAGE': 'en_US:en', 16:36:56 INFO - 'LOGNAME': 'cltbld', 16:36:56 INFO - 'MAIL': '/var/mail/cltbld', 16:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:56 INFO - 'MOZ_NO_REMOTE': '1', 16:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:56 INFO - 'NO_EM_RESTART': '1', 16:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:56 INFO - 'PWD': '/builds/slave/test', 16:36:56 INFO - 'SHELL': '/bin/bash', 16:36:56 INFO - 'SHLVL': '1', 16:36:56 INFO - 'TERM': 'linux', 16:36:56 INFO - 'TMOUT': '86400', 16:36:56 INFO - 'USER': 'cltbld', 16:36:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:56 INFO - '_': '/tools/buildbot/bin/python'} 16:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:36:56 INFO - Cleaning up... 16:36:56 INFO - Return code: 0 16:36:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:36:56 INFO - Using env: {'DISPLAY': ':0', 16:36:56 INFO - 'HOME': '/home/cltbld', 16:36:56 INFO - 'LANG': 'en_US.UTF-8', 16:36:56 INFO - 'LANGUAGE': 'en_US:en', 16:36:56 INFO - 'LOGNAME': 'cltbld', 16:36:56 INFO - 'MAIL': '/var/mail/cltbld', 16:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:56 INFO - 'MOZ_NO_REMOTE': '1', 16:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:56 INFO - 'NO_EM_RESTART': '1', 16:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:56 INFO - 'PWD': '/builds/slave/test', 16:36:56 INFO - 'SHELL': '/bin/bash', 16:36:56 INFO - 'SHLVL': '1', 16:36:56 INFO - 'TERM': 'linux', 16:36:56 INFO - 'TMOUT': '86400', 16:36:56 INFO - 'USER': 'cltbld', 16:36:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:56 INFO - '_': '/tools/buildbot/bin/python'} 16:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:36:56 INFO - Cleaning up... 16:36:56 INFO - Return code: 0 16:36:56 INFO - Running post-action listener: _resource_record_post_action 16:36:56 INFO - Running post-action listener: _start_resource_monitoring 16:36:56 INFO - Starting resource monitoring. 16:36:56 INFO - [mozharness: 2016-08-07 23:36:56.761302Z] Finished create-virtualenv step (success) 16:36:56 INFO - [mozharness: 2016-08-07 23:36:56.761842Z] Running install step. 16:36:56 INFO - Running pre-action listener: _resource_record_pre_action 16:36:56 INFO - Running main action method: install 16:36:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:36:56 INFO - Reading from file tmpfile_stdout 16:36:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:36:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:36:57 INFO - Reading from file tmpfile_stdout 16:36:57 INFO - Output received: 16:36:57 INFO - Usage: mozinstall [options] installer 16:36:57 INFO - Options: 16:36:57 INFO - -h, --help show this help message and exit 16:36:57 INFO - -d DEST, --destination=DEST 16:36:57 INFO - Directory to install application into. [default: 16:36:57 INFO - "/builds/slave/test"] 16:36:57 INFO - --app=APP Application being installed. [default: firefox] 16:36:57 INFO - mkdir: /builds/slave/test/build/application 16:36:57 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'] 16:36:57 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 16:37:13 INFO - Reading from file tmpfile_stdout 16:37:13 INFO - Output received: 16:37:13 INFO - /builds/slave/test/build/application/firefox/firefox 16:37:13 INFO - Running post-action listener: _resource_record_post_action 16:37:13 INFO - [mozharness: 2016-08-07 23:37:13.302328Z] Finished install step (success) 16:37:13 INFO - [mozharness: 2016-08-07 23:37:13.302577Z] Running run-tests step. 16:37:13 INFO - Running pre-action listener: _resource_record_pre_action 16:37:13 INFO - Running main action method: run_tests 16:37:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:37:13 INFO - Python 2.7.3 16:37:13 INFO - Return code: 0 16:37:13 INFO - grabbing minidump binary from tooltool 16:37:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24bc3b0>, '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 0x24bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24bd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:37:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:37:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:37:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:37:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:37:13 INFO - Return code: 0 16:37:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:37:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:37:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:37:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:37:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:37:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:37:13 INFO - Using env: {'DISPLAY': ':0', 16:37:13 INFO - 'HOME': '/home/cltbld', 16:37:13 INFO - 'LANG': 'en_US.UTF-8', 16:37:13 INFO - 'LANGUAGE': 'en_US:en', 16:37:13 INFO - 'LOGNAME': 'cltbld', 16:37:13 INFO - 'MAIL': '/var/mail/cltbld', 16:37:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:13 INFO - 'MOZ_NO_REMOTE': '1', 16:37:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:13 INFO - 'NO_EM_RESTART': '1', 16:37:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:13 INFO - 'PWD': '/builds/slave/test', 16:37:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:13 INFO - 'SHELL': '/bin/bash', 16:37:13 INFO - 'SHLVL': '1', 16:37:13 INFO - 'TERM': 'linux', 16:37:13 INFO - 'TMOUT': '86400', 16:37:13 INFO - 'USER': 'cltbld', 16:37:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871', 16:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:13 INFO - '_': '/tools/buildbot/bin/python'} 16:37:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:37:13 INFO - mozversion application_buildid: 20160807150652 16:37:13 INFO - mozversion application_changeset: 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c 16:37:13 INFO - mozversion application_display_name: Nightly 16:37:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:37:13 INFO - mozversion application_name: Firefox 16:37:13 INFO - mozversion application_remotingname: firefox 16:37:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:37:13 INFO - mozversion application_vendor: Mozilla 16:37:13 INFO - mozversion application_version: 51.0a1 16:37:13 INFO - mozversion platform_buildid: 20160807150652 16:37:13 INFO - mozversion platform_changeset: 763fe887c37cee5fcfe0f00e94fdffc84a41ea1c 16:37:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:37:13 INFO - mozversion platform_version: 51.0a1 16:37:13 INFO - using testdate: 1470613033 16:37:13 INFO - actual date: 1470613033 16:37:13 INFO - starting webserver on 'localhost:38337' 16:37:13 INFO - SUITE-START | Running 2 tests 16:37:13 INFO - TEST-START | dromaeo_css 16:37:13 INFO - operating with platform_type : linux_ 16:37:13 INFO - Initialising browser for dromaeo_css test... 16:37:13 INFO - TEST-INFO | started process 10516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCRMa8S/profile http://localhost:38337/getInfo.html) 16:37:17 INFO - PROCESS | 10516 | __metrics Screen width/height:1600/1200 16:37:17 INFO - PROCESS | 10516 | colorDepth:24 16:37:17 INFO - PROCESS | 10516 | Browser inner width/height: 1024/697 16:37:17 INFO - PROCESS | 10516 | __metrics 16:37:18 INFO - PROCESS | 10516 | 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" 16:37:18 INFO - PROCESS | 10516 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:19 INFO - TEST-INFO | 10516: exit 0 16:37:19 INFO - Browser initialized. 16:37:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:37:19 INFO - TEST-INFO | started process 10595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCRMa8S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:31 INFO - PROCESS | 10595 | RSS: Main: 157646848 16:37:31 INFO - PROCESS | 10595 | 16:38:22 INFO - PROCESS | 10595 | 1470613102344 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] 16:40:35 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38337/tests/dromaeo/cssquery-ext.html) 16:40:36 INFO - PROCESS | 10595 | RSS: Main: 234938368 16:40:36 INFO - PROCESS | 10595 | 16:43:39 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-ext.html (next: http://localhost:38337/tests/dromaeo/cssquery-jquery.html) 16:43:40 INFO - PROCESS | 10595 | RSS: Main: 242225152 16:43:40 INFO - PROCESS | 10595 | 16:46:42 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38337/tests/dromaeo/cssquery-mootools.html) 16:46:42 INFO - PROCESS | 10595 | RSS: Main: 229281792 16:46:42 INFO - PROCESS | 10595 | 16:49:45 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38337/tests/dromaeo/cssquery-prototype.html) 16:49:45 INFO - PROCESS | 10595 | RSS: Main: 191418368 16:49:45 INFO - PROCESS | 10595 | 16:52:48 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38337/tests/dromaeo/cssquery-yui.html) 16:52:49 INFO - PROCESS | 10595 | RSS: Main: 192929792 16:52:49 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | Cycle 1(1): loaded http://localhost:38337/tests/dromaeo/cssquery-yui.html (next: http://localhost:38337/tests/dromaeo/cssquery-dojo.html) 16:55:52 INFO - PROCESS | 10595 | RSS: Main: 248516608 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | __start_tp_report 16:55:52 INFO - PROCESS | 10595 | _x_x_mozilla_page_load 16:55:52 INFO - PROCESS | 10595 | _x_x_mozilla_page_load_details 16:55:52 INFO - PROCESS | 10595 | |i|pagename|runs| 16:55:52 INFO - PROCESS | 10595 | |0;dojo.html;2652.3634327761333;2653.7478939500766;2688.6370340004105;2689.4621075784835;2689.5293323668366;4544.136614043329;4607.539246075391;4750.358701575286;4893.14369985253;4899.995500922316;4626.144163329778;4690.108879312937;4732.1482133216;4784.354112194853;4820.855374338776;4420.292753159495;4424.97787511062;4458.353538736881;4458.97770511147;4499.46006479223;4467.285234362503;4515.367848501212;4535.614472769802;4544.136614043321;4606.838760643378;4568.0635469728795;4571.748553829538;4590.426196725392;4600.493945665974;4618.49196588375;4181.874543763662;4248.214080395137;4488.259437192859;4492.797824097891;4531.796069176895;4221.831126754926;4307.547707490699;4311.892202694927;4342.240107981091;4352.042550638855;4756.262779269219;4758;4764.071006153801;4774.28385742138;4789.3294938708605;5097.23541468779;5099.5410413063;5138.97430512845;5150.768215430315;5162.561182299509;4868.659193856432;5104.132297509433;5178.533931946105;5188;5200.427952925175;4794.736289504111;4814.374131362935;4842.6368022398465;4877.975610121926;4884.5603895649565;4984.501549844987;5359.276497672842;5369.624126311199;5369.7583608737705;5371.865703357447;5004;5041.6722913010535;5067.467915868854;5127.41034781005;5144.742762861917;5176.689398636094;5183.429822719498;5184.7407629618365;5224.164133738584;5227.424983251839;4869.805207791719;5121.8719532012;5134.589232861362;5135.87160320995;5184.7407629618365;4943.1596628573225;4972.676776009548;4975.626827987915;4977;4983.651144419856;4250.149970006011;4284.1645879053285;4290.184864875664;4291.570842915746;4293.720908140961;3776.792276424823;3796.8481260749813;3800.847966081381;3801.752886062397;3810.3903375459795;4424.513303536608;4431.224535706238;4470.575295346936;4477.820887164542;4481.529439408815;5156.664816786897;5191.6884986900905;5206.140986737221;5211.921821172686;5256.763445644955;4188.602082802129;4386.122775444924;4421.2262854001065;4428.535003824616;4477.7984990675495;4696.9060618787435;4714.434267887876;4719.622430205575;4759.762011899391;4760.59534939534;4900;4903.877403064952;4909.288153217834;4910.968696573759;4962.950370496321;4898.3142360068905;4947.406311242674;5225.790968361375;5247.107991641354;5290.65610735301;4736.928946065743;4793.376861008047;4816.229403295456;4819.060283244734;4848;5053.7220452875445;5058.3929928408825;5059.342285502861;5067.391912970467;5111.821086261963;4904.607631389409;5105.770240339268;5121.539061484485;5125.846224613268;5128.12821820284;4993.076280888047;5049.343585333883;5058.974705126598;5075.061113693979;5134.409542902614;5060.949390506195;5063.265826455217;5064;5064.620153488429;5112.233165025127;4671.322658214608;4989.650724449399;5009.849704508871;5050.545450909436;5067.41724701664;4990;5046.84859454217;5061.721605311744;5084.3898732152375;5135.614828887923;4944.505549445027;4959.851204463872;4964.3298154748645;4966.106100901872;5017.799288028438 16:55:52 INFO - PROCESS | 10595 | |1;ext.html;573.9684317362546;584.5265335078586;586.6978506069372;589.3193361667273;594.9196858424115;28352.85823570882;37999.81000094999;39242.41136382957;39285.99999999996;44091;39814.40278395826;40251.59748402515;40312;40340.596594034054;40350;7750.224977502247;7892.881606775903;7894.526328420313;7894.960525197366;7906.130325664172;7799.883001754978;7870.976773019528;7881.881771773399;7884;7903.1701671324545;7873.488223265469;7905.0513938327485;7925.801854953615;7926.167752385974;7933.286004259644;7843.921560784376;7875.684972601089;7887.605619718991;7889.763307100796;7894.171112033211;7785.649645765954;7842.058952925686;7842.176571460029;7844.764657060297;7847.607619619053;38590.42114368287;38895.41656875149;38932.80533597342;38960.22079558393;38978.41532377017;3037.179961410407;3094.7214750672547;3104.984475077633;3189.6331921829055;3326.933461330784;29355.41289174226;29486;29553.70446295531;29771.553426698618;31062.53406198909;3391.609964854024;3482.181687303482;3486.9476957845654;3521.4013617685055;3608.0438683748566;234418;234621;234895;235704;236467;52448.73775631174;52664.73667631637;52687.20969185465;52717;52757.73621131947;32879.67120328814;33894.49158262628;33923.660763392545;36077;36164.27671446609;13653.590392288048;13781.51764688231;13793.310334483236;13796.172229666252;13797.24115173675;10322.071013608662;10396.272260941661;10401.947990260152;10412.219083568763;10422.062014418738;18173.63652726938;18384.26462941494;18420.17109230088;18453.446396608124;18455.354062607745;6644.8671026579195;6733;6792.3886850182735;6859.382655561023;7122.465815063787;811.1482942909921;872.8472517309445;891.6118748754706;909.1226965977863;978.1392374710209;27076.729232707814;27131.457370852473;27291.727082729314;27305.49819759932;28604.284892877844;772.4554189296532;952.7856232347666;972.7859870828406;979.1970584120952;1060.4750648429076;14049.859501405059;14994.325255363536;15106.177124789405;15107;15238.466653667068;375268;377486;379382;379589;379653;48603.756981214865;49049;49090.263646045336;49108.263376049385;49157.508424916006;1102.6581759654373;1132.0660455124555;1163.7439763252241;1188.013948422807;1213.878612138779;27242.5913611292;29237.561436578042;29676.55485167681;29942.700572993992;30083.699163008965;42665;43107.353389699805;43186.78406607947;43187.35218971655;43306.56693433025;287723;288994;289426;290410;291779;24982.62526062074;25583.488330233664;25994.350141245865;26000.47999039971;27376.315592110357;41323.1735365297;41563;41613.37579936243;41832.58167418287;42951.785241073594;35865.28269434649;36004.639953600126;36103.27793444169;36582.26835463329;36687.63312366949;35160.64839351574;35442.468362975094;36107.81946090253;36276;36462.63537364592;34985.475217871244;35085;35166;35353;35415;30930.707505480437;33425.832870836464;33434.66565334413;33522;33523;25251.74748252494;25261.87369063143;25353.87323063373;25889.870550647127;26095.608565871906 16:55:52 INFO - PROCESS | 10595 | |2;jquery.html;327.6395964439119;364.2714570858283;366.104873584087;366.9577998530169;377.9603141670125;12879.871201287984;13113.934430327836;13383.866161338396;16542.255598498068;17453.7381939271;16725;16952.83047169528;16980.830191698078;17104.230309636067;17190.82809171908;5053.938672878699;5202.4537423570355;5242.449542798008;5259.86850328741;5273.841784746444;3731.067233191702;5198.142306519419;5204.115300398922;5225.294585231002;5251.317328747276;4604.079184163164;6125.111858780474;6134.294556126036;6236.563440559163;6249.625022498642;5980.312264089642;6113.633182009094;6230.065490176465;6241.65670888101;6248.875022499525;5390.272313237702;5391.218273350381;5394.487523685244;5395.379531353866;5395.973020134914;18376.448706538824;19118.61762764751;19208.80791192098;20225.696614550794;20604;1781.100544225171;1842.5485755989869;1967.7933816949158;2181.454636340915;2250.1224522436264;9121.179093881583;9256.72229833106;9373.671921482715;9591.712248632552;10842.96991785787;1771.4685594321652;1780.7506949027147;1794.3271273272524;1803.9098045097692;1866.505376075341;67076.6646166771;67582;69362;70431.64784176098;71340.6432967837;9677.854832177523;9694.46680432583;9798.285300072459;9914.851277230848;10012.899871001342;13202.273874936778;13205.669858253621;13209.73580528384;13215.405306761219;13218.272994985367;6725.9663701681175;6858.588484690935;6885.552439091443;7029.402500787388;7046.5067445279365;6484.610923344614;6551.770688025897;6669.133012708414;6681.365270299314;6753.12209412783;4108.979455102765;4112.897177570525;4135.731177473454;4214.431051808028;4226.978865105716;1673.5983363992798;1787.204693911197;1796;1815.9001254931106;1826.9545143808236;675.7364627795164;686.6212882323161;876.8246350729879;910.9225715814098;963.3016063354012;16237;16356.918215408847;17369.47891563255;17556.209970551357;17580.912095439697;835.0980940584154;861.9269010082487;862.2843040276556;866.5623859950684;889.7775556110972;8986.910130898737;9714.22286217101;9729.805403891882;9731.318807683394;9882.752931176778;384053;387911;390902;391525;392725;72894.63552682202;73574.63212683902;73737.6313118431;74392.62803686055;75168;824.3734761581242;827.5779352530133;881.2509367038094;887.4187407248263;889.9644014239358;15161.393544258106;15552.222388880737;15864.92067539655;16008.879378443396;16145.838541614903;37831.81084094562;38391.808040959615;38619.80690096531;38788;39127.608723912395;46864.53135468738;50746.731331717005;52619.21071183859;52633.7368313156;53157;4459.866204013885;5107.846764597068;5873.2658417697785;7140.571565706075;7917.564533950457;9585.808283834423;10747.46262686847;10991.890081099087;11043.889561104608;11887.524499019943;10097.495125243855;10208;10284.897151028694;10520.684379468628;10753.193510486588;9741.415515069119;10356.585736570452;10410.739731506774;10672.89327106719;10673.146148307962;10527.578896844041;10681.092107170667;10769.946150269512;10826.837597436202;10919.672409827717;7321.231270716498;7691.2308769124875;7767.1456139825705;7821.804454888674;7983.800404989922;9435.669751558937;9753.609855605697;9901.306908516335;10045.146162576262;10557.9472102639 16:55:52 INFO - PROCESS | 10595 | |3;mootools.html;1360.4966162519866;1397.6855207578294;1480;1654.2142482320892;1693.0688121533146;7400.444966627504;7968.681252749883;7970.043594768621;7976.481528700645;8008.719694810683;6272.184615999925;6463.385978332063;7416.184219735825;7706.113796913357;7791;2048.446919331783;2077.1898959405844;2077.356019633911;2078.8544801863877;2078.9480262993393;2140.3578926322043;2152.074607918594;2167.7723838996917;2197.450637340665;2199.912003519857;1780.2789870102629;2118.4174352053155;2158.5035441848386;2197.461621902628;2214;2156.212982261479;2184.213683074092;2184.421128400975;2186.158329043314;2189.4526368407896;2071.927482538119;2111.672690732939;2112.0601332407086;2112.757032941216;2113.556153207828;7717;7882.487638303492;7883.921160788376;7906.090799558067;7907.0906845712325;4453.665975051884;4579.702319349231;4591.586757191835;4592.288195329729;4597.103564804865;2711.240852561286;2816.9295767605768;2855.5431131018936;2864.8281103133877;2867;842.4061036968923;861.3281640320497;864.4035615425399;879.4283715584857;881.2069137775989;9785.510724463747;9846.113849753556;9866.950665246628;10031.799364012679;10039.949800251024;3917.353636649978;4044.2922488564973;4154.356074808409;4157.521884983205;4163.10493243954;8823.205911467998;8852.513111778913;8863.867041994376;8867;8884.422512536665;4581.266997280486;4601.907961840744;4608.239640459354;4608.28571571407;4614.284785858197;4100.958990410118;4189.350650649093;4195.580441955841;4196.958030419718;4197.349410841324;3593.8742144025355;3672.7429079964145;3755.023693839567;3761.363806744075;3876.1860009398342;1142.257532603814;1169.4912712969885;1180.3744015671707;1184.644606618011;1186.7448498572849;718.4324383736789;822.5106061893164;832.2052439919862;840.8108175660427;842.439777547936;4976.0794253063295;5081.339425874687;5164.741762911839;5223.242629818655;5231.843044708665;378.53062202868796;397.99903243343016;401.19560281635137;402.981865816039;407.6473850119684;3574.12433953683;3589.3898037333715;3619.9819000905354;3652.3608368535934;3998.6001399859783;9534.809303813885;9675.225981921429;9738.367006144577;9742.805143897223;9804.803903922024;9718.611255549698;9726.854097188407;9730.902690973;9733.270004749673;9740.220782337396;6919.792406227822;7337.926620733831;7563.130240022358;7572.545647261182;7576.469647124648;5314.22943673173;5342.786288548415;5376.408595054462;5401.054815407367;5674.517665998436;8450.19723126302;8587.570621469027;8709;8861.069587693457;8950.552472376485;8708.085651006806;8742.082081381363;8801.427907186013;9101.317401194805;9141.3601047926;6635.270120286668;6754.425873800782;6754.986751987241;6771.153605799275;6773.390394864486;7439.70241190368;7447.292507211806;7489.850202996018;7494.100707915085;7499.062617172854;6772.762953296611;6774.559653622498;6794.8641027180165;6939.791806245821;6967.163940327193;6652.900206497004;6718.0594716738715;6804.353586409283;6825.35159159879;6849.828754281183;6313.494920406449;6406.135171751754;6406.199225096863;6413.71138298787;6417;4381.846635367747;4393.868183954359;4468.932966005447;4611.308303754464;4647.604953578983;4528.184926713091;4563.977180114078;4605.861824145281;4653.348531205566;4789.209780386197 16:55:52 INFO - PROCESS | 10595 | |4;prototype.html;297.81535448022225;301.4152544064515;307.1522597630539;309.5588785979981;309.8388837804342;4961.553460188582;4982.683052380647;4989.850304490862;4994.97502512487;4995.400551933764;4876.390451193601;4900.436449808273;4928.5564299213065;4928.581070608993;4945.826896058638;3873.0898238913833;3896.961030389695;3900.356441187215;3925.8037098145123;3935.9803200983956;3790.3366910790637;3881.825317860703;3905.4141878718137;3933.9606603934026;3946.743461674982;3840.481534992782;3843.6925045996254;3863.3818589025764;3873.767573945558;3881;3817.1983883384246;3834.118152824838;3860.4595356650093;3861.1698484825615;3871.644924276509;3232.7413806895393;3308.669133086696;3350.542514006415;3381.830908454592;3460.1176699941607;4702.166155199475;4716.915109524829;4722.551357621055;4755.42934847817;4758.476567577563;4364.581511008935;4364.716293440916;4384.627306678936;4395.978020109911;4400.757958312323;4720.645951553647;4833.984863178736;5104.234364845266;5104.9234261485735;5108.846734597968;3675.7059435245114;3687.465317528943;3694.224212915291;3704.833282502294;3721.5906250312446;5330.413654498002;5330.520253177194;5344.706041167734;5359.946400536022;5384.3538775346815;3522.18989632386;3696.6488183622514;3813.275477659236;3818.3508803503473;3822.6559609635265;3736.215394767075;3769.1896242308035;3782.6973842092566;3787.450819631138;3791.469194312798;4347.239233134252;4348.34774783785;4356.237658409766;4379.474463064453;4388.605025547716;5162.45794191612;5162.8967420652125;5185.2999845021195;5191.247269146028;5211;1243.3161761031397;1299.0257307019735;1313.0611612696966;1318.182726709427;1321.2799024531653;824.8185399212164;831.409699113624;840.6469282901196;844.957752112392;856.3491746272753;4034.2738307105;4047.7571345719352;4050.675945924319;4061.7969101545395;4085.59144085595;4639.628829693616;4667.253239481734;4667.879708869915;4668.649851261169;4669.95330046702;4009.8797036088963;4016.859409920639;4028.8388464461677;4029.5970402960056;4039.2729308724124;2322.7573248312074;2473.0602371098867;2473.4682043360403;2474.91337803176;2475.9009639614574;4430.13612345596;4703.623710103184;4814.7833347499445;4947.579455746302;4966.751662416937;5381.919271210935;5383.757730902119;5383.946160538423;5404.864878377994;5464.562834973192;5007.048660754591;5011.899762004812;5025.773840177201;5027.371578552681;5034.672746271481;5114.616403769658;5272.55183309423;5295.576353891603;5300.681959082467;5303.7878484860175;5233.293505376878;5242.370915490165;5248.527632513092;5263.6841789492755;5297.735113244399;4783.7129772213775;5006.5994720423005;5023.2716256143385;5029.497050295087;5063.620228482805;3698.944515832211;3707.6281775743146;3718.1076541629272;3720.4605332227216;3726.6087060859304;5301.522862942354;5314.28257185275;5320.361556613077;5345.251664767014;5360.892782144413;5082.33929589151;5195.9740201298755;5200.6619569727845;5205.271262023395;5246.868828279324;5140.64015518925;5142.382914050338;5144.6398752087;5158.535731784157;5173.81891633791;5058.241263810458;5059.5952323814745;5071.264666623393;5080.847574572768;5113.590912727047;4963.429205641253;5029.27075574047;5163.380394352702;5175.1019429119815;5189.896202075862;5010.198368261061;5013.423456302426;5037.294778731054;5038.546530812245;5087.923681144712 16:55:52 INFO - PROCESS | 10595 | |5;yui.html;540.5567434703545;566.4081035318089;592.6947621974682;593.382881802925;601.6330038676407;12578.937105314471;13272.469101235938;13287.136336138145;13291.468341266338;13294;13478.93260533696;14207.360668769905;14401.567952961403;14890.702185956274;14925.328360223788;776.7941495503682;792.7859477940953;793.5199204481293;794.3049831397527;794.400227827989;632.9620222786624;753.3521171792254;777.4169372970272;777.4441274488739;778.7352300217925;811.5130921447144;813.536284317939;814.1085511365046;814.2102160903943;814.450246083891;808.59974312715;809.134226377776;809.1948511231333;809.5345176523493;809.5668817182808;490.5633985752683;501.04550830667637;501.48597687370375;501.8745313671582;581.1138014527836;11843.46704398304;12288.262704237774;12291.323977181251;12295.877041229563;12317.56888508907;10644.57421703131;10819.83770243447;11455.427228638535;11911.404429778562;12462;10087.89912100877;10159.593616255415;10201.285909986304;10206.795864082676;10516.421596812172;3176.7299779518767;3203.5675183850117;3237;3258.234314935988;3296.620888597818;66972;67261;68106;68652;69168.6541567294;9111.453312801254;9118.270538356915;9173.312001599774;9397.436153830791;9436.622535098657;17738.201780919888;17898.5525361867;17919.910400447916;18008.549786255448;18137;6824.146981627297;6851.3491218334175;6908.620025898524;7084.114485689289;7114.39527640146;3802.8288727007352;3829.617038296148;3830.3871380578976;3886.6696330811633;3903.0437542802197;2666.9333266668486;2775.2229375774814;2776.958345624777;2782.7773778097703;2783.819051761629;479.89922116355257;482.17788670317026;482.9975850120727;485.53631282125485;498.72071639881057;449.0256144167165;525.6110478246147;532.5455479587349;551.9282493275848;629.6064959400375;7882.369410447151;8006.239407256301;8029.598520073973;8066.556339401389;8159.67361305553;471.3660127129006;508.7303729023623;518.7639623971085;534.8072010099698;541.1260813785786;3758.1356288053903;3837.3092843288478;3942.980285098556;4051.108756073659;4057.655099316532;57304.713476433186;72164;99231.50384248032;106264;107232.4638376803;65345;91123;91223.54388228017;91772;95773;80294;82760.17239827645;83203.26365731632;84282.5785871079;84809.57595211985;7369.189389167294;7396.96301518489;7763.5730034846365;7905.446618736633;7935.523868567904;46632.30051549162;47568.097276108565;47668.76165619289;48089.27866082082;48894.26658600195;68232.6588367055;70067;70211.64894175496;71527.64236178786;72246.27753722605;9160.633574657206;9322.254219662547;9361.063893610857;10206.948965255126;10472.581096756045;11304.208705390543;11511.30932144074;11700.06399488056;11870.465829037887;12805.8079128815;11577.942110289394;11688;11779.587714430296;12149.453274602487;12263.69340766488;7559.470837041355;10457.320274182153;11086.223964322742;11558.595449159238;11632.709182270512;11147.888521114684;11494.252873563191;11545.13411494158;11638.53445862156;11646.534138634359;9313.674021409219;9423.38747981379;9516.95241523788;9576.712698619052;9594.952025239829;8444.197801208875;8937.106289371011;9003.279737621106;9055.538374053534;9103.90896091057 16:55:52 INFO - PROCESS | 10595 | __end_tp_report 16:55:52 INFO - PROCESS | 10595 | __start_cc_report 16:55:52 INFO - PROCESS | 10595 | _x_x_mozilla_cycle_collect,1150 16:55:52 INFO - PROCESS | 10595 | __end_cc_report 16:55:52 INFO - PROCESS | 10595 | __startTimestamp1470614152325__endTimestamp 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | ------- Summary: start ------- 16:55:52 INFO - PROCESS | 10595 | Number of tests: 6 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#0] dojo.html Cycles:165 Average:4753.36 Median:4898.31 stddev:504.65 (10.3%) stddev-sans-first:478.53 16:55:52 INFO - PROCESS | 10595 | Values: 2652.4 2653.7 2688.6 2689.5 2689.5 4544.1 4607.5 4750.4 4893.1 4900.0 4626.1 4690.1 4732.1 4784.4 4820.9 4420.3 4425.0 4458.4 4459.0 4499.5 4467.3 4515.4 4535.6 4544.1 4606.8 4568.1 4571.7 4590.4 4600.5 4618.5 4181.9 4248.2 4488.3 4492.8 4531.8 4221.8 4307.5 4311.9 4342.2 4352.0 4756.3 4758.0 4764.1 4774.3 4789.3 5097.2 5099.5 5139.0 5150.8 5162.6 4868.7 5104.1 5178.5 5188.0 5200.4 4794.7 4814.4 4842.6 4878.0 4884.6 4984.5 5359.3 5369.6 5369.8 5371.9 5004.0 5041.7 5067.5 5127.4 5144.7 5176.7 5183.4 5184.7 5224.2 5227.4 4869.8 5121.9 5134.6 5135.9 5184.7 4943.2 4972.7 4975.6 4977.0 4983.7 4250.1 4284.2 4290.2 4291.6 4293.7 3776.8 3796.8 3800.8 3801.8 3810.4 4424.5 4431.2 4470.6 4477.8 4481.5 5156.7 5191.7 5206.1 5211.9 5256.8 4188.6 4386.1 4421.2 4428.5 4477.8 4696.9 4714.4 4719.6 4759.8 4760.6 4900.0 4903.9 4909.3 4911.0 4963.0 4898.3 4947.4 5225.8 5247.1 5290.7 4736.9 4793.4 4816.2 4819.1 4848.0 5053.7 5058.4 5059.3 5067.4 5111.8 4904.6 5105.8 5121.5 5125.8 5128.1 4993.1 5049.3 5059.0 5075.1 5134.4 5060.9 5063.3 5064.0 5064.6 5112.2 4671.3 4989.7 5009.8 5050.5 5067.4 4990.0 5046.8 5061.7 5084.4 5135.6 4944.5 4959.9 4964.3 4966.1 5017.8 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#1] ext.html Cycles:180 Average:45375.55 Median:36105.55 stddev:80635.92 (223.3%) stddev-sans-first:80791.97 16:55:52 INFO - PROCESS | 10595 | Values: 574.0 584.5 586.7 589.3 594.9 28352.9 37999.8 39242.4 39286.0 44091.0 39814.4 40251.6 40312.0 40340.6 40350.0 7750.2 7892.9 7894.5 7895.0 7906.1 7799.9 7871.0 7881.9 7884.0 7903.2 7873.5 7905.1 7925.8 7926.2 7933.3 7843.9 7875.7 7887.6 7889.8 7894.2 7785.6 7842.1 7842.2 7844.8 7847.6 38590.4 38895.4 38932.8 38960.2 38978.4 3037.2 3094.7 3105.0 3189.6 3326.9 29355.4 29486.0 29553.7 29771.6 31062.5 3391.6 3482.2 3486.9 3521.4 3608.0 234418.0 234621.0 234895.0 235704.0 236467.0 52448.7 52664.7 52687.2 52717.0 52757.7 32879.7 33894.5 33923.7 36077.0 36164.3 13653.6 13781.5 13793.3 13796.2 13797.2 10322.1 10396.3 10401.9 10412.2 10422.1 18173.6 18384.3 18420.2 18453.4 18455.4 6644.9 6733.0 6792.4 6859.4 7122.5 811.1 872.8 891.6 909.1 978.1 27076.7 27131.5 27291.7 27305.5 28604.3 772.5 952.8 972.8 979.2 1060.5 14049.9 14994.3 15106.2 15107.0 15238.5 375268.0 377486.0 379382.0 379589.0 379653.0 48603.8 49049.0 49090.3 49108.3 49157.5 1102.7 1132.1 1163.7 1188.0 1213.9 27242.6 29237.6 29676.6 29942.7 30083.7 42665.0 43107.4 43186.8 43187.4 43306.6 287723.0 288994.0 289426.0 290410.0 291779.0 24982.6 25583.5 25994.4 26000.5 27376.3 41323.2 41563.0 41613.4 41832.6 42951.8 35865.3 36004.6 36103.3 36582.3 36687.6 35160.6 35442.5 36107.8 36276.0 36462.6 34985.5 35085.0 35166.0 35353.0 35415.0 30930.7 33425.8 33434.7 33522.0 33523.0 25251.7 25261.9 25353.9 25889.9 26095.6 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#2] jquery.html Cycles:180 Average:24120.85 Median:25959.24 stddev:64272.75 (247.6%) stddev-sans-first:64428.23 16:55:52 INFO - PROCESS | 10595 | Values: 327.6 364.3 366.1 367.0 378.0 12879.9 13113.9 13383.9 16542.3 17453.7 16725.0 16952.8 16980.8 17104.2 17190.8 5053.9 5202.5 5242.4 5259.9 5273.8 3731.1 5198.1 5204.1 5225.3 5251.3 4604.1 6125.1 6134.3 6236.6 6249.6 5980.3 6113.6 6230.1 6241.7 6248.9 5390.3 5391.2 5394.5 5395.4 5396.0 18376.4 19118.6 19208.8 20225.7 20604.0 1781.1 1842.5 1967.8 2181.5 2250.1 9121.2 9256.7 9373.7 9591.7 10843.0 1771.5 1780.8 1794.3 1803.9 1866.5 67076.7 67582.0 69362.0 70431.6 71340.6 9677.9 9694.5 9798.3 9914.9 10012.9 13202.3 13205.7 13209.7 13215.4 13218.3 6726.0 6858.6 6885.6 7029.4 7046.5 6484.6 6551.8 6669.1 6681.4 6753.1 4109.0 4112.9 4135.7 4214.4 4227.0 1673.6 1787.2 1796.0 1815.9 1827.0 675.7 686.6 876.8 910.9 963.3 16237.0 16356.9 17369.5 17556.2 17580.9 835.1 861.9 862.3 866.6 889.8 8986.9 9714.2 9729.8 9731.3 9882.8 384053.0 387911.0 390902.0 391525.0 392725.0 72894.6 73574.6 73737.6 74392.6 75168.0 824.4 827.6 881.3 887.4 890.0 15161.4 15552.2 15864.9 16008.9 16145.8 37831.8 38391.8 38619.8 38788.0 39127.6 46864.5 50746.7 52619.2 52633.7 53157.0 4459.9 5107.8 5873.3 7140.6 7917.6 9585.8 10747.5 10991.9 11043.9 11887.5 10097.5 10208.0 10284.9 10520.7 10753.2 9741.4 10356.6 10410.7 10672.9 10673.1 10527.6 10681.1 10769.9 10826.8 10919.7 7321.2 7691.2 7767.1 7821.8 7983.8 9435.7 9753.6 9901.3 10045.1 10557.9 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#3] mootools.html Cycles:180 Average:5084.06 Median:5227.54 stddev:2854.68 (54.6%) stddev-sans-first:2848.98 16:55:52 INFO - PROCESS | 10595 | Values: 1360.5 1397.7 1480.0 1654.2 1693.1 7400.4 7968.7 7970.0 7976.5 8008.7 6272.2 6463.4 7416.2 7706.1 7791.0 2048.4 2077.2 2077.4 2078.9 2078.9 2140.4 2152.1 2167.8 2197.5 2199.9 1780.3 2118.4 2158.5 2197.5 2214.0 2156.2 2184.2 2184.4 2186.2 2189.5 2071.9 2111.7 2112.1 2112.8 2113.6 7717.0 7882.5 7883.9 7906.1 7907.1 4453.7 4579.7 4591.6 4592.3 4597.1 2711.2 2816.9 2855.5 2864.8 2867.0 842.4 861.3 864.4 879.4 881.2 9785.5 9846.1 9867.0 10031.8 10039.9 3917.4 4044.3 4154.4 4157.5 4163.1 8823.2 8852.5 8863.9 8867.0 8884.4 4581.3 4601.9 4608.2 4608.3 4614.3 4101.0 4189.4 4195.6 4197.0 4197.3 3593.9 3672.7 3755.0 3761.4 3876.2 1142.3 1169.5 1180.4 1184.6 1186.7 718.4 822.5 832.2 840.8 842.4 4976.1 5081.3 5164.7 5223.2 5231.8 378.5 398.0 401.2 403.0 407.6 3574.1 3589.4 3620.0 3652.4 3998.6 9534.8 9675.2 9738.4 9742.8 9804.8 9718.6 9726.9 9730.9 9733.3 9740.2 6919.8 7337.9 7563.1 7572.5 7576.5 5314.2 5342.8 5376.4 5401.1 5674.5 8450.2 8587.6 8709.0 8861.1 8950.6 8708.1 8742.1 8801.4 9101.3 9141.4 6635.3 6754.4 6755.0 6771.2 6773.4 7439.7 7447.3 7489.9 7494.1 7499.1 6772.8 6774.6 6794.9 6939.8 6967.2 6652.9 6718.1 6804.4 6825.4 6849.8 6313.5 6406.1 6406.2 6413.7 6417.0 4381.8 4393.9 4468.9 4611.3 4647.6 4528.2 4564.0 4605.9 4653.3 4789.2 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#4] prototype.html Cycles:180 Average:4218.57 Median:4738.99 stddev:1244.81 (26.3%) stddev-sans-first:1213.02 16:55:52 INFO - PROCESS | 10595 | Values: 297.8 301.4 307.2 309.6 309.8 4961.6 4982.7 4989.9 4995.0 4995.4 4876.4 4900.4 4928.6 4928.6 4945.8 3873.1 3897.0 3900.4 3925.8 3936.0 3790.3 3881.8 3905.4 3934.0 3946.7 3840.5 3843.7 3863.4 3873.8 3881.0 3817.2 3834.1 3860.5 3861.2 3871.6 3232.7 3308.7 3350.5 3381.8 3460.1 4702.2 4716.9 4722.6 4755.4 4758.5 4364.6 4364.7 4384.6 4396.0 4400.8 4720.6 4834.0 5104.2 5104.9 5108.8 3675.7 3687.5 3694.2 3704.8 3721.6 5330.4 5330.5 5344.7 5359.9 5384.4 3522.2 3696.6 3813.3 3818.4 3822.7 3736.2 3769.2 3782.7 3787.5 3791.5 4347.2 4348.3 4356.2 4379.5 4388.6 5162.5 5162.9 5185.3 5191.2 5211.0 1243.3 1299.0 1313.1 1318.2 1321.3 824.8 831.4 840.6 845.0 856.3 4034.3 4047.8 4050.7 4061.8 4085.6 4639.6 4667.3 4667.9 4668.6 4670.0 4009.9 4016.9 4028.8 4029.6 4039.3 2322.8 2473.1 2473.5 2474.9 2475.9 4430.1 4703.6 4814.8 4947.6 4966.8 5381.9 5383.8 5383.9 5404.9 5464.6 5007.0 5011.9 5025.8 5027.4 5034.7 5114.6 5272.6 5295.6 5300.7 5303.8 5233.3 5242.4 5248.5 5263.7 5297.7 4783.7 5006.6 5023.3 5029.5 5063.6 3698.9 3707.6 3718.1 3720.5 3726.6 5301.5 5314.3 5320.4 5345.3 5360.9 5082.3 5196.0 5200.7 5205.3 5246.9 5140.6 5142.4 5144.6 5158.5 5173.8 5058.2 5059.6 5071.3 5080.8 5113.6 4963.4 5029.3 5163.4 5175.1 5189.9 5010.2 5013.4 5037.3 5038.5 5087.9 16:55:52 INFO - PROCESS | 10595 | 16:55:52 INFO - PROCESS | 10595 | [#5] yui.html Cycles:180 Average:18039.80 Median:522.19 stddev:26518.41 (5078.3%) stddev-sans-first:26560.25 16:55:52 INFO - PROCESS | 10595 | Values: 540.6 566.4 592.7 593.4 601.6 12578.9 13272.5 13287.1 13291.5 13294.0 13478.9 14207.4 14401.6 14890.7 14925.3 776.8 792.8 793.5 794.3 794.4 633.0 753.4 777.4 777.4 778.7 811.5 813.5 814.1 814.2 814.5 808.6 809.1 809.2 809.5 809.6 490.6 501.0 501.5 501.9 581.1 11843.5 12288.3 12291.3 12295.9 12317.6 10644.6 10819.8 11455.4 11911.4 12462.0 10087.9 10159.6 10201.3 10206.8 10516.4 3176.7 3203.6 3237.0 3258.2 3296.6 66972.0 67261.0 68106.0 68652.0 69168.7 9111.5 9118.3 9173.3 9397.4 9436.6 17738.2 17898.6 17919.9 18008.5 18137.0 6824.1 6851.3 6908.6 7084.1 7114.4 3802.8 3829.6 3830.4 3886.7 3903.0 2666.9 2775.2 2777.0 2782.8 2783.8 479.9 482.2 483.0 485.5 498.7 449.0 525.6 532.5 551.9 629.6 7882.4 8006.2 8029.6 8066.6 8159.7 471.4 508.7 518.8 534.8 541.1 3758.1 3837.3 3943.0 4051.1 4057.7 57304.7 72164.0 99231.5 106264.0 107232.5 65345.0 91123.0 91223.5 91772.0 95773.0 80294.0 82760.2 83203.3 84282.6 84809.6 7369.2 7397.0 7763.6 7905.4 7935.5 46632.3 47568.1 47668.8 48089.3 48894.3 68232.7 70067.0 70211.6 71527.6 72246.3 9160.6 9322.3 9361.1 10206.9 10472.6 11304.2 11511.3 11700.1 11870.5 12805.8 11577.9 11688.0 11779.6 12149.5 12263.7 7559.5 10457.3 11086.2 11558.6 11632.7 11147.9 11494.3 11545.1 11638.5 11646.5 9313.7 9423.4 9517.0 9576.7 9595.0 8444.2 8937.1 9003.3 9055.5 9103.9 16:55:52 INFO - PROCESS | 10595 | -------- Summary: end -------- 16:55:52 INFO - PROCESS | 10595 | 16:55:53 INFO - TEST-INFO | 10595: exit 0 16:55:53 INFO - TEST-OK | dromaeo_css | took 1119603ms 16:55:53 INFO - TEST-START | kraken 16:55:53 INFO - operating with platform_type : linux_ 16:55:53 INFO - Initialising browser for kraken test... 16:55:53 INFO - TEST-INFO | started process 10780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGugFTT/profile http://localhost:38337/getInfo.html) 16:55:57 INFO - PROCESS | 10780 | __metrics Screen width/height:1600/1200 16:55:57 INFO - PROCESS | 10780 | colorDepth:24 16:55:57 INFO - PROCESS | 10780 | Browser inner width/height: 1024/697 16:55:57 INFO - PROCESS | 10780 | __metrics 16:55:58 INFO - PROCESS | 10780 | 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" 16:55:58 INFO - PROCESS | 10780 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:55:59 INFO - TEST-INFO | 10780: exit 0 16:55:59 INFO - Browser initialized. 16:55:59 INFO - Running cycle 1/1 for kraken test... 16:55:59 INFO - TEST-INFO | started process 10860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGugFTT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:11 INFO - PROCESS | 10860 | RSS: Main: 157315072 16:56:11 INFO - PROCESS | 10860 | 16:56:13 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38337/tests/kraken/driver.html?testName=audio-beat-detection) 16:56:14 INFO - PROCESS | 10860 | RSS: Main: 213159936 16:56:14 INFO - PROCESS | 10860 | 16:56:17 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38337/tests/kraken/driver.html?testName=audio-dft) 16:56:18 INFO - PROCESS | 10860 | RSS: Main: 191496192 16:56:18 INFO - PROCESS | 10860 | 16:56:21 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38337/tests/kraken/driver.html?testName=audio-fft) 16:56:21 INFO - PROCESS | 10860 | RSS: Main: 270958592 16:56:21 INFO - PROCESS | 10860 | 16:56:24 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38337/tests/kraken/driver.html?testName=audio-oscillator) 16:56:24 INFO - PROCESS | 10860 | RSS: Main: 180031488 16:56:24 INFO - PROCESS | 10860 | 16:56:26 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38337/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:56:27 INFO - PROCESS | 10860 | RSS: Main: 184823808 16:56:27 INFO - PROCESS | 10860 | 16:56:30 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38337/tests/kraken/driver.html?testName=imaging-darkroom) 16:56:31 INFO - PROCESS | 10860 | RSS: Main: 221143040 16:56:31 INFO - PROCESS | 10860 | 16:56:34 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38337/tests/kraken/driver.html?testName=imaging-desaturate) 16:56:35 INFO - PROCESS | 10860 | RSS: Main: 214933504 16:56:35 INFO - PROCESS | 10860 | 16:56:38 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38337/tests/kraken/driver.html?testName=json-parse-financial) 16:56:38 INFO - PROCESS | 10860 | RSS: Main: 218603520 16:56:38 INFO - PROCESS | 10860 | 16:56:40 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38337/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:56:41 INFO - PROCESS | 10860 | RSS: Main: 184266752 16:56:41 INFO - PROCESS | 10860 | 16:56:43 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:56:43 INFO - PROCESS | 10860 | RSS: Main: 189444096 16:56:43 INFO - PROCESS | 10860 | 16:56:45 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:56:46 INFO - PROCESS | 10860 | RSS: Main: 194146304 16:56:46 INFO - PROCESS | 10860 | 16:56:49 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:56:50 INFO - PROCESS | 10860 | RSS: Main: 195674112 16:56:50 INFO - PROCESS | 10860 | 16:56:53 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:56:53 INFO - PROCESS | 10860 | RSS: Main: 196501504 16:56:53 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:38337/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38337/tests/kraken/driver.html?testName=ai-astar) 16:56:55 INFO - PROCESS | 10860 | RSS: Main: 244088832 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | __start_tp_report 16:56:55 INFO - PROCESS | 10860 | _x_x_mozilla_page_load 16:56:55 INFO - PROCESS | 10860 | _x_x_mozilla_page_load_details 16:56:55 INFO - PROCESS | 10860 | |i|pagename|runs| 16:56:55 INFO - PROCESS | 10860 | |0;ai-astar;103;114;114;113;114;115;113;113;115;114 16:56:55 INFO - PROCESS | 10860 | |1;audio-beat-detection;153;123;124;172;121;128;144;121;166;122 16:56:55 INFO - PROCESS | 10860 | |2;audio-dft;162;161;162;161;162;162;163;161;162;160 16:56:55 INFO - PROCESS | 10860 | |3;audio-fft;75;76;74;110;76;76;74;74;103;75 16:56:55 INFO - PROCESS | 10860 | |4;audio-oscillator;85;86;128;85;85;85;133;84;85;134 16:56:55 INFO - PROCESS | 10860 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;117 16:56:55 INFO - PROCESS | 10860 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;146;146 16:56:55 INFO - PROCESS | 10860 | |7;imaging-desaturate;94;94;94;95;95;95;96;96;95;96 16:56:55 INFO - PROCESS | 10860 | |8;json-parse-financial;64;63;63;63;63;65;63;62;63;62 16:56:55 INFO - PROCESS | 10860 | |9;json-stringify-tinderbox;66;64;64;65;65;65;64;66;65;65 16:56:55 INFO - PROCESS | 10860 | |10;stanford-crypto-aes;89;82;86;79;80;85;81;88;79;82 16:56:55 INFO - PROCESS | 10860 | |11;stanford-crypto-ccm;142;138;140;144;137;140;131;136;137;143 16:56:55 INFO - PROCESS | 10860 | |12;stanford-crypto-pbkdf2;163;164;163;169;163;165;168;163;164;162 16:56:55 INFO - PROCESS | 10860 | |13;stanford-crypto-sha256-iterative;68;66;67;67;67;67;67;73;66;67 16:56:55 INFO - PROCESS | 10860 | __end_tp_report 16:56:55 INFO - PROCESS | 10860 | __start_cc_report 16:56:55 INFO - PROCESS | 10860 | _x_x_mozilla_cycle_collect,1972 16:56:55 INFO - PROCESS | 10860 | __end_cc_report 16:56:55 INFO - PROCESS | 10860 | __startTimestamp1470614215628__endTimestamp 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | ------- Summary: start ------- 16:56:55 INFO - PROCESS | 10860 | Number of tests: 14 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.52 (3.1%) stddev-sans-first:0.78 16:56:55 INFO - PROCESS | 10860 | Values: 103.0 114.0 114.0 113.0 114.0 115.0 113.0 113.0 115.0 114.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:136.00 stddev:19.87 (14.6%) stddev-sans-first:20.25 16:56:55 INFO - PROCESS | 10860 | Values: 153.0 123.0 124.0 172.0 121.0 128.0 144.0 121.0 166.0 122.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 16:56:55 INFO - PROCESS | 10860 | Values: 162.0 161.0 162.0 161.0 162.0 162.0 163.0 161.0 162.0 160.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.00 stddev:13.41 (17.9%) stddev-sans-first:14.03 16:56:55 INFO - PROCESS | 10860 | Values: 75.0 76.0 74.0 110.0 76.0 76.0 74.0 74.0 103.0 75.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#4] audio-oscillator Cycles:10 Average:99.00 Median:85.00 stddev:22.60 (26.6%) stddev-sans-first:23.39 16:56:55 INFO - PROCESS | 10860 | Values: 85.0 86.0 128.0 85.0 85.0 85.0 133.0 84.0 85.0 134.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:56:55 INFO - PROCESS | 10860 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 16:56:55 INFO - PROCESS | 10860 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 16:56:55 INFO - PROCESS | 10860 | Values: 94.0 94.0 94.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.88 (1.4%) stddev-sans-first:0.87 16:56:55 INFO - PROCESS | 10860 | Values: 64.0 63.0 63.0 63.0 63.0 65.0 63.0 62.0 63.0 62.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 16:56:55 INFO - PROCESS | 10860 | Values: 66.0 64.0 64.0 65.0 65.0 65.0 64.0 66.0 65.0 65.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:3.67 (4.4%) stddev-sans-first:3.21 16:56:55 INFO - PROCESS | 10860 | Values: 89.0 82.0 86.0 79.0 80.0 85.0 81.0 88.0 79.0 82.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:140.00 stddev:3.85 (2.8%) stddev-sans-first:3.91 16:56:55 INFO - PROCESS | 10860 | Values: 142.0 138.0 140.0 144.0 137.0 140.0 131.0 136.0 137.0 143.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:164.00 stddev:2.32 (1.4%) stddev-sans-first:2.40 16:56:55 INFO - PROCESS | 10860 | Values: 163.0 164.0 163.0 169.0 163.0 165.0 168.0 163.0 164.0 162.0 16:56:55 INFO - PROCESS | 10860 | 16:56:55 INFO - PROCESS | 10860 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:2.01 (3.0%) stddev-sans-first:2.13 16:56:55 INFO - PROCESS | 10860 | Values: 68.0 66.0 67.0 67.0 67.0 67.0 67.0 73.0 66.0 67.0 16:56:55 INFO - PROCESS | 10860 | -------- Summary: end -------- 16:56:55 INFO - PROCESS | 10860 | 16:56:56 INFO - TEST-INFO | 10860: exit 0 16:56:56 INFO - TEST-OK | kraken | took 63548ms 16:56:56 INFO - SUITE-END | took 1183s 16:56:56 INFO - Completed test suite (00:19:43) 16:56:56 INFO - javascript benchmark 16:56:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2652.3634327761333, 2653.7478939500766, 2688.6370340004105, 2689.4621075784835, 2689.5293323668366, 4544.136614043329, 4607.539246075391, 4750.358701575286, 4893.14369985253, 4899.995500922316, 4626.144163329778, 4690.108879312937, 4732.1482133216, 4784.354112194853, 4820.855374338776, 4420.292753159495, 4424.97787511062, 4458.353538736881, 4458.97770511147, 4499.46006479223, 4467.285234362503, 4515.367848501212, 4535.614472769802, 4544.136614043321, 4606.838760643378, 4568.0635469728795, 4571.748553829538, 4590.426196725392, 4600.493945665974, 4618.49196588375, 4181.874543763662, 4248.214080395137, 4488.259437192859, 4492.797824097891, 4531.796069176895, 4221.831126754926, 4307.547707490699, 4311.892202694927, 4342.240107981091, 4352.042550638855, 4756.262779269219, 4758.0, 4764.071006153801, 4774.28385742138, 4789.3294938708605, 5097.23541468779, 5099.5410413063, 5138.97430512845, 5150.768215430315, 5162.561182299509, 4868.659193856432, 5104.132297509433, 5178.533931946105, 5188.0, 5200.427952925175, 4794.736289504111, 4814.374131362935, 4842.6368022398465, 4877.975610121926, 4884.5603895649565, 4984.501549844987, 5359.276497672842, 5369.624126311199, 5369.7583608737705, 5371.865703357447, 5004.0, 5041.6722913010535, 5067.467915868854, 5127.41034781005, 5144.742762861917, 5176.689398636094, 5183.429822719498, 5184.7407629618365, 5224.164133738584, 5227.424983251839, 4869.805207791719, 5121.8719532012, 5134.589232861362, 5135.87160320995, 5184.7407629618365, 4943.1596628573225, 4972.676776009548, 4975.626827987915, 4977.0, 4983.651144419856, 4250.149970006011, 4284.1645879053285, 4290.184864875664, 4291.570842915746, 4293.720908140961, 3776.792276424823, 3796.8481260749813, 3800.847966081381, 3801.752886062397, 3810.3903375459795, 4424.513303536608, 4431.224535706238, 4470.575295346936, 4477.820887164542, 4481.529439408815, 5156.664816786897, 5191.6884986900905, 5206.140986737221, 5211.921821172686, 5256.763445644955, 4188.602082802129, 4386.122775444924, 4421.2262854001065, 4428.535003824616, 4477.7984990675495, 4696.9060618787435, 4714.434267887876, 4719.622430205575, 4759.762011899391, 4760.59534939534, 4900.0, 4903.877403064952, 4909.288153217834, 4910.968696573759, 4962.950370496321, 4898.3142360068905, 4947.406311242674, 5225.790968361375, 5247.107991641354, 5290.65610735301, 4736.928946065743, 4793.376861008047, 4816.229403295456, 4819.060283244734, 4848.0, 5053.7220452875445, 5058.3929928408825, 5059.342285502861, 5067.391912970467, 5111.821086261963, 4904.607631389409, 5105.770240339268, 5121.539061484485, 5125.846224613268, 5128.12821820284, 4993.076280888047, 5049.343585333883, 5058.974705126598, 5075.061113693979, 5134.409542902614, 5060.949390506195, 5063.265826455217, 5064.0, 5064.620153488429, 5112.233165025127, 4671.322658214608, 4989.650724449399, 5009.849704508871, 5050.545450909436, 5067.41724701664, 4990.0, 5046.84859454217, 5061.721605311744, 5084.3898732152375, 5135.614828887923, 4944.505549445027, 4959.851204463872, 4964.3298154748645, 4966.106100901872, 5017.799288028438], "value": 4721.057260079603, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.9684317362546, 584.5265335078586, 586.6978506069372, 589.3193361667273, 594.9196858424115, 28352.85823570882, 37999.81000094999, 39242.41136382957, 39285.99999999996, 44091.0, 39814.40278395826, 40251.59748402515, 40312.0, 40340.596594034054, 40350.0, 7750.224977502247, 7892.881606775903, 7894.526328420313, 7894.960525197366, 7906.130325664172, 7799.883001754978, 7870.976773019528, 7881.881771773399, 7884.0, 7903.1701671324545, 7873.488223265469, 7905.0513938327485, 7925.801854953615, 7926.167752385974, 7933.286004259644, 7843.921560784376, 7875.684972601089, 7887.605619718991, 7889.763307100796, 7894.171112033211, 7785.649645765954, 7842.058952925686, 7842.176571460029, 7844.764657060297, 7847.607619619053, 38590.42114368287, 38895.41656875149, 38932.80533597342, 38960.22079558393, 38978.41532377017, 3037.179961410407, 3094.7214750672547, 3104.984475077633, 3189.6331921829055, 3326.933461330784, 29355.41289174226, 29486.0, 29553.70446295531, 29771.553426698618, 31062.53406198909, 3391.609964854024, 3482.181687303482, 3486.9476957845654, 3521.4013617685055, 3608.0438683748566, 234418.0, 234621.0, 234895.0, 235704.0, 236467.0, 52448.73775631174, 52664.73667631637, 52687.20969185465, 52717.0, 52757.73621131947, 32879.67120328814, 33894.49158262628, 33923.660763392545, 36077.0, 36164.27671446609, 13653.590392288048, 13781.51764688231, 13793.310334483236, 13796.172229666252, 13797.24115173675, 10322.071013608662, 10396.272260941661, 10401.947990260152, 10412.219083568763, 10422.062014418738, 18173.63652726938, 18384.26462941494, 18420.17109230088, 18453.446396608124, 18455.354062607745, 6644.8671026579195, 6733.0, 6792.3886850182735, 6859.382655561023, 7122.465815063787, 811.1482942909921, 872.8472517309445, 891.6118748754706, 909.1226965977863, 978.1392374710209, 27076.729232707814, 27131.457370852473, 27291.727082729314, 27305.49819759932, 28604.284892877844, 772.4554189296532, 952.7856232347666, 972.7859870828406, 979.1970584120952, 1060.4750648429076, 14049.859501405059, 14994.325255363536, 15106.177124789405, 15107.0, 15238.466653667068, 375268.0, 377486.0, 379382.0, 379589.0, 379653.0, 48603.756981214865, 49049.0, 49090.263646045336, 49108.263376049385, 49157.508424916006, 1102.6581759654373, 1132.0660455124555, 1163.7439763252241, 1188.013948422807, 1213.878612138779, 27242.5913611292, 29237.561436578042, 29676.55485167681, 29942.700572993992, 30083.699163008965, 42665.0, 43107.353389699805, 43186.78406607947, 43187.35218971655, 43306.56693433025, 287723.0, 288994.0, 289426.0, 290410.0, 291779.0, 24982.62526062074, 25583.488330233664, 25994.350141245865, 26000.47999039971, 27376.315592110357, 41323.1735365297, 41563.0, 41613.37579936243, 41832.58167418287, 42951.785241073594, 35865.28269434649, 36004.639953600126, 36103.27793444169, 36582.26835463329, 36687.63312366949, 35160.64839351574, 35442.468362975094, 36107.81946090253, 36276.0, 36462.63537364592, 34985.475217871244, 35085.0, 35166.0, 35353.0, 35415.0, 30930.707505480437, 33425.832870836464, 33434.66565334413, 33522.0, 33523.0, 25251.74748252494, 25261.87369063143, 25353.87323063373, 25889.870550647127, 26095.608565871906], "value": 17203.42547453345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.6395964439119, 364.2714570858283, 366.104873584087, 366.9577998530169, 377.9603141670125, 12879.871201287984, 13113.934430327836, 13383.866161338396, 16542.255598498068, 17453.7381939271, 16725.0, 16952.83047169528, 16980.830191698078, 17104.230309636067, 17190.82809171908, 5053.938672878699, 5202.4537423570355, 5242.449542798008, 5259.86850328741, 5273.841784746444, 3731.067233191702, 5198.142306519419, 5204.115300398922, 5225.294585231002, 5251.317328747276, 4604.079184163164, 6125.111858780474, 6134.294556126036, 6236.563440559163, 6249.625022498642, 5980.312264089642, 6113.633182009094, 6230.065490176465, 6241.65670888101, 6248.875022499525, 5390.272313237702, 5391.218273350381, 5394.487523685244, 5395.379531353866, 5395.973020134914, 18376.448706538824, 19118.61762764751, 19208.80791192098, 20225.696614550794, 20604.0, 1781.100544225171, 1842.5485755989869, 1967.7933816949158, 2181.454636340915, 2250.1224522436264, 9121.179093881583, 9256.72229833106, 9373.671921482715, 9591.712248632552, 10842.96991785787, 1771.4685594321652, 1780.7506949027147, 1794.3271273272524, 1803.9098045097692, 1866.505376075341, 67076.6646166771, 67582.0, 69362.0, 70431.64784176098, 71340.6432967837, 9677.854832177523, 9694.46680432583, 9798.285300072459, 9914.851277230848, 10012.899871001342, 13202.273874936778, 13205.669858253621, 13209.73580528384, 13215.405306761219, 13218.272994985367, 6725.9663701681175, 6858.588484690935, 6885.552439091443, 7029.402500787388, 7046.5067445279365, 6484.610923344614, 6551.770688025897, 6669.133012708414, 6681.365270299314, 6753.12209412783, 4108.979455102765, 4112.897177570525, 4135.731177473454, 4214.431051808028, 4226.978865105716, 1673.5983363992798, 1787.204693911197, 1796.0, 1815.9001254931106, 1826.9545143808236, 675.7364627795164, 686.6212882323161, 876.8246350729879, 910.9225715814098, 963.3016063354012, 16237.0, 16356.918215408847, 17369.47891563255, 17556.209970551357, 17580.912095439697, 835.0980940584154, 861.9269010082487, 862.2843040276556, 866.5623859950684, 889.7775556110972, 8986.910130898737, 9714.22286217101, 9729.805403891882, 9731.318807683394, 9882.752931176778, 384053.0, 387911.0, 390902.0, 391525.0, 392725.0, 72894.63552682202, 73574.63212683902, 73737.6313118431, 74392.62803686055, 75168.0, 824.3734761581242, 827.5779352530133, 881.2509367038094, 887.4187407248263, 889.9644014239358, 15161.393544258106, 15552.222388880737, 15864.92067539655, 16008.879378443396, 16145.838541614903, 37831.81084094562, 38391.808040959615, 38619.80690096531, 38788.0, 39127.608723912395, 46864.53135468738, 50746.731331717005, 52619.21071183859, 52633.7368313156, 53157.0, 4459.866204013885, 5107.846764597068, 5873.2658417697785, 7140.571565706075, 7917.564533950457, 9585.808283834423, 10747.46262686847, 10991.890081099087, 11043.889561104608, 11887.524499019943, 10097.495125243855, 10208.0, 10284.897151028694, 10520.684379468628, 10753.193510486588, 9741.415515069119, 10356.585736570452, 10410.739731506774, 10672.89327106719, 10673.146148307962, 10527.578896844041, 10681.092107170667, 10769.946150269512, 10826.837597436202, 10919.672409827717, 7321.231270716498, 7691.2308769124875, 7767.1456139825705, 7821.804454888674, 7983.800404989922, 9435.669751558937, 9753.609855605697, 9901.306908516335, 10045.146162576262, 10557.9472102639], "value": 8092.98064944895, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.4966162519866, 1397.6855207578294, 1480.0, 1654.2142482320892, 1693.0688121533146, 7400.444966627504, 7968.681252749883, 7970.043594768621, 7976.481528700645, 8008.719694810683, 6272.184615999925, 6463.385978332063, 7416.184219735825, 7706.113796913357, 7791.0, 2048.446919331783, 2077.1898959405844, 2077.356019633911, 2078.8544801863877, 2078.9480262993393, 2140.3578926322043, 2152.074607918594, 2167.7723838996917, 2197.450637340665, 2199.912003519857, 1780.2789870102629, 2118.4174352053155, 2158.5035441848386, 2197.461621902628, 2214.0, 2156.212982261479, 2184.213683074092, 2184.421128400975, 2186.158329043314, 2189.4526368407896, 2071.927482538119, 2111.672690732939, 2112.0601332407086, 2112.757032941216, 2113.556153207828, 7717.0, 7882.487638303492, 7883.921160788376, 7906.090799558067, 7907.0906845712325, 4453.665975051884, 4579.702319349231, 4591.586757191835, 4592.288195329729, 4597.103564804865, 2711.240852561286, 2816.9295767605768, 2855.5431131018936, 2864.8281103133877, 2867.0, 842.4061036968923, 861.3281640320497, 864.4035615425399, 879.4283715584857, 881.2069137775989, 9785.510724463747, 9846.113849753556, 9866.950665246628, 10031.799364012679, 10039.949800251024, 3917.353636649978, 4044.2922488564973, 4154.356074808409, 4157.521884983205, 4163.10493243954, 8823.205911467998, 8852.513111778913, 8863.867041994376, 8867.0, 8884.422512536665, 4581.266997280486, 4601.907961840744, 4608.239640459354, 4608.28571571407, 4614.284785858197, 4100.958990410118, 4189.350650649093, 4195.580441955841, 4196.958030419718, 4197.349410841324, 3593.8742144025355, 3672.7429079964145, 3755.023693839567, 3761.363806744075, 3876.1860009398342, 1142.257532603814, 1169.4912712969885, 1180.3744015671707, 1184.644606618011, 1186.7448498572849, 718.4324383736789, 822.5106061893164, 832.2052439919862, 840.8108175660427, 842.439777547936, 4976.0794253063295, 5081.339425874687, 5164.741762911839, 5223.242629818655, 5231.843044708665, 378.53062202868796, 397.99903243343016, 401.19560281635137, 402.981865816039, 407.6473850119684, 3574.12433953683, 3589.3898037333715, 3619.9819000905354, 3652.3608368535934, 3998.6001399859783, 9534.809303813885, 9675.225981921429, 9738.367006144577, 9742.805143897223, 9804.803903922024, 9718.611255549698, 9726.854097188407, 9730.902690973, 9733.270004749673, 9740.220782337396, 6919.792406227822, 7337.926620733831, 7563.130240022358, 7572.545647261182, 7576.469647124648, 5314.22943673173, 5342.786288548415, 5376.408595054462, 5401.054815407367, 5674.517665998436, 8450.19723126302, 8587.570621469027, 8709.0, 8861.069587693457, 8950.552472376485, 8708.085651006806, 8742.082081381363, 8801.427907186013, 9101.317401194805, 9141.3601047926, 6635.270120286668, 6754.425873800782, 6754.986751987241, 6771.153605799275, 6773.390394864486, 7439.70241190368, 7447.292507211806, 7489.850202996018, 7494.100707915085, 7499.062617172854, 6772.762953296611, 6774.559653622498, 6794.8641027180165, 6939.791806245821, 6967.163940327193, 6652.900206497004, 6718.0594716738715, 6804.353586409283, 6825.35159159879, 6849.828754281183, 6313.494920406449, 6406.135171751754, 6406.199225096863, 6413.71138298787, 6417.0, 4381.846635367747, 4393.868183954359, 4468.932966005447, 4611.308303754464, 4647.604953578983, 4528.184926713091, 4563.977180114078, 4605.861824145281, 4653.348531205566, 4789.209780386197], "value": 4015.8307397215754, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.81535448022225, 301.4152544064515, 307.1522597630539, 309.5588785979981, 309.8388837804342, 4961.553460188582, 4982.683052380647, 4989.850304490862, 4994.97502512487, 4995.400551933764, 4876.390451193601, 4900.436449808273, 4928.5564299213065, 4928.581070608993, 4945.826896058638, 3873.0898238913833, 3896.961030389695, 3900.356441187215, 3925.8037098145123, 3935.9803200983956, 3790.3366910790637, 3881.825317860703, 3905.4141878718137, 3933.9606603934026, 3946.743461674982, 3840.481534992782, 3843.6925045996254, 3863.3818589025764, 3873.767573945558, 3881.0, 3817.1983883384246, 3834.118152824838, 3860.4595356650093, 3861.1698484825615, 3871.644924276509, 3232.7413806895393, 3308.669133086696, 3350.542514006415, 3381.830908454592, 3460.1176699941607, 4702.166155199475, 4716.915109524829, 4722.551357621055, 4755.42934847817, 4758.476567577563, 4364.581511008935, 4364.716293440916, 4384.627306678936, 4395.978020109911, 4400.757958312323, 4720.645951553647, 4833.984863178736, 5104.234364845266, 5104.9234261485735, 5108.846734597968, 3675.7059435245114, 3687.465317528943, 3694.224212915291, 3704.833282502294, 3721.5906250312446, 5330.413654498002, 5330.520253177194, 5344.706041167734, 5359.946400536022, 5384.3538775346815, 3522.18989632386, 3696.6488183622514, 3813.275477659236, 3818.3508803503473, 3822.6559609635265, 3736.215394767075, 3769.1896242308035, 3782.6973842092566, 3787.450819631138, 3791.469194312798, 4347.239233134252, 4348.34774783785, 4356.237658409766, 4379.474463064453, 4388.605025547716, 5162.45794191612, 5162.8967420652125, 5185.2999845021195, 5191.247269146028, 5211.0, 1243.3161761031397, 1299.0257307019735, 1313.0611612696966, 1318.182726709427, 1321.2799024531653, 824.8185399212164, 831.409699113624, 840.6469282901196, 844.957752112392, 856.3491746272753, 4034.2738307105, 4047.7571345719352, 4050.675945924319, 4061.7969101545395, 4085.59144085595, 4639.628829693616, 4667.253239481734, 4667.879708869915, 4668.649851261169, 4669.95330046702, 4009.8797036088963, 4016.859409920639, 4028.8388464461677, 4029.5970402960056, 4039.2729308724124, 2322.7573248312074, 2473.0602371098867, 2473.4682043360403, 2474.91337803176, 2475.9009639614574, 4430.13612345596, 4703.623710103184, 4814.7833347499445, 4947.579455746302, 4966.751662416937, 5381.919271210935, 5383.757730902119, 5383.946160538423, 5404.864878377994, 5464.562834973192, 5007.048660754591, 5011.899762004812, 5025.773840177201, 5027.371578552681, 5034.672746271481, 5114.616403769658, 5272.55183309423, 5295.576353891603, 5300.681959082467, 5303.7878484860175, 5233.293505376878, 5242.370915490165, 5248.527632513092, 5263.6841789492755, 5297.735113244399, 4783.7129772213775, 5006.5994720423005, 5023.2716256143385, 5029.497050295087, 5063.620228482805, 3698.944515832211, 3707.6281775743146, 3718.1076541629272, 3720.4605332227216, 3726.6087060859304, 5301.522862942354, 5314.28257185275, 5320.361556613077, 5345.251664767014, 5360.892782144413, 5082.33929589151, 5195.9740201298755, 5200.6619569727845, 5205.271262023395, 5246.868828279324, 5140.64015518925, 5142.382914050338, 5144.6398752087, 5158.535731784157, 5173.81891633791, 5058.241263810458, 5059.5952323814745, 5071.264666623393, 5080.847574572768, 5113.590912727047, 4963.429205641253, 5029.27075574047, 5163.380394352702, 5175.1019429119815, 5189.896202075862, 5010.198368261061, 5013.423456302426, 5037.294778731054, 5038.546530812245, 5087.923681144712], "value": 3822.424475589043, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.5567434703545, 566.4081035318089, 592.6947621974682, 593.382881802925, 601.6330038676407, 12578.937105314471, 13272.469101235938, 13287.136336138145, 13291.468341266338, 13294.0, 13478.93260533696, 14207.360668769905, 14401.567952961403, 14890.702185956274, 14925.328360223788, 776.7941495503682, 792.7859477940953, 793.5199204481293, 794.3049831397527, 794.400227827989, 632.9620222786624, 753.3521171792254, 777.4169372970272, 777.4441274488739, 778.7352300217925, 811.5130921447144, 813.536284317939, 814.1085511365046, 814.2102160903943, 814.450246083891, 808.59974312715, 809.134226377776, 809.1948511231333, 809.5345176523493, 809.5668817182808, 490.5633985752683, 501.04550830667637, 501.48597687370375, 501.8745313671582, 581.1138014527836, 11843.46704398304, 12288.262704237774, 12291.323977181251, 12295.877041229563, 12317.56888508907, 10644.57421703131, 10819.83770243447, 11455.427228638535, 11911.404429778562, 12462.0, 10087.89912100877, 10159.593616255415, 10201.285909986304, 10206.795864082676, 10516.421596812172, 3176.7299779518767, 3203.5675183850117, 3237.0, 3258.234314935988, 3296.620888597818, 66972.0, 67261.0, 68106.0, 68652.0, 69168.6541567294, 9111.453312801254, 9118.270538356915, 9173.312001599774, 9397.436153830791, 9436.622535098657, 17738.201780919888, 17898.5525361867, 17919.910400447916, 18008.549786255448, 18137.0, 6824.146981627297, 6851.3491218334175, 6908.620025898524, 7084.114485689289, 7114.39527640146, 3802.8288727007352, 3829.617038296148, 3830.3871380578976, 3886.6696330811633, 3903.0437542802197, 2666.9333266668486, 2775.2229375774814, 2776.958345624777, 2782.7773778097703, 2783.819051761629, 479.89922116355257, 482.17788670317026, 482.9975850120727, 485.53631282125485, 498.72071639881057, 449.0256144167165, 525.6110478246147, 532.5455479587349, 551.9282493275848, 629.6064959400375, 7882.369410447151, 8006.239407256301, 8029.598520073973, 8066.556339401389, 8159.67361305553, 471.3660127129006, 508.7303729023623, 518.7639623971085, 534.8072010099698, 541.1260813785786, 3758.1356288053903, 3837.3092843288478, 3942.980285098556, 4051.108756073659, 4057.655099316532, 57304.713476433186, 72164.0, 99231.50384248032, 106264.0, 107232.4638376803, 65345.0, 91123.0, 91223.54388228017, 91772.0, 95773.0, 80294.0, 82760.17239827645, 83203.26365731632, 84282.5785871079, 84809.57595211985, 7369.189389167294, 7396.96301518489, 7763.5730034846365, 7905.446618736633, 7935.523868567904, 46632.30051549162, 47568.097276108565, 47668.76165619289, 48089.27866082082, 48894.26658600195, 68232.6588367055, 70067.0, 70211.64894175496, 71527.64236178786, 72246.27753722605, 9160.633574657206, 9322.254219662547, 9361.063893610857, 10206.948965255126, 10472.581096756045, 11304.208705390543, 11511.30932144074, 11700.06399488056, 11870.465829037887, 12805.8079128815, 11577.942110289394, 11688.0, 11779.587714430296, 12149.453274602487, 12263.69340766488, 7559.470837041355, 10457.320274182153, 11086.223964322742, 11558.595449159238, 11632.709182270512, 11147.888521114684, 11494.252873563191, 11545.13411494158, 11638.53445862156, 11646.534138634359, 9313.674021409219, 9423.38747981379, 9516.95241523788, 9576.712698619052, 9594.952025239829, 8444.197801208875, 8937.106289371011, 9003.279737621106, 9055.538374053534, 9103.90896091057], "value": 6357.721192145754, "unit": "score"}], "name": "dromaeo_css", "value": 6327.098579768174, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 113.0, 114.0, 115.0, 113.0, 113.0, 115.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 124.0, 172.0, 121.0, 128.0, 144.0, 121.0, 166.0, 122.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 162.0, 162.0, 163.0, 161.0, 162.0, 160.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 110.0, 76.0, 76.0, 74.0, 74.0, 103.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 128.0, 85.0, 85.0, 85.0, 133.0, 84.0, 85.0, 134.0], "value": 99.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 63.0, 65.0, 63.0, 62.0, 63.0, 62.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 86.0, 79.0, 80.0, 85.0, 81.0, 88.0, 79.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 140.0, 144.0, 137.0, 140.0, 131.0, 136.0, 137.0, 143.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 169.0, 163.0, 165.0, 168.0, 163.0, 164.0, 162.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 73.0, 66.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1531.8, "alertThreshold": 2.0}]} 16:56:56 INFO - Return code: 0 16:56:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:56:56 INFO - # TBPL SUCCESS # 16:56:56 INFO - Running post-action listener: _resource_record_post_action 16:56:56 INFO - [mozharness: 2016-08-07 23:56:56.927818Z] Finished run-tests step (success) 16:56:56 INFO - Running post-run listener: _resource_record_post_run 16:56:57 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 537542656; Read time: 0; Write time: 474704 16:56:57 INFO - TinderboxPrint: CPU usage
13.0% 16:56:57 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:56:57 INFO - TinderboxPrint: I/O write bytes / time
537,542,656 / 474,704 16:56:57 INFO - TinderboxPrint: CPU idle
8,329.5 (87.0%) 16:56:57 INFO - TinderboxPrint: CPU user
1,204.2 (12.6%) 16:56:57 INFO - TinderboxPrint: Swap in / out
0 / 0 16:56:57 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 251101184; Read time: 0; Write time: 242848 16:56:57 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 286441472; Read time: 0; Write time: 231856 16:56:57 INFO - Running post-run listener: _upload_blobber_files 16:56:57 INFO - Blob upload gear active. 16:56:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:56:57 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 16:56:57 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:56:57 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:56:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:56:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:56:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:56:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:56:58 INFO - (blobuploader) - INFO - Done attempting. 16:56:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:56:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:56:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:56:59 INFO - (blobuploader) - INFO - Done attempting. 16:56:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:56:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:56:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:57:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:03 INFO - (blobuploader) - INFO - Done attempting. 16:57:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:57:03 INFO - Return code: 0 16:57:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:57:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:57:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/588ed21bbc885915f70a73ebdac63247a86d907de5dbde13c68e9885dd3e40da400493945e230429cafc8da09704ce54f61263d7a4ca0b9caec2442036566b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbd4f6c99efad842669432da0fc784e7197180f63e1f95a283b52818deff940a4fee1cd3d5a59cc1e4e0775806bee0450311fb95e30c7c9d58266d3fc2b1f980", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6613e226d8514a7cdbfc01e9b42dccffde9d9871dfa8ec1170fa8c89f224b0425701003884b93ddb03d8d988c1b529277cb696eb08e3ae0db6dbd7cbf663d57e"} 16:57:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:57:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:57:03 INFO - Contents: 16:57:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/588ed21bbc885915f70a73ebdac63247a86d907de5dbde13c68e9885dd3e40da400493945e230429cafc8da09704ce54f61263d7a4ca0b9caec2442036566b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbd4f6c99efad842669432da0fc784e7197180f63e1f95a283b52818deff940a4fee1cd3d5a59cc1e4e0775806bee0450311fb95e30c7c9d58266d3fc2b1f980", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6613e226d8514a7cdbfc01e9b42dccffde9d9871dfa8ec1170fa8c89f224b0425701003884b93ddb03d8d988c1b529277cb696eb08e3ae0db6dbd7cbf663d57e"} 16:57:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:57:03 INFO - Copying logs to upload dir... 16:57:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:57:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.510816 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-08-07 16:57:03.104339) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:57:03.107611) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/588ed21bbc885915f70a73ebdac63247a86d907de5dbde13c68e9885dd3e40da400493945e230429cafc8da09704ce54f61263d7a4ca0b9caec2442036566b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbd4f6c99efad842669432da0fc784e7197180f63e1f95a283b52818deff940a4fee1cd3d5a59cc1e4e0775806bee0450311fb95e30c7c9d58266d3fc2b1f980", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6613e226d8514a7cdbfc01e9b42dccffde9d9871dfa8ec1170fa8c89f224b0425701003884b93ddb03d8d988c1b529277cb696eb08e3ae0db6dbd7cbf663d57e"} build_url:https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/fMfstDePQaWpUX0POGsR6A/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/Autoland-Non-PGO/sha512/588ed21bbc885915f70a73ebdac63247a86d907de5dbde13c68e9885dd3e40da400493945e230429cafc8da09704ce54f61263d7a4ca0b9caec2442036566b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbd4f6c99efad842669432da0fc784e7197180f63e1f95a283b52818deff940a4fee1cd3d5a59cc1e4e0775806bee0450311fb95e30c7c9d58266d3fc2b1f980", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6613e226d8514a7cdbfc01e9b42dccffde9d9871dfa8ec1170fa8c89f224b0425701003884b93ddb03d8d988c1b529277cb696eb08e3ae0db6dbd7cbf663d57e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:57:03.132660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:57:03.132975) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1470612838.472660-1339001871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004409 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:57:03.181919) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:57:03.182229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:57:03.182502) ========= ========= Total master_lag: 0.49 =========