builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-041 starttime: 1465339035.52 results: success (0) buildid: 20160607142622 builduid: f176d15aaf8046a386d41aab72a6cf08 revision: 99b213f494e1a9e20629e936681faedec16d22a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.516080) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.516600) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.516886) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.532841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.533290) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 15:37:15-- 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% 37.9M=0s 2016-06-07 15:37:15 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.677062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.677440) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022891 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.727359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:15.727730) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 99b213f494e1a9e20629e936681faedec16d22a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 99b213f494e1a9e20629e936681faedec16d22a3 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 15:37:16,112 truncating revision to first 12 chars 2016-06-07 15:37:16,112 Setting DEBUG logging. 2016-06-07 15:37:16,112 attempt 1/10 2016-06-07 15:37:16,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/99b213f494e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 15:37:16,422 unpacking tar archive at: fx-team-99b213f494e1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661796 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:16.403353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:16.403728) ========= script_repo_revision: 99b213f494e1a9e20629e936681faedec16d22a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:16.404216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:16.404512) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:16.415648) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-07 15:37:16.416078) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:37:16 INFO - MultiFileLogger online at 20160607 15:37:16 in /builds/slave/test 15:37:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 15:37:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:37:16 INFO - {'append_to_log': False, 15:37:16 INFO - 'base_work_dir': '/builds/slave/test', 15:37:16 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 15:37:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:37:16 INFO - 'branch': 'Fx-Team-Non-PGO', 15:37:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:37:16 INFO - 'config_files': ('talos/linux_config.py',), 15:37:16 INFO - 'default_actions': ('clobber', 15:37:16 INFO - 'read-buildbot-config', 15:37:16 INFO - 'download-and-extract', 15:37:16 INFO - 'populate-webroot', 15:37:16 INFO - 'create-virtualenv', 15:37:16 INFO - 'install', 15:37:16 INFO - 'run-tests'), 15:37:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:37:16 INFO - 'download_minidump_stackwalk': True, 15:37:16 INFO - 'download_symbols': 'ondemand', 15:37:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:37:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:37:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:37:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:37:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:37:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:37:16 INFO - 'installer_path': 'installer.exe', 15:37:16 INFO - 'log_level': 'info', 15:37:16 INFO - 'log_name': 'talos', 15:37:16 INFO - 'log_to_console': True, 15:37:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:37:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:37:16 INFO - 'opt_config_files': (), 15:37:16 INFO - 'pip_index': False, 15:37:16 INFO - 'sps_profile': False, 15:37:16 INFO - 'sps_profile_interval': 0, 15:37:16 INFO - 'suite': 'dromaeojs-e10s', 15:37:16 INFO - 'system_bits': '32', 15:37:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:37:16 INFO - 'title': 'talos-linux64-ix-041', 15:37:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:37:16 INFO - 'use_talos_json': True, 15:37:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:37:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:37:16 INFO - 'work_dir': 'build'} 15:37:16 INFO - ##### 15:37:16 INFO - ##### Running clobber step. 15:37:16 INFO - ##### 15:37:16 INFO - Running pre-action listener: _resource_record_pre_action 15:37:16 INFO - Running main action method: clobber 15:37:16 INFO - rmtree: /builds/slave/test/build 15:37:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:37:17 INFO - Running post-action listener: _resource_record_post_action 15:37:17 INFO - ##### 15:37:17 INFO - ##### Finished clobber step (success) 15:37:17 INFO - ##### 15:37:17 INFO - ##### 15:37:17 INFO - ##### Running read-buildbot-config step. 15:37:17 INFO - ##### 15:37:17 INFO - Running pre-action listener: _resource_record_pre_action 15:37:17 INFO - Running main action method: read_buildbot_config 15:37:17 INFO - Using buildbot properties: 15:37:17 INFO - { 15:37:17 INFO - "project": "", 15:37:17 INFO - "product": "firefox", 15:37:17 INFO - "script_repo_revision": "production", 15:37:17 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 15:37:17 INFO - "repository": "", 15:37:17 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 15:37:17 INFO - "buildid": "20160607142622", 15:37:17 INFO - "pgo_build": "False", 15:37:17 INFO - "basedir": "/builds/slave/test", 15:37:17 INFO - "buildnumber": 1377, 15:37:17 INFO - "slavename": "talos-linux64-ix-041", 15:37:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:37:17 INFO - "platform": "ubuntu64_hw", 15:37:17 INFO - "scheduler": "tests-fx-team-linux64-talos", 15:37:17 INFO - "branch": "fx-team", 15:37:17 INFO - "repo_path": "integration/fx-team", 15:37:17 INFO - "revision": "99b213f494e1a9e20629e936681faedec16d22a3", 15:37:17 INFO - "stage_platform": "linux64", 15:37:17 INFO - "builduid": "f176d15aaf8046a386d41aab72a6cf08", 15:37:17 INFO - "slavebuilddir": "test" 15:37:17 INFO - } 15:37:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:37:17 INFO - Running post-action listener: _resource_record_post_action 15:37:17 INFO - ##### 15:37:17 INFO - ##### Finished read-buildbot-config step (success) 15:37:17 INFO - ##### 15:37:17 INFO - ##### 15:37:17 INFO - ##### Running download-and-extract step. 15:37:17 INFO - ##### 15:37:17 INFO - Running pre-action listener: _resource_record_pre_action 15:37:17 INFO - Running main action method: download_and_extract 15:37:17 INFO - mkdir: /builds/slave/test/build/tests 15:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:17 INFO - https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:37:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:37:18 INFO - Downloaded 1361 bytes. 15:37:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:18 INFO - Using the following test package requirements: 15:37:18 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:37:18 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:37:18 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:37:18 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'jsshell-linux-x86_64.zip'], 15:37:18 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:37:18 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:37:18 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:37:18 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:37:18 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:37:18 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:18 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:37:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:18 INFO - https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:37:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:19 INFO - Downloaded 22873824 bytes. 15:37:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:37:20 INFO - Return code: 0 15:37:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:20 INFO - https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:37:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:21 INFO - Downloaded 22873824 bytes. 15:37:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:37:23 INFO - Return code: 0 15:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:23 INFO - https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:37:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:37:23 INFO - Downloaded 10833369 bytes. 15:37:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:37:24 INFO - Return code: 0 15:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:24 INFO - https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:37:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:37:25 INFO - Downloaded 55204015 bytes. 15:37:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:25 INFO - mkdir: /builds/slave/test/properties 15:37:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:25 INFO - Contents: 15:37:25 INFO - build_url:https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:26 INFO - Running post-action listener: _resource_record_post_action 15:37:26 INFO - Running post-action listener: set_extra_try_arguments 15:37:26 INFO - ##### 15:37:26 INFO - ##### Finished download-and-extract step (success) 15:37:26 INFO - ##### 15:37:26 INFO - ##### 15:37:26 INFO - ##### Running populate-webroot step. 15:37:26 INFO - ##### 15:37:26 INFO - Running pre-action listener: _resource_record_pre_action 15:37:26 INFO - Running main action method: populate_webroot 15:37:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:37:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:37:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:37:26 INFO - '--tppagecycles', 15:37:26 INFO - '7'], 15:37:26 INFO - 'tests': ['tsvgm']}}, 15:37:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:37:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:37:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['damp', 'tps']}, 15:37:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'tests': ['damp', 'tps']}, 15:37:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['dromaeo_dom']}, 15:37:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['a11yr', 15:37:26 INFO - 'ts_paint', 15:37:26 INFO - 'tpaint', 15:37:26 INFO - 'sessionrestore', 15:37:26 INFO - 'sessionrestore_no_auto_restore', 15:37:26 INFO - 'tabpaint']}, 15:37:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['tsvgx', 15:37:26 INFO - 'tsvgr_opacity', 15:37:26 INFO - 'tart', 15:37:26 INFO - 'tscrollx', 15:37:26 INFO - 'cart']}, 15:37:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:26 INFO - 'tsvgr_opacity', 15:37:26 INFO - 'tart', 15:37:26 INFO - 'tscrollx', 15:37:26 INFO - 'cart']}, 15:37:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'talos_options': ['--disable-e10s'], 15:37:26 INFO - 'tests': ['tp5o']}, 15:37:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'tests': ['tp5o']}, 15:37:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'talos_options': ['--disable-e10s', 15:37:26 INFO - '--xperf_path', 15:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:26 INFO - 'tests': ['tp5n']}, 15:37:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:26 INFO - 'talos_options': ['--xperf_path', 15:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:26 INFO - 'tests': ['tp5n']}}, 15:37:26 INFO - 'talos.zip': {'path': '', 15:37:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:37:26 INFO - Running post-action listener: _resource_record_post_action 15:37:26 INFO - ##### 15:37:26 INFO - ##### Finished populate-webroot step (success) 15:37:26 INFO - ##### 15:37:26 INFO - ##### 15:37:26 INFO - ##### Running create-virtualenv step. 15:37:26 INFO - ##### 15:37:26 INFO - Running pre-action listener: _resource_record_pre_action 15:37:26 INFO - Running main action method: create_virtualenv 15:37:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37:26 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 15:37:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:37:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:26 INFO - Using real prefix '/usr' 15:37:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:27 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:29 INFO - Installing pip.................done. 15:37:29 INFO - Return code: 0 15:37:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:29 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 15:37:29 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 15:37:29 INFO - Using env: {'DISPLAY': ':0', 15:37:29 INFO - 'HOME': '/home/cltbld', 15:37:29 INFO - 'LANG': 'en_US.UTF-8', 15:37:29 INFO - 'LANGUAGE': 'en_US:en', 15:37:29 INFO - 'LOGNAME': 'cltbld', 15:37:29 INFO - 'MAIL': '/var/mail/cltbld', 15:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:29 INFO - 'MOZ_NO_REMOTE': '1', 15:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:29 INFO - 'NO_EM_RESTART': '1', 15:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:29 INFO - 'PWD': '/builds/slave/test', 15:37:29 INFO - 'SHELL': '/bin/bash', 15:37:29 INFO - 'SHLVL': '1', 15:37:29 INFO - 'TERM': 'linux', 15:37:29 INFO - 'TMOUT': '86400', 15:37:29 INFO - 'USER': 'cltbld', 15:37:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:29 INFO - '_': '/tools/buildbot/bin/python'} 15:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:29 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 15:37:29 INFO - Cleaning up... 15:37:29 INFO - Return code: 0 15:37:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:29 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 15:37:29 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 15:37:29 INFO - Using env: {'DISPLAY': ':0', 15:37:29 INFO - 'HOME': '/home/cltbld', 15:37:29 INFO - 'LANG': 'en_US.UTF-8', 15:37:29 INFO - 'LANGUAGE': 'en_US:en', 15:37:29 INFO - 'LOGNAME': 'cltbld', 15:37:29 INFO - 'MAIL': '/var/mail/cltbld', 15:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:29 INFO - 'MOZ_NO_REMOTE': '1', 15:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:29 INFO - 'NO_EM_RESTART': '1', 15:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:29 INFO - 'PWD': '/builds/slave/test', 15:37:29 INFO - 'SHELL': '/bin/bash', 15:37:29 INFO - 'SHLVL': '1', 15:37:29 INFO - 'TERM': 'linux', 15:37:29 INFO - 'TMOUT': '86400', 15:37:29 INFO - 'USER': 'cltbld', 15:37:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:29 INFO - '_': '/tools/buildbot/bin/python'} 15:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:29 INFO - Downloading/unpacking psutil>=3.1.1 15:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:29 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 15:37:29 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 15:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:31 INFO - Installing collected packages: psutil 15:37:31 INFO - Running setup.py install for psutil 15:37:31 INFO - building 'psutil._psutil_linux' extension 15:37:31 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 15:37:31 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 15:37:31 INFO - building 'psutil._psutil_posix' extension 15:37:31 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 15:37:32 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 15:37:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:32 INFO - Successfully installed psutil 15:37:32 INFO - Cleaning up... 15:37:32 INFO - Return code: 0 15:37:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:32 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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:32 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 15:37:32 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 15:37:32 INFO - Using env: {'DISPLAY': ':0', 15:37:32 INFO - 'HOME': '/home/cltbld', 15:37:32 INFO - 'LANG': 'en_US.UTF-8', 15:37:32 INFO - 'LANGUAGE': 'en_US:en', 15:37:32 INFO - 'LOGNAME': 'cltbld', 15:37:32 INFO - 'MAIL': '/var/mail/cltbld', 15:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:32 INFO - 'MOZ_NO_REMOTE': '1', 15:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:32 INFO - 'NO_EM_RESTART': '1', 15:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:32 INFO - 'PWD': '/builds/slave/test', 15:37:32 INFO - 'SHELL': '/bin/bash', 15:37:32 INFO - 'SHLVL': '1', 15:37:32 INFO - 'TERM': 'linux', 15:37:32 INFO - 'TMOUT': '86400', 15:37:32 INFO - 'USER': 'cltbld', 15:37:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:32 INFO - '_': '/tools/buildbot/bin/python'} 15:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:32 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 15:37:32 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 15:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:37:34 INFO - Installing collected packages: mozsystemmonitor 15:37:34 INFO - Running setup.py install for mozsystemmonitor 15:37:34 INFO - Successfully installed mozsystemmonitor 15:37:34 INFO - Cleaning up... 15:37:34 INFO - Return code: 0 15:37:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:34 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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:34 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 15:37:34 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 15:37:34 INFO - Using env: {'DISPLAY': ':0', 15:37:34 INFO - 'HOME': '/home/cltbld', 15:37:34 INFO - 'LANG': 'en_US.UTF-8', 15:37:34 INFO - 'LANGUAGE': 'en_US:en', 15:37:34 INFO - 'LOGNAME': 'cltbld', 15:37:34 INFO - 'MAIL': '/var/mail/cltbld', 15:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:34 INFO - 'MOZ_NO_REMOTE': '1', 15:37:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:34 INFO - 'NO_EM_RESTART': '1', 15:37:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:34 INFO - 'PWD': '/builds/slave/test', 15:37:34 INFO - 'SHELL': '/bin/bash', 15:37:34 INFO - 'SHLVL': '1', 15:37:34 INFO - 'TERM': 'linux', 15:37:34 INFO - 'TMOUT': '86400', 15:37:34 INFO - 'USER': 'cltbld', 15:37:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:34 INFO - '_': '/tools/buildbot/bin/python'} 15:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:34 INFO - Downloading/unpacking jsonschema==2.5.1 15:37:34 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 15:37:34 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 15:37:34 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 15:37:34 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 15:37:34 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 15:37:34 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 15:37:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:37:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:37:36 INFO - warning: no files found matching '*.txt' 15:37:36 INFO - no previously-included directories found matching 'build' 15:37:36 INFO - no previously-included directories found matching 'dist' 15:37:36 INFO - no previously-included directories found matching '.git*' 15:37:36 INFO - Installing collected packages: jsonschema, functools32 15:37:37 INFO - Running setup.py install for functools32 15:37:37 INFO - warning: no files found matching '*.txt' 15:37:37 INFO - no previously-included directories found matching 'build' 15:37:37 INFO - no previously-included directories found matching 'dist' 15:37:37 INFO - no previously-included directories found matching '.git*' 15:37:37 INFO - Successfully installed jsonschema functools32 15:37:37 INFO - Cleaning up... 15:37:37 INFO - Return code: 0 15:37:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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', '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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:37: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 blobuploader==1.2.4 15:37:37 INFO - Using env: {'DISPLAY': ':0', 15:37:37 INFO - 'HOME': '/home/cltbld', 15:37:37 INFO - 'LANG': 'en_US.UTF-8', 15:37:37 INFO - 'LANGUAGE': 'en_US:en', 15:37:37 INFO - 'LOGNAME': 'cltbld', 15:37:37 INFO - 'MAIL': '/var/mail/cltbld', 15:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:37 INFO - 'MOZ_NO_REMOTE': '1', 15:37:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:37 INFO - 'NO_EM_RESTART': '1', 15:37:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:37 INFO - 'PWD': '/builds/slave/test', 15:37:37 INFO - 'SHELL': '/bin/bash', 15:37:37 INFO - 'SHLVL': '1', 15:37:37 INFO - 'TERM': 'linux', 15:37:37 INFO - 'TMOUT': '86400', 15:37:37 INFO - 'USER': 'cltbld', 15:37:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:37 INFO - '_': '/tools/buildbot/bin/python'} 15:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:37 INFO - Downloading/unpacking blobuploader==1.2.4 15:37:37 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 15:37:37 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 15:37:37 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 15:37:37 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 15:37:37 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 15:37:37 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 15:37:39 INFO - Downloading blobuploader-1.2.4.tar.gz 15:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:37:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:37:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 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 15:37:39 INFO - Downloading docopt-0.6.1.tar.gz 15:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:37:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:37:40 INFO - Running setup.py install for blobuploader 15:37:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:37:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:37:40 INFO - Running setup.py install for requests 15:37:40 INFO - Running setup.py install for docopt 15:37:40 INFO - Successfully installed blobuploader requests docopt 15:37:40 INFO - Cleaning up... 15:37:40 INFO - Return code: 0 15:37:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:40 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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:40 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 15:37:40 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 15:37:40 INFO - Using env: {'DISPLAY': ':0', 15:37:40 INFO - 'HOME': '/home/cltbld', 15:37:40 INFO - 'LANG': 'en_US.UTF-8', 15:37:40 INFO - 'LANGUAGE': 'en_US:en', 15:37:40 INFO - 'LOGNAME': 'cltbld', 15:37:40 INFO - 'MAIL': '/var/mail/cltbld', 15:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:40 INFO - 'MOZ_NO_REMOTE': '1', 15:37:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:40 INFO - 'NO_EM_RESTART': '1', 15:37:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:40 INFO - 'PWD': '/builds/slave/test', 15:37:40 INFO - 'SHELL': '/bin/bash', 15:37:40 INFO - 'SHLVL': '1', 15:37:40 INFO - 'TERM': 'linux', 15:37:40 INFO - 'TMOUT': '86400', 15:37:40 INFO - 'USER': 'cltbld', 15:37:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:40 INFO - '_': '/tools/buildbot/bin/python'} 15:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:40 INFO - Running setup.py (path:/tmp/pip-P4F7de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:40 INFO - Running setup.py (path:/tmp/pip-5npPAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:40 INFO - Running setup.py (path:/tmp/pip-bauHXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:41 INFO - Running setup.py (path:/tmp/pip-SdFXkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:41 INFO - Running setup.py (path:/tmp/pip-E5MZGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:41 INFO - Running setup.py (path:/tmp/pip-LDJvG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:41 INFO - Running setup.py (path:/tmp/pip-G77et7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:41 INFO - Running setup.py (path:/tmp/pip-pIWW26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:41 INFO - Running setup.py (path:/tmp/pip-PbosxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:41 INFO - Running setup.py (path:/tmp/pip-3pG3qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:41 INFO - Running setup.py (path:/tmp/pip-FsG6bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:41 INFO - Running setup.py (path:/tmp/pip-H7bymV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:41 INFO - Running setup.py (path:/tmp/pip-9leoDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:41 INFO - Running setup.py (path:/tmp/pip-tbdSGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:41 INFO - Running setup.py (path:/tmp/pip-ZmyrM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:41 INFO - Running setup.py (path:/tmp/pip-nW8SU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:41 INFO - Running setup.py (path:/tmp/pip-fr2R1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:37:42 INFO - Running setup.py install for manifestparser 15:37:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozcrash 15:37:42 INFO - Running setup.py install for mozdebug 15:37:42 INFO - Running setup.py install for mozdevice 15:37:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozfile 15:37:42 INFO - Running setup.py install for mozhttpd 15:37:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozinfo 15:37:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozInstall 15:37:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozleak 15:37:42 INFO - Running setup.py install for mozlog 15:37:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for moznetwork 15:37:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozprocess 15:37:43 INFO - Running setup.py install for mozprofile 15:37:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozrunner 15:37:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozscreenshot 15:37:43 INFO - Running setup.py install for moztest 15:37:43 INFO - Running setup.py install for mozversion 15:37:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:37:43 INFO - Cleaning up... 15:37:43 INFO - Return code: 0 15:37:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:43 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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:43 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 15:37:43 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 15:37:43 INFO - Using env: {'DISPLAY': ':0', 15:37:43 INFO - 'HOME': '/home/cltbld', 15:37:43 INFO - 'LANG': 'en_US.UTF-8', 15:37:43 INFO - 'LANGUAGE': 'en_US:en', 15:37:43 INFO - 'LOGNAME': 'cltbld', 15:37:43 INFO - 'MAIL': '/var/mail/cltbld', 15:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:43 INFO - 'MOZ_NO_REMOTE': '1', 15:37:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:43 INFO - 'NO_EM_RESTART': '1', 15:37:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:43 INFO - 'PWD': '/builds/slave/test', 15:37:43 INFO - 'SHELL': '/bin/bash', 15:37:43 INFO - 'SHLVL': '1', 15:37:43 INFO - 'TERM': 'linux', 15:37:43 INFO - 'TMOUT': '86400', 15:37:43 INFO - 'USER': 'cltbld', 15:37:43 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:43 INFO - '_': '/tools/buildbot/bin/python'} 15:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:44 INFO - Running setup.py (path:/tmp/pip-Pkn5Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:44 INFO - Running setup.py (path:/tmp/pip-4rKm6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:44 INFO - Running setup.py (path:/tmp/pip-Fnb_sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:44 INFO - Running setup.py (path:/tmp/pip-On1flK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:44 INFO - Running setup.py (path:/tmp/pip-QcXgZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:44 INFO - Running setup.py (path:/tmp/pip-CE_HHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:44 INFO - Running setup.py (path:/tmp/pip-DnNtp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:44 INFO - Running setup.py (path:/tmp/pip-FzqG_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:44 INFO - Running setup.py (path:/tmp/pip-l6L8b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:44 INFO - Running setup.py (path:/tmp/pip-gTDysH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:44 INFO - Running setup.py (path:/tmp/pip-kc2Tzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:44 INFO - Running setup.py (path:/tmp/pip-XiHG37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:44 INFO - Running setup.py (path:/tmp/pip-edblar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:44 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)) 15:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:44 INFO - Running setup.py (path:/tmp/pip-wXESZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:45 INFO - Running setup.py (path:/tmp/pip-5BLdkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:45 INFO - Running setup.py (path:/tmp/pip-sp0WFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:45 INFO - Running setup.py (path:/tmp/pip-xPvpB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:45 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)) 15:37:45 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)) 15:37:45 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)) 15:37:45 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)) 15:37:45 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)) 15:37:45 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)) 15:37:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:47 INFO - Downloading blessings-1.6.tar.gz 15:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:37:47 INFO - Installing collected packages: blessings 15:37:47 INFO - Running setup.py install for blessings 15:37:47 INFO - Successfully installed blessings 15:37:47 INFO - Cleaning up... 15:37:47 INFO - Return code: 0 15:37:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:37:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:47 INFO - Reading from file tmpfile_stdout 15:37:47 INFO - Current package versions: 15:37:47 INFO - argparse == 1.2.1 15:37:47 INFO - blessings == 1.6 15:37:47 INFO - blobuploader == 1.2.4 15:37:47 INFO - docopt == 0.6.1 15:37:47 INFO - functools32 == 3.2.3-2 15:37:47 INFO - jsonschema == 2.5.1 15:37:47 INFO - manifestparser == 1.1 15:37:47 INFO - mozInstall == 1.12 15:37:47 INFO - mozcrash == 0.17 15:37:47 INFO - mozdebug == 0.1 15:37:47 INFO - mozdevice == 0.48 15:37:47 INFO - mozfile == 1.2 15:37:47 INFO - mozhttpd == 0.7 15:37:47 INFO - mozinfo == 0.9 15:37:47 INFO - mozleak == 0.1 15:37:47 INFO - mozlog == 3.2 15:37:47 INFO - moznetwork == 0.27 15:37:47 INFO - mozprocess == 0.22 15:37:47 INFO - mozprofile == 0.28 15:37:47 INFO - mozrunner == 6.11 15:37:47 INFO - mozscreenshot == 0.1 15:37:47 INFO - mozsystemmonitor == 0.3 15:37:47 INFO - moztest == 0.7 15:37:47 INFO - mozversion == 1.4 15:37:47 INFO - psutil == 3.1.1 15:37:47 INFO - requests == 1.2.3 15:37:47 INFO - wsgiref == 0.1.2 15:37:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 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 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:47 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 15:37:47 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 15:37:47 INFO - Using env: {'DISPLAY': ':0', 15:37:47 INFO - 'HOME': '/home/cltbld', 15:37:47 INFO - 'LANG': 'en_US.UTF-8', 15:37:47 INFO - 'LANGUAGE': 'en_US:en', 15:37:47 INFO - 'LOGNAME': 'cltbld', 15:37:47 INFO - 'MAIL': '/var/mail/cltbld', 15:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:47 INFO - 'MOZ_NO_REMOTE': '1', 15:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:47 INFO - 'NO_EM_RESTART': '1', 15:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:47 INFO - 'PWD': '/builds/slave/test', 15:37:47 INFO - 'SHELL': '/bin/bash', 15:37:47 INFO - 'SHLVL': '1', 15:37:47 INFO - 'TERM': 'linux', 15:37:47 INFO - 'TMOUT': '86400', 15:37:47 INFO - 'USER': 'cltbld', 15:37:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:47 INFO - '_': '/tools/buildbot/bin/python'} 15:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 INFO - Cleaning up... 15:37:47 INFO - Return code: 0 15:37:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:37:47 INFO - Using env: {'DISPLAY': ':0', 15:37:47 INFO - 'HOME': '/home/cltbld', 15:37:47 INFO - 'LANG': 'en_US.UTF-8', 15:37:47 INFO - 'LANGUAGE': 'en_US:en', 15:37:47 INFO - 'LOGNAME': 'cltbld', 15:37:47 INFO - 'MAIL': '/var/mail/cltbld', 15:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:47 INFO - 'MOZ_NO_REMOTE': '1', 15:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:47 INFO - 'NO_EM_RESTART': '1', 15:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:47 INFO - 'PWD': '/builds/slave/test', 15:37:47 INFO - 'SHELL': '/bin/bash', 15:37:47 INFO - 'SHLVL': '1', 15:37:47 INFO - 'TERM': 'linux', 15:37:47 INFO - 'TMOUT': '86400', 15:37:47 INFO - 'USER': 'cltbld', 15:37:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:47 INFO - '_': '/tools/buildbot/bin/python'} 15:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:37:48 INFO - Cleaning up... 15:37:48 INFO - Return code: 0 15:37:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f908aa12f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d43350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', '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 15:37:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:37:48 INFO - Using env: {'DISPLAY': ':0', 15:37:48 INFO - 'HOME': '/home/cltbld', 15:37:48 INFO - 'LANG': 'en_US.UTF-8', 15:37:48 INFO - 'LANGUAGE': 'en_US:en', 15:37:48 INFO - 'LOGNAME': 'cltbld', 15:37:48 INFO - 'MAIL': '/var/mail/cltbld', 15:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:48 INFO - 'MOZ_NO_REMOTE': '1', 15:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:48 INFO - 'NO_EM_RESTART': '1', 15:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:48 INFO - 'PWD': '/builds/slave/test', 15:37:48 INFO - 'SHELL': '/bin/bash', 15:37:48 INFO - 'SHLVL': '1', 15:37:48 INFO - 'TERM': 'linux', 15:37:48 INFO - 'TMOUT': '86400', 15:37:48 INFO - 'USER': 'cltbld', 15:37:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:48 INFO - '_': '/tools/buildbot/bin/python'} 15:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:48 INFO - Downloading/unpacking flake8 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:37:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:37:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:37:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:37:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:37:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:37:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:37:50 INFO - no previously-included directories found matching 'docs/_build' 15:37:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:37:50 INFO - Running setup.py install for pep8 15:37:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:37:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:37:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:37:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:37:51 INFO - no previously-included directories found matching 'docs/_build' 15:37:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:37:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:37:51 INFO - Cleaning up... 15:37:51 INFO - Return code: 0 15:37:51 INFO - Running post-action listener: _resource_record_post_action 15:37:51 INFO - Running post-action listener: _start_resource_monitoring 15:37:51 INFO - Starting resource monitoring. 15:37:51 INFO - ##### 15:37:51 INFO - ##### Finished create-virtualenv step (success) 15:37:51 INFO - ##### 15:37:51 INFO - ##### 15:37:51 INFO - ##### Running install step. 15:37:51 INFO - ##### 15:37:51 INFO - Running pre-action listener: _resource_record_pre_action 15:37:51 INFO - Running main action method: install 15:37:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:51 INFO - Reading from file tmpfile_stdout 15:37:51 INFO - Detecting whether we're running mozinstall >=1.0... 15:37:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:37:51 INFO - Reading from file tmpfile_stdout 15:37:51 INFO - Output received: 15:37:51 INFO - Usage: mozinstall [options] installer 15:37:51 INFO - Options: 15:37:51 INFO - -h, --help show this help message and exit 15:37:51 INFO - -d DEST, --destination=DEST 15:37:51 INFO - Directory to install application into. [default: 15:37:51 INFO - "/builds/slave/test"] 15:37:51 INFO - --app=APP Application being installed. [default: firefox] 15:37:51 INFO - mkdir: /builds/slave/test/build/application 15:37:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:38:07 INFO - Reading from file tmpfile_stdout 15:38:07 INFO - Output received: 15:38:07 INFO - /builds/slave/test/build/application/firefox/firefox 15:38:07 INFO - Running post-action listener: _resource_record_post_action 15:38:07 INFO - ##### 15:38:07 INFO - ##### Finished install step (success) 15:38:07 INFO - ##### 15:38:07 INFO - ##### 15:38:07 INFO - ##### Running run-tests step. 15:38:07 INFO - ##### 15:38:07 INFO - Running pre-action listener: _resource_record_pre_action 15:38:07 INFO - Running main action method: run_tests 15:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:07 INFO - Python 2.7.3 15:38:07 INFO - Return code: 0 15:38:07 INFO - grabbing minidump binary from tooltool 15:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:07 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 0x1dd21c0>, '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 0x1dd30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dd3550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:07 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 15:38:07 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 15:38:07 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 15:38:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:07 INFO - Return code: 0 15:38:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:38:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:38:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:38:08 INFO - Return code: 0 15:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:38:08 INFO - Using env: {'DISPLAY': ':0', 15:38:08 INFO - 'HOME': '/home/cltbld', 15:38:08 INFO - 'LANG': 'en_US.UTF-8', 15:38:08 INFO - 'LANGUAGE': 'en_US:en', 15:38:08 INFO - 'LOGNAME': 'cltbld', 15:38:08 INFO - 'MAIL': '/var/mail/cltbld', 15:38:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:08 INFO - 'MOZ_NO_REMOTE': '1', 15:38:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:08 INFO - 'NO_EM_RESTART': '1', 15:38:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:08 INFO - 'PWD': '/builds/slave/test', 15:38:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:08 INFO - 'SHELL': '/bin/bash', 15:38:08 INFO - 'SHLVL': '1', 15:38:08 INFO - 'TERM': 'linux', 15:38:08 INFO - 'TMOUT': '86400', 15:38:08 INFO - 'USER': 'cltbld', 15:38:08 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094', 15:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:08 INFO - '_': '/tools/buildbot/bin/python'} 15:38:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:38:08 INFO - mozversion application_buildid: 20160607142622 15:38:08 INFO - mozversion application_changeset: 99b213f494e1a9e20629e936681faedec16d22a3 15:38:08 INFO - mozversion application_display_name: Nightly 15:38:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:08 INFO - mozversion application_name: Firefox 15:38:08 INFO - mozversion application_remotingname: firefox 15:38:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:38:08 INFO - mozversion application_vendor: Mozilla 15:38:08 INFO - mozversion application_version: 50.0a1 15:38:08 INFO - mozversion platform_buildid: 20160607142622 15:38:08 INFO - mozversion platform_changeset: 99b213f494e1a9e20629e936681faedec16d22a3 15:38:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:38:08 INFO - mozversion platform_version: 50.0a1 15:38:08 INFO - using testdate: 1465339088 15:38:08 INFO - actual date: 1465339088 15:38:08 INFO - starting webserver on 'localhost:56072' 15:38:08 INFO - SUITE-START | Running 2 tests 15:38:08 INFO - TEST-START | dromaeo_css 15:38:08 INFO - operating with platform_type : linux_ 15:38:08 INFO - Initialising browser for dromaeo_css test... 15:38:08 INFO - TEST-INFO | started process 23569 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPlFocD/profile http://localhost:56072/getInfo.html) 15:38:14 INFO - PROCESS | 23569 | __metrics Screen width/height:1600/1200 15:38:14 INFO - PROCESS | 23569 | colorDepth:24 15:38:14 INFO - PROCESS | 23569 | Browser inner width/height: 1024/697 15:38:14 INFO - PROCESS | 23569 | __metrics 15:38:15 ERROR - PROCESS | 23569 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 15:38:16 INFO - TEST-INFO | 23569: exit 0 15:38:16 INFO - Browser initialized. 15:38:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:16 INFO - TEST-INFO | started process 23687 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPlFocD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:28 INFO - PROCESS | 23687 | RSS: Main: 167940096 15:38:28 INFO - PROCESS | 23687 | 15:39:17 INFO - PROCESS | 23687 | 1465339157633 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] 15:41:29 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56072/tests/dromaeo/cssquery-ext.html) 15:41:30 INFO - PROCESS | 23687 | RSS: Main: 170647552 15:41:30 INFO - PROCESS | 23687 | 15:44:32 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-ext.html (next: http://localhost:56072/tests/dromaeo/cssquery-jquery.html) 15:44:33 INFO - PROCESS | 23687 | RSS: Main: 170254336 15:44:33 INFO - PROCESS | 23687 | 15:47:35 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56072/tests/dromaeo/cssquery-mootools.html) 15:47:36 INFO - PROCESS | 23687 | RSS: Main: 171859968 15:47:36 INFO - PROCESS | 23687 | 15:50:37 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56072/tests/dromaeo/cssquery-prototype.html) 15:50:38 INFO - PROCESS | 23687 | RSS: Main: 169111552 15:50:38 INFO - PROCESS | 23687 | 15:53:41 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56072/tests/dromaeo/cssquery-yui.html) 15:53:42 INFO - PROCESS | 23687 | RSS: Main: 170561536 15:53:42 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | Cycle 1(1): loaded http://localhost:56072/tests/dromaeo/cssquery-yui.html (next: http://localhost:56072/tests/dromaeo/cssquery-dojo.html) 15:56:44 INFO - PROCESS | 23687 | RSS: Main: 172433408 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | __start_tp_report 15:56:44 INFO - PROCESS | 23687 | _x_x_mozilla_page_load 15:56:44 INFO - PROCESS | 23687 | _x_x_mozilla_page_load_details 15:56:44 INFO - PROCESS | 23687 | |i|pagename|runs| 15:56:44 INFO - PROCESS | 23687 | |0;dojo.html;2627.6189952456893;2654.7477989590984;2663.5205662980666;2688.959665605016;2690.2332835141974;4672.509386514414;4684.6252299816015;4740.620750339973;4765.523447655232;4775.331453596499;4655.371524844145;4831.855044348676;4856;4858.854234372966;4870.780814863331;4423.0711550574415;4461.442319710036;4470.418845550074;4586;4596.03483268514;4504.4594648642205;4510.25580779171;4570.657200709943;4589.5639914208095;4631.953680463186;4373.234683930315;4409.977950110229;4416.955830441687;4446.155230506193;4446.421965144543;4436.622887054572;4442.200403927324;4450.354698568722;4487.102579484083;4505.166544189336;4309.030468144674;4369.497507786614;4405.163019026375;4406.317020861771;4461.464624245112;4649.953500464986;4704.835330763441;4711.175544279771;4724.291356296549;4740.597049250818;5160.535551800319;5185.844424667266;5188.7146206958605;5207;5238.109521381374;5139.640225184238;5224.216367544861;5225.843224703265;5231.084560201949;5249.950009998016;4892.412910450768;4895.2167653175675;4903.681260718006;4916;4949.579285760678;5366.060939335601;5388.757505912244;5388.895276468393;5392.379876314196;5404.162354835007;4934.037862616827;5052.898942021139;5071.41678706946;5107.514786095315;5141;4995;5163.199704045879;5196.246544251062;5209.843704688866;5215.6609820361555;5193.818216362409;5197.7141257231215;5199.220116982483;5200.843974680765;5218.843434696965;4875.390576177978;5013.874653133628;5051.974740126276;5064.442911279756;5170.302009228706;4364;4380.627646650007;4380.956190438119;4384.517703052662;4411.933820992687;3795.658390744846;3814.675752561008;3844.75009124406;3864.0339915021245;3871.3031654302495;4349.847755328612;4464.129494748493;4503.234450143418;4520.254158063883;4555.544445555418;5301.522862942354;5303.708296043755;5309.601779866525;5316.202569614588;5353.544948679406;4327.913441731211;4506.031203291308;4532;4539.046800171935;4577.22187228172;4682.531746825358;4703.764811759467;4711.293306004127;4718.834840780556;4726.314684370747;4860.4896485869185;4897.289892965571;4917;4919.065377578248;4948.752562371867;5034.924476132935;5172.9741351293005;5246.947530524799;5253.737313134252;5295.788168473372;4853.587445067209;4872.317875497362;4876.878078048077;4882.707037577757;4944.950550494449;5126.948730512796;5129.2819005340025;5135.38375394955;5136.8202112925865;5186.2479940407675;5014.272930425141;5058.114829904678;5069.366329208849;5210.426853046086;5218.139007063944;4888.877778055577;4913.877153071059;4943.456219815889;4994.750262486934;5026.874328141826;5005.824796132118;5006.948540806471;5025.221090730943;5035.219540971156;5060.443351231288;4663.953360466352;4754.334393185026;4935.555799978019;4944.777485013255;4958.950410495993;4920.40955085392;4981.850544483671;4990.351254337058;4992.600591952562;5042.268871013609;4936.037472692934;5008.4240312364545;5018.924716129334;5034.2196959469875;5077 15:56:44 INFO - PROCESS | 23687 | |1;ext.html;531.8057047380356;579.5769088565349;585.6456843609616;605.8333958161505;616.2912650451976;36334.454983175274;39985.200295994066;40069.799651001704;40086.997825054386;40108.39837402434;40596.79701601495;41282.38076428856;41621.58378416215;41628.58371416285;41721.374179387334;7739.961300193505;7878.960605196995;7927.3658107351275;7953.840923181533;7979.640916158787;7784.338331241851;7847.1368149503505;7899.052113746358;7922.366210703159;7928.524288542677;7845.607719613997;7903.36773058154;7917.208279172066;7932.325752311061;7939.325157361631;7837.255460731221;7855.7250496232355;7861.646225919819;7861.921380786204;7876.724314648988;7794.766157015299;7862.960685196595;7865.921340786576;7870.488418252795;7893.447458677895;37507.4373884392;37621.24757504834;37927.81036094829;37944.810275948446;38067.23865522702;2995.9850200749074;3141.6544180140168;3149.9055028349185;3166.9524957125664;3241.3679332530164;28838.134855954144;29079.727216367017;29220.561691574643;29435.06606182313;29943.401131977454;3531.099569609733;3572.4819901114192;3590.35373632746;3617.602063772983;3672.540932383452;249396;249652.75173624197;249714;250101;250274;54820.17769733457;55490;55511.44488555063;55511.444885551435;55576;35891.4616280756;36917.446238306446;37066;37089.814550927615;37091.629083708816;15532.223388830513;15548.144852033247;15623.609409764847;15625.531234062997;15626.531204063896;11822.408879555987;11885.16803823724;11913.22564033335;11918.165728399103;11949.462274197682;18757.531061723566;18818.52953676169;18821.717674234897;18882.716759248622;19022.144003519876;7002.124734408199;7078.221395646578;7194.676239569232;7204.351608355273;7282.96358518204;836.8493671139132;924.0805398628407;950.2065775077888;988.9159421449114;1002.2633364477103;28633.57049644257;29286.414271714446;29444.852775735984;29470.97900883624;29592.704072958993;1026.440589878518;1035.5650626736788;1069.5668254356997;1077.3266708307308;1091.497910960967;14798.852011479963;15300.540983770505;15388.846111538965;15544.689106217813;15566.22168891551;406764;408894;412693;413676;415131;50664.24003639948;51399.743001285504;51557.48442515602;51733;51865.2220216697;1184.838857919244;1224.6815827884636;1236.07912105482;1246.731952630189;1258.1256027061288;27505.724942750316;28419.857900710365;28490.43019139564;28604.99765016429;29094.27264318409;44305.55694443014;44465.333020005375;44496.55503445054;44590.554094459934;44724.10551789011;347832;347881;348923;349476;350586;25774.355641109123;25799.226023219333;26529.204123876316;26550.468990619695;26597.734022659526;42977.57022429736;43186.56813431951;43380.566194337654;43810;43867.56132438762;37044.629553704115;37927.24145517129;37939.24121517499;38244.80877595594;38291.425628615034;36874.63125368712;37099.44350834793;37214.255714886094;37310.06724831792;37880.24239515139;32407.675923240466;36135;36867;37101;37824;32426.027219183463;33612.66387336095;34055.148621284665;34165.829170853984;34194.316113678084;25847.22458326178;25853.353666158495;26093.347666307734;26139.35296862138;26375.20874373772 15:56:44 INFO - PROCESS | 23687 | |2;jquery.html;354.6453546453546;360.57632282068573;365.42992931027584;377.588428612812;390.58792973413057;15382.230888455575;16082.437114700988;16536.917315413437;16542.917285413587;16558.172091395427;15558.688826223497;16116.919415402936;16640.750388744178;16649.583760405996;16650.58373540659;5051.292819005343;5227.869303267411;5258.47415258473;5267.28891599633;5280.973595132038;3708.684761795251;5212.374515058198;5744.856378590548;6155.784547540837;6156.322804491502;6151.64663773969;6157.599756015845;6196.659183744892;6209.658468784215;6236.064590311467;5018.7992480300745;5323.307969963918;5326.334208223972;5397.379301380352;5408.972295263888;5069.163588007974;5201.063808514466;5395.5953303502;5405.486478784509;5422.376426710939;11150.442477876155;11174.21780475367;11281.887181128248;11309.982101610813;16758.83241167585;1698.1424380687797;1834.2479583370887;1835.0457761963837;1900.220909427142;1988.35378501986;9171.31215158859;9251.814963700755;10764.192685548614;12738;13375.26436046017;1731.9307227710876;1769.938052168168;1785.6071664233848;1822.526143202777;1828.6068495273446;69378;71176.6441167796;72785.27214727891;72811;73062.26937730607;9578.281628877723;9733.902660973441;9862.950685246527;10294.588216471257;10356.430396328124;13743.656408589864;13843.238621875893;13853.16880987144;13857.51498697561;13864.306784660726;7373.8156546086775;7469.178390377054;7529.284717951786;7551.622418879034;7559.735409260649;7147.499675022698;7189.460790440738;7241.348278654947;7295.781126566211;7413.777586672409;4241.554636763157;4259.16952622056;4290.249206388869;4352.194843953878;4377.1683380157665;1654.7352423612165;1715.3395942562204;1799.5231263715125;1820.399268241477;1839.5768973136253;823.3865770001387;875.711015364928;906.243286855483;959.712086374085;1007.3200589601989;16145.838541614434;16337.591560210853;16517;16891.91554042222;16976.57558561046;878.2008372381102;882.9072947340566;954.8329042417549;955.5890966884307;957.6456711016968;9218.58516366765;9253.120953509406;9337.953310233406;9485.33602647808;9560.808783824285;411144;413010;418089;421561;422788;63790.6810465954;73667;77063.61468192624;78164;78453;871.6339137562239;871.7471933139445;878.7056336127341;893.6425429828121;894.5974311559825;14415.711685766435;14695.92652036733;15927.124008179198;16057.11685857289;16373.263203156124;25743.613845791955;26052.609210862232;26822.865885670446;28667.713322866504;36673.266534668626;48655;48922.266166006826;49745.75127124486;49829.75085124551;49886.25170622371;7741.806454838674;7921.32668723165;7943.364530837403;7970.521768693897;8109.513429194267;8454.40819142654;8776.122387761172;11763.06550805454;12135.817962730744;12343.629691109281;5527.060399731975;5761.567882408753;10320.27758056959;10857.34855908648;10970.945145274223;10570.577176913144;10579.94200579936;10587.89412105869;10644.8935510647;10750.892491074988;10925.453727313443;10998.890011099787;11001.119910407308;11025.117990560575;11102.2783519071;7720.7683769487;7799.49303295284;7978.3218426434405;8170.346372290322;8212.753617391489;10063.496825158567;10912.890871091506;10959.287646302964;11009.449527523751;11052 15:56:44 INFO - PROCESS | 23687 | |3;mootools.html;1349.939252733627;1395.1629022586446;1478.3716920308866;1674.740415235639;1704.4204970310093;6489.772857949961;7443.404527637789;7455.589942553157;7920.168382319853;7962.084360298568;7589.127250366209;7728.07263128424;7882.645280962356;7888.526688398686;7904.446688731791;2039.5920815836819;2071.564971356017;2074.8858812765293;2078.8025137611903;2085.86441881278;2151.064287035145;2154.4183070570934;2155.8706477611395;2159.654455287154;2182.3671135370723;2152;2164.4588852786806;2165.740111186652;2177.4665207024223;2201.8899055047264;2098.0558748563053;2099.4436474334316;2102.810747032767;2112.767595564487;2112.7781582933726;2029.8274646655052;2066.059942726056;2090.5087304483436;2094.696269040996;2109.440998135495;7734.342580880632;7794.2205779422175;7798.337141342992;7843.843123137505;7848.293653571206;4244.639205667522;4327.17783621111;4356.6732495063;4364.5635436456105;4372.1692878353015;2724.891004359843;2789.4839454700946;2893.7829662775375;2934.5891575179494;2946.175070980129;891.663840737266;912.32943786317;924.366808736018;930.3255140023497;931.1061381074177;10352.275340726152;10354.17166626668;10366.533505992324;10374.585016599327;10382;4025.798710064485;4046.979765101156;4151.169766046803;4152.1280531088205;4155.10665206982;8905.866412003825;8961.775955601162;8962.103789621116;8975;8982.056884027214;4546.02260513991;4609.032103258286;4614.077184563033;4625.982283897537;4636.188666983265;3527.753057286017;3908.687305015592;4025.77858217795;4186.581341865789;4252.829886804555;3613.602503724588;3615.2769446110883;3678.4850120983083;3722.1625134344554;3733.75730577517;1122.2480937771713;1152.8097863852538;1167.6146871532374;1168.6318809574957;1175.8236264560385;852.2457625001917;860.586918279217;869.1221865915345;882.748416701237;899.6761165980242;5393.325834270716;5435.402105768362;5616.269884914935;5767.307923049261;5778.046622307273;867.6225841758729;895.9676500175954;899.3659470073583;939.2345238630494;955.5556666150273;3732.1975775207916;3758.3047136279874;3808.7333886628226;3854.730168888207;3887.747296425723;9310.441373517611;9579.473128977834;9603.471809050568;9649.324547281623;9657.420554766595;9443.811123777487;9464.668736594185;9490.478023708623;9540.522973851419;9585.089416505421;6857.27998560154;7046.330598593126;7129.073220481304;7223.1693355264815;7292.453066020176;5484.808031718871;5589.944100558942;5622.353429355513;5677.943220567907;5697.800576979786;8427.199416055464;8710.564471776259;8938;8973.237274831712;9015.323850711355;8308.750737477885;8615.396922215385;9119.680811171576;9126.17864392208;9141.26869850397;5852.502537284208;6583.3087525811015;6810.42111420515;6840.760573379908;7010.158780946319;7195.496315257882;7208.27917208275;7279.636018199175;7340.669669864976;7371.852562948604;6716.899246511404;6920.550164239308;6929.514933954776;6962.3733863950465;6992.965035174791;6862.4853136014;6863.897041544281;6888.207856096613;6924.238333783381;7040.3663670269925;5972.313183983897;6150.477209437069;6238.532110091852;6265.216847894013;6283.403076707888;4598.540145985375;4610.631149507964;4623.676342655982;4624.3757092793385;4709.929351059668;4415.823367065282;4432.4513404793015;4435.5120936697585;4441.178381999341;4477.171723231213 15:56:44 INFO - PROCESS | 23687 | |4;prototype.html;290.12093357127895;301.4092378937283;306.88645201275534;308.10802726107903;309.5526963537689;4857.684250523723;4953.504649535044;5063.1645778446555;5085.516875896793;5104.05574968631;5019.899602007967;5023.196288593826;5035.546800787928;5045.192769156935;5047.1167545679455;3931.5085614298214;3945.9013524661827;3951.6443520083185;3959.940600890989;3970.9007274818146;3849.7867305646405;3872.5740168581433;3945.9605403946025;3951.683865290784;3966.2152031585756;3851.8074096295213;3914.2367238388524;3926.195129998358;3942.7634341939433;3952;3902.1220225449188;3906.726529142961;3919.568847426781;3944.1520073184115;3957.28768821612;3532.9116772080647;3544.3620148373284;3554.306910152521;3554.6267641897743;3560.928781424357;4635.976820115911;4828.913494463717;4840.249761287007;4936.5063493650705;4962.932969411558;4407.272799987998;4431.2466880630045;4435.179491793996;4459.130469558438;4467.15124126415;5209.6874187548865;5224.373075230958;5251.054810134175;5260.2372655965055;5266.183741520071;3514.7346955096145;3703.4629978652943;3720.5163328767358;3731.794751288678;3738.2336620992633;5447.400785913546;5480.479354461319;5486.451354864522;5495.697736624484;5500.28496295479;3807.600201978808;3821.7706937583835;3827.004978705557;3836.2519308734527;3838.098046958962;3816.6183381662167;3839.232153569297;3839.865604703822;3847.4228865670375;3853.6339047790416;4172.165566886574;4389.956100439019;4412.624926881187;4427.247367947457;4431.667624928144;5239.371275446971;5309.814156504503;5329.067413202752;5367.78528858849;5385.0037743017665;1258.1004581724112;1304.0480449272084;1319.6107148391247;1330.767115754739;1335.7929520924272;839.8992120945526;851.3997631669658;854.6410507586828;859.1623167411849;865.731623196811;4129.628333450004;4136.565660605593;4137.02134463186;4147.605977432139;4161.687873409446;4811.511654050855;4819.048285444401;4837.927431088537;4889.48660390661;4895.559399654048;4180.832766689298;4214.620684138442;4218.535961044283;4221.176870510221;4223.387608796707;2502.4869901670113;2519.6472493850874;2528.5081801737065;2585.4441295121637;2596.870156492168;4508.594226519629;4583.541645835457;4598.172328980749;4616.984263462033;4634.559716826896;4777.829431789164;4875.975620121877;4913.77887995034;4971.552560269521;4992.475790042066;4596.724196548218;4761.738104404291;5159.535641792257;5161.380634323905;5168.7415629218385;5471.890562188813;5482.972585137049;5486.478784515464;5491.341039075336;5496.340439147328;5238.449962753856;5415.241866138665;5440.591955603267;5458.781648734165;5472.261244731911;5119.846404607868;5162.380514338303;5164.1995490699;5168.689878607295;5190.714510701948;3765.378712512405;3799.1072098056384;3810.971037819829;3818.6706109272764;3825.1775868188474;4947.975260123821;5497.697626630574;5504.697241651747;5511.4213007633625;5515.676237703003;5213.582913366998;5267.209918512254;5294.549963253166;5302.681839089667;5325.414204437431;5275.630705850554;5287.074761916726;5300.416954134965;5344.9732751336;5349;5069.011542749128;5174.922376164436;5187.507186817246;5217;5220.546343218681;5188.610854185876;5289.915567308615;5308.761105750174;5316.30887984575;5326.467353264798;5151.192600033066;5160.303359046481;5213.869653258699;5231.895362092813;5244.344456942882 15:56:44 INFO - PROCESS | 23687 | |5;yui.html;661.8213082467731;671.1878626861497;681.0261326303385;722.2885457824044;749.5727435361845;10697.144228461724;12710.936445317771;12838.229706217633;13305.401256943425;13360.265185414799;13362.265075420848;13488.932555337235;13520.729585408286;13565.25391103489;13576.592702218924;633.3128555517246;788.5229436191108;789.5144486141011;799.0571126071234;799.256691277112;807.6002378822491;811.1280373598375;812.3217113710059;812.6627449608405;813.9023317201944;852.4970267542149;858.8969323681167;860.1312674199041;860.5482121885997;861.8104017116225;840.4789030800912;843.3253397282181;844.8690452979798;845.0704225352125;855.5337341149027;621.8942779727457;622.4304761143549;623.6725718997517;624.4286477872741;624.665803794972;13305.135166214262;13378.732425351536;13494.325283735774;13547.119437236548;13553;12527.812082818766;12643.258777958266;13051.804222936664;13145.474181032843;13688.863111368859;10111.89888101117;10233.74415639615;10300.587976480932;10301.896981030168;10319.690409287658;3335.232896433834;3359.160209947513;3377;3380.290139070798;3493.0219538529254;64381.356186438476;64572;65145.719899803276;65160;65360.67319663419;10439.739006524745;10722.41026743521;10809;10904.127669786398;11377.431128443544;20873.68689469659;20901.790982089984;20939.79060209409;20939.895300523705;20967.790322096887;6591.176102987127;6723.966380168068;6735.461163106939;6757.93242067573;6871.278515755874;3328.8002719836886;3336.7997920124385;3339.1819004344093;3478.32172726321;3530.3115892401247;3033.1810411188853;3175.063356309894;3181.5227715842348;3187.3306605612615;3188.1870123118456;461.15838594565054;470.96938698984457;475.41049099116844;487.45892059813576;488.8142505847826;458.84628649402157;498.5986281043722;577.7255803493391;583.4020129367348;593.44809327326;7504.812379690442;7637.656305466267;7747.30274275318;7873.960630196812;8007.639656215484;450.36498537062585;451.29597827389375;578.3406916115631;596.4035964035965;625.4183609243447;3579.964200358015;3583.8208089595414;3606.8016259105475;3630.5098811660614;3644.6879123515514;77447;96033.51983240039;97819.51090244504;98123;99253;52723.47276527262;62430;79729;81366.59316703379;88135.55932220299;57445;74369.6281518589;76646;76977.61511192408;77978.61010695023;7093.964530177316;7171.928280717126;7216.206217315986;7240.746573869889;7410.9258907412395;44168.33747493855;44860.32709509294;45938.540614593425;46139.76930115462;46361.76819115883;56484.435155647916;62023;62395.37604624077;63292.68353658386;63507.36492635014;10474.738131546772;10485.475726213506;10770.623028193975;10829.51267192994;11061.778764424827;10462.424566648908;10504.632337868137;10556.788864222519;11694.415279236175;11747.530098795953;11301.491432885705;11326.263792853439;11471;11499.195056345976;11661.183717139718;11712.414379280832;12143.453544590691;12146.696332591757;12156.696082598006;12335.50657973671;10069.446180459852;11508.712282192677;11532.769344613229;11614.419279036183;11730;9405.482698451651;9519.809603808024;9596.424214547149;9953.104220620178;10526.473676316307;9520.428774273565;10125.696229113139;10211.897881021094;10226.59093636276;10259.230557708352 15:56:44 INFO - PROCESS | 23687 | __end_tp_report 15:56:44 INFO - PROCESS | 23687 | __start_cc_report 15:56:44 INFO - PROCESS | 23687 | _x_x_mozilla_cycle_collect,387 15:56:44 INFO - PROCESS | 23687 | __end_cc_report 15:56:44 INFO - PROCESS | 23687 | __startTimestamp1465340204941__endTimestamp 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | ------- Summary: start ------- 15:56:44 INFO - PROCESS | 23687 | Number of tests: 6 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#0] dojo.html Cycles:165 Average:4779.62 Median:4903.68 stddev:507.47 (10.3%) stddev-sans-first:480.13 15:56:44 INFO - PROCESS | 23687 | Values: 2627.6 2654.7 2663.5 2689.0 2690.2 4672.5 4684.6 4740.6 4765.5 4775.3 4655.4 4831.9 4856.0 4858.9 4870.8 4423.1 4461.4 4470.4 4586.0 4596.0 4504.5 4510.3 4570.7 4589.6 4632.0 4373.2 4410.0 4417.0 4446.2 4446.4 4436.6 4442.2 4450.4 4487.1 4505.2 4309.0 4369.5 4405.2 4406.3 4461.5 4650.0 4704.8 4711.2 4724.3 4740.6 5160.5 5185.8 5188.7 5207.0 5238.1 5139.6 5224.2 5225.8 5231.1 5250.0 4892.4 4895.2 4903.7 4916.0 4949.6 5366.1 5388.8 5388.9 5392.4 5404.2 4934.0 5052.9 5071.4 5107.5 5141.0 4995.0 5163.2 5196.2 5209.8 5215.7 5193.8 5197.7 5199.2 5200.8 5218.8 4875.4 5013.9 5052.0 5064.4 5170.3 4364.0 4380.6 4381.0 4384.5 4411.9 3795.7 3814.7 3844.8 3864.0 3871.3 4349.8 4464.1 4503.2 4520.3 4555.5 5301.5 5303.7 5309.6 5316.2 5353.5 4327.9 4506.0 4532.0 4539.0 4577.2 4682.5 4703.8 4711.3 4718.8 4726.3 4860.5 4897.3 4917.0 4919.1 4948.8 5034.9 5173.0 5246.9 5253.7 5295.8 4853.6 4872.3 4876.9 4882.7 4945.0 5126.9 5129.3 5135.4 5136.8 5186.2 5014.3 5058.1 5069.4 5210.4 5218.1 4888.9 4913.9 4943.5 4994.8 5026.9 5005.8 5006.9 5025.2 5035.2 5060.4 4664.0 4754.3 4935.6 4944.8 4959.0 4920.4 4981.9 4990.4 4992.6 5042.3 4936.0 5008.4 5018.9 5034.2 5077.0 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#1] ext.html Cycles:180 Average:48980.91 Median:37090.72 stddev:90504.62 (244.0%) stddev-sans-first:90685.41 15:56:44 INFO - PROCESS | 23687 | Values: 531.8 579.6 585.6 605.8 616.3 36334.5 39985.2 40069.8 40087.0 40108.4 40596.8 41282.4 41621.6 41628.6 41721.4 7740.0 7879.0 7927.4 7953.8 7979.6 7784.3 7847.1 7899.1 7922.4 7928.5 7845.6 7903.4 7917.2 7932.3 7939.3 7837.3 7855.7 7861.6 7861.9 7876.7 7794.8 7863.0 7865.9 7870.5 7893.4 37507.4 37621.2 37927.8 37944.8 38067.2 2996.0 3141.7 3149.9 3167.0 3241.4 28838.1 29079.7 29220.6 29435.1 29943.4 3531.1 3572.5 3590.4 3617.6 3672.5 249396.0 249652.8 249714.0 250101.0 250274.0 54820.2 55490.0 55511.4 55511.4 55576.0 35891.5 36917.4 37066.0 37089.8 37091.6 15532.2 15548.1 15623.6 15625.5 15626.5 11822.4 11885.2 11913.2 11918.2 11949.5 18757.5 18818.5 18821.7 18882.7 19022.1 7002.1 7078.2 7194.7 7204.4 7283.0 836.8 924.1 950.2 988.9 1002.3 28633.6 29286.4 29444.9 29471.0 29592.7 1026.4 1035.6 1069.6 1077.3 1091.5 14798.9 15300.5 15388.8 15544.7 15566.2 406764.0 408894.0 412693.0 413676.0 415131.0 50664.2 51399.7 51557.5 51733.0 51865.2 1184.8 1224.7 1236.1 1246.7 1258.1 27505.7 28419.9 28490.4 28605.0 29094.3 44305.6 44465.3 44496.6 44590.6 44724.1 347832.0 347881.0 348923.0 349476.0 350586.0 25774.4 25799.2 26529.2 26550.5 26597.7 42977.6 43186.6 43380.6 43810.0 43867.6 37044.6 37927.2 37939.2 38244.8 38291.4 36874.6 37099.4 37214.3 37310.1 37880.2 32407.7 36135.0 36867.0 37101.0 37824.0 32426.0 33612.7 34055.1 34165.8 34194.3 25847.2 25853.4 26093.3 26139.4 26375.2 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#2] jquery.html Cycles:180 Average:24595.79 Median:4321.22 stddev:68719.73 (1590.3%) stddev-sans-first:68888.40 15:56:44 INFO - PROCESS | 23687 | Values: 354.6 360.6 365.4 377.6 390.6 15382.2 16082.4 16536.9 16542.9 16558.2 15558.7 16116.9 16640.8 16649.6 16650.6 5051.3 5227.9 5258.5 5267.3 5281.0 3708.7 5212.4 5744.9 6155.8 6156.3 6151.6 6157.6 6196.7 6209.7 6236.1 5018.8 5323.3 5326.3 5397.4 5409.0 5069.2 5201.1 5395.6 5405.5 5422.4 11150.4 11174.2 11281.9 11310.0 16758.8 1698.1 1834.2 1835.0 1900.2 1988.4 9171.3 9251.8 10764.2 12738.0 13375.3 1731.9 1769.9 1785.6 1822.5 1828.6 69378.0 71176.6 72785.3 72811.0 73062.3 9578.3 9733.9 9863.0 10294.6 10356.4 13743.7 13843.2 13853.2 13857.5 13864.3 7373.8 7469.2 7529.3 7551.6 7559.7 7147.5 7189.5 7241.3 7295.8 7413.8 4241.6 4259.2 4290.2 4352.2 4377.2 1654.7 1715.3 1799.5 1820.4 1839.6 823.4 875.7 906.2 959.7 1007.3 16145.8 16337.6 16517.0 16891.9 16976.6 878.2 882.9 954.8 955.6 957.6 9218.6 9253.1 9338.0 9485.3 9560.8 411144.0 413010.0 418089.0 421561.0 422788.0 63790.7 73667.0 77063.6 78164.0 78453.0 871.6 871.7 878.7 893.6 894.6 14415.7 14695.9 15927.1 16057.1 16373.3 25743.6 26052.6 26822.9 28667.7 36673.3 48655.0 48922.3 49745.8 49829.8 49886.3 7741.8 7921.3 7943.4 7970.5 8109.5 8454.4 8776.1 11763.1 12135.8 12343.6 5527.1 5761.6 10320.3 10857.3 10970.9 10570.6 10579.9 10587.9 10644.9 10750.9 10925.5 10998.9 11001.1 11025.1 11102.3 7720.8 7799.5 7978.3 8170.3 8212.8 10063.5 10912.9 10959.3 11009.4 11052.0 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#3] mootools.html Cycles:180 Average:5104.12 Median:5650.15 stddev:2838.12 (50.2%) stddev-sans-first:2832.06 15:56:44 INFO - PROCESS | 23687 | Values: 1349.9 1395.2 1478.4 1674.7 1704.4 6489.8 7443.4 7455.6 7920.2 7962.1 7589.1 7728.1 7882.6 7888.5 7904.4 2039.6 2071.6 2074.9 2078.8 2085.9 2151.1 2154.4 2155.9 2159.7 2182.4 2152.0 2164.5 2165.7 2177.5 2201.9 2098.1 2099.4 2102.8 2112.8 2112.8 2029.8 2066.1 2090.5 2094.7 2109.4 7734.3 7794.2 7798.3 7843.8 7848.3 4244.6 4327.2 4356.7 4364.6 4372.2 2724.9 2789.5 2893.8 2934.6 2946.2 891.7 912.3 924.4 930.3 931.1 10352.3 10354.2 10366.5 10374.6 10382.0 4025.8 4047.0 4151.2 4152.1 4155.1 8905.9 8961.8 8962.1 8975.0 8982.1 4546.0 4609.0 4614.1 4626.0 4636.2 3527.8 3908.7 4025.8 4186.6 4252.8 3613.6 3615.3 3678.5 3722.2 3733.8 1122.2 1152.8 1167.6 1168.6 1175.8 852.2 860.6 869.1 882.7 899.7 5393.3 5435.4 5616.3 5767.3 5778.0 867.6 896.0 899.4 939.2 955.6 3732.2 3758.3 3808.7 3854.7 3887.7 9310.4 9579.5 9603.5 9649.3 9657.4 9443.8 9464.7 9490.5 9540.5 9585.1 6857.3 7046.3 7129.1 7223.2 7292.5 5484.8 5589.9 5622.4 5677.9 5697.8 8427.2 8710.6 8938.0 8973.2 9015.3 8308.8 8615.4 9119.7 9126.2 9141.3 5852.5 6583.3 6810.4 6840.8 7010.2 7195.5 7208.3 7279.6 7340.7 7371.9 6716.9 6920.6 6929.5 6962.4 6993.0 6862.5 6863.9 6888.2 6924.2 7040.4 5972.3 6150.5 6238.5 6265.2 6283.4 4598.5 4610.6 4623.7 4624.4 4709.9 4415.8 4432.5 4435.5 4441.2 4477.2 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#4] prototype.html Cycles:180 Average:4292.80 Median:4823.98 stddev:1264.78 (26.2%) stddev-sans-first:1232.13 15:56:44 INFO - PROCESS | 23687 | Values: 290.1 301.4 306.9 308.1 309.6 4857.7 4953.5 5063.2 5085.5 5104.1 5019.9 5023.2 5035.5 5045.2 5047.1 3931.5 3945.9 3951.6 3959.9 3970.9 3849.8 3872.6 3946.0 3951.7 3966.2 3851.8 3914.2 3926.2 3942.8 3952.0 3902.1 3906.7 3919.6 3944.2 3957.3 3532.9 3544.4 3554.3 3554.6 3560.9 4636.0 4828.9 4840.2 4936.5 4962.9 4407.3 4431.2 4435.2 4459.1 4467.2 5209.7 5224.4 5251.1 5260.2 5266.2 3514.7 3703.5 3720.5 3731.8 3738.2 5447.4 5480.5 5486.5 5495.7 5500.3 3807.6 3821.8 3827.0 3836.3 3838.1 3816.6 3839.2 3839.9 3847.4 3853.6 4172.2 4390.0 4412.6 4427.2 4431.7 5239.4 5309.8 5329.1 5367.8 5385.0 1258.1 1304.0 1319.6 1330.8 1335.8 839.9 851.4 854.6 859.2 865.7 4129.6 4136.6 4137.0 4147.6 4161.7 4811.5 4819.0 4837.9 4889.5 4895.6 4180.8 4214.6 4218.5 4221.2 4223.4 2502.5 2519.6 2528.5 2585.4 2596.9 4508.6 4583.5 4598.2 4617.0 4634.6 4777.8 4876.0 4913.8 4971.6 4992.5 4596.7 4761.7 5159.5 5161.4 5168.7 5471.9 5483.0 5486.5 5491.3 5496.3 5238.4 5415.2 5440.6 5458.8 5472.3 5119.8 5162.4 5164.2 5168.7 5190.7 3765.4 3799.1 3811.0 3818.7 3825.2 4948.0 5497.7 5504.7 5511.4 5515.7 5213.6 5267.2 5294.5 5302.7 5325.4 5275.6 5287.1 5300.4 5345.0 5349.0 5069.0 5174.9 5187.5 5217.0 5220.5 5188.6 5289.9 5308.8 5316.3 5326.5 5151.2 5160.3 5213.9 5231.9 5244.3 15:56:44 INFO - PROCESS | 23687 | 15:56:44 INFO - PROCESS | 23687 | [#5] yui.html Cycles:180 Average:17324.78 Median:23199.85 stddev:24423.68 (105.3%) stddev-sans-first:24460.15 15:56:44 INFO - PROCESS | 23687 | Values: 661.8 671.2 681.0 722.3 749.6 10697.1 12710.9 12838.2 13305.4 13360.3 13362.3 13488.9 13520.7 13565.3 13576.6 633.3 788.5 789.5 799.1 799.3 807.6 811.1 812.3 812.7 813.9 852.5 858.9 860.1 860.5 861.8 840.5 843.3 844.9 845.1 855.5 621.9 622.4 623.7 624.4 624.7 13305.1 13378.7 13494.3 13547.1 13553.0 12527.8 12643.3 13051.8 13145.5 13688.9 10111.9 10233.7 10300.6 10301.9 10319.7 3335.2 3359.2 3377.0 3380.3 3493.0 64381.4 64572.0 65145.7 65160.0 65360.7 10439.7 10722.4 10809.0 10904.1 11377.4 20873.7 20901.8 20939.8 20939.9 20967.8 6591.2 6724.0 6735.5 6757.9 6871.3 3328.8 3336.8 3339.2 3478.3 3530.3 3033.2 3175.1 3181.5 3187.3 3188.2 461.2 471.0 475.4 487.5 488.8 458.8 498.6 577.7 583.4 593.4 7504.8 7637.7 7747.3 7874.0 8007.6 450.4 451.3 578.3 596.4 625.4 3580.0 3583.8 3606.8 3630.5 3644.7 77447.0 96033.5 97819.5 98123.0 99253.0 52723.5 62430.0 79729.0 81366.6 88135.6 57445.0 74369.6 76646.0 76977.6 77978.6 7094.0 7171.9 7216.2 7240.7 7410.9 44168.3 44860.3 45938.5 46139.8 46361.8 56484.4 62023.0 62395.4 63292.7 63507.4 10474.7 10485.5 10770.6 10829.5 11061.8 10462.4 10504.6 10556.8 11694.4 11747.5 11301.5 11326.3 11471.0 11499.2 11661.2 11712.4 12143.5 12146.7 12156.7 12335.5 10069.4 11508.7 11532.8 11614.4 11730.0 9405.5 9519.8 9596.4 9953.1 10526.5 9520.4 10125.7 10211.9 10226.6 10259.2 15:56:44 INFO - PROCESS | 23687 | -------- Summary: end -------- 15:56:44 INFO - PROCESS | 23687 | 15:56:45 INFO - TEST-INFO | 23687: exit 0 15:56:45 INFO - TEST-OK | dromaeo_css | took 1116926ms 15:56:45 INFO - TEST-START | kraken 15:56:45 INFO - operating with platform_type : linux_ 15:56:45 INFO - Initialising browser for kraken test... 15:56:45 INFO - TEST-INFO | started process 23920 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfiGs5D/profile http://localhost:56072/getInfo.html) 15:56:51 INFO - PROCESS | 23920 | __metrics Screen width/height:1600/1200 15:56:51 INFO - PROCESS | 23920 | colorDepth:24 15:56:51 INFO - PROCESS | 23920 | Browser inner width/height: 1024/697 15:56:51 INFO - PROCESS | 23920 | __metrics 15:56:51 ERROR - PROCESS | 23920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 15:56:52 INFO - TEST-INFO | 23920: exit 0 15:56:52 INFO - Browser initialized. 15:56:52 INFO - Running cycle 1/1 for kraken test... 15:56:52 INFO - TEST-INFO | started process 24039 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfiGs5D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:04 INFO - PROCESS | 24039 | RSS: Main: 168079360 15:57:04 INFO - PROCESS | 24039 | 15:57:07 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56072/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:08 INFO - PROCESS | 24039 | RSS: Main: 172285952 15:57:08 INFO - PROCESS | 24039 | 15:57:11 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56072/tests/kraken/driver.html?testName=audio-dft) 15:57:11 INFO - PROCESS | 24039 | RSS: Main: 172183552 15:57:11 INFO - PROCESS | 24039 | 15:57:14 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56072/tests/kraken/driver.html?testName=audio-fft) 15:57:15 INFO - PROCESS | 24039 | RSS: Main: 167682048 15:57:15 INFO - PROCESS | 24039 | 15:57:17 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56072/tests/kraken/driver.html?testName=audio-oscillator) 15:57:18 INFO - PROCESS | 24039 | RSS: Main: 168284160 15:57:18 INFO - PROCESS | 24039 | 15:57:20 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56072/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:21 INFO - PROCESS | 24039 | RSS: Main: 168222720 15:57:21 INFO - PROCESS | 24039 | 15:57:24 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56072/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:24 INFO - PROCESS | 24039 | RSS: Main: 168611840 15:57:24 INFO - PROCESS | 24039 | 15:57:28 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56072/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:29 INFO - PROCESS | 24039 | RSS: Main: 166297600 15:57:29 INFO - PROCESS | 24039 | 15:57:31 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56072/tests/kraken/driver.html?testName=json-parse-financial) 15:57:32 INFO - PROCESS | 24039 | RSS: Main: 167985152 15:57:32 INFO - PROCESS | 24039 | 15:57:34 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56072/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:34 INFO - PROCESS | 24039 | RSS: Main: 168013824 15:57:34 INFO - PROCESS | 24039 | 15:57:36 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:37 INFO - PROCESS | 24039 | RSS: Main: 167976960 15:57:37 INFO - PROCESS | 24039 | 15:57:39 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:40 INFO - PROCESS | 24039 | RSS: Main: 168501248 15:57:40 INFO - PROCESS | 24039 | 15:57:43 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:57:43 INFO - PROCESS | 24039 | RSS: Main: 168828928 15:57:43 INFO - PROCESS | 24039 | 15:57:46 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:57:47 INFO - PROCESS | 24039 | RSS: Main: 168529920 15:57:47 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | Cycle 1(1): loaded http://localhost:56072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56072/tests/kraken/driver.html?testName=ai-astar) 15:57:49 INFO - PROCESS | 24039 | RSS: Main: 169517056 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | __start_tp_report 15:57:49 INFO - PROCESS | 24039 | _x_x_mozilla_page_load 15:57:49 INFO - PROCESS | 24039 | _x_x_mozilla_page_load_details 15:57:49 INFO - PROCESS | 24039 | |i|pagename|runs| 15:57:49 INFO - PROCESS | 24039 | |0;ai-astar;115;111;115;113;116;116;116;114;114;113 15:57:49 INFO - PROCESS | 24039 | |1;audio-beat-detection;166;136;159;199;133;161;164;132;191;145 15:57:49 INFO - PROCESS | 24039 | |2;audio-dft;162;165;165;169;168;164;162;163;163;162 15:57:49 INFO - PROCESS | 24039 | |3;audio-fft;76;78;77;157;77;76;76;77;176;81 15:57:49 INFO - PROCESS | 24039 | |4;audio-oscillator;91;86;137;87;86;88;141;87;86;133 15:57:49 INFO - PROCESS | 24039 | |5;imaging-gaussian-blur;120;117;122;117;118;118;117;117;121;118 15:57:49 INFO - PROCESS | 24039 | |6;imaging-darkroom;148;147;147;147;146;147;148;147;147;147 15:57:49 INFO - PROCESS | 24039 | |7;imaging-desaturate;95;95;96;96;95;95;96;95;96;95 15:57:49 INFO - PROCESS | 24039 | |8;json-parse-financial;62;63;63;63;62;62;64;63;61;63 15:57:49 INFO - PROCESS | 24039 | |9;json-stringify-tinderbox;65;66;67;66;72;65;66;66;65;66 15:57:49 INFO - PROCESS | 24039 | |10;stanford-crypto-aes;84;84;84;86;87;88;85;87;112;92 15:57:49 INFO - PROCESS | 24039 | |11;stanford-crypto-ccm;138;131;137;129;140;140;132;139;140;132 15:57:49 INFO - PROCESS | 24039 | |12;stanford-crypto-pbkdf2;182;175;174;179;170;168;174;171;169;167 15:57:49 INFO - PROCESS | 24039 | |13;stanford-crypto-sha256-iterative;72;68;70;69;68;69;67;72;69;67 15:57:49 INFO - PROCESS | 24039 | __end_tp_report 15:57:49 INFO - PROCESS | 24039 | __start_cc_report 15:57:49 INFO - PROCESS | 24039 | _x_x_mozilla_cycle_collect,969 15:57:49 INFO - PROCESS | 24039 | __end_cc_report 15:57:49 INFO - PROCESS | 24039 | __startTimestamp1465340269364__endTimestamp 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | ------- Summary: start ------- 15:57:49 INFO - PROCESS | 24039 | Number of tests: 14 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:1.64 (1.4%) stddev-sans-first:1.72 15:57:49 INFO - PROCESS | 24039 | Values: 115.0 111.0 115.0 113.0 116.0 116.0 116.0 114.0 114.0 113.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#1] audio-beat-detection Cycles:10 Average:158.60 Median:162.50 stddev:23.17 (14.3%) stddev-sans-first:24.42 15:57:49 INFO - PROCESS | 24039 | Values: 166.0 136.0 159.0 199.0 133.0 161.0 164.0 132.0 191.0 145.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.50 stddev:2.50 (1.5%) stddev-sans-first:2.51 15:57:49 INFO - PROCESS | 24039 | Values: 162.0 165.0 165.0 169.0 168.0 164.0 162.0 163.0 163.0 162.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#3] audio-fft Cycles:10 Average:95.10 Median:77.00 stddev:37.93 (49.3%) stddev-sans-first:39.59 15:57:49 INFO - PROCESS | 24039 | Values: 76.0 78.0 77.0 157.0 77.0 76.0 76.0 77.0 176.0 81.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:86.50 stddev:24.13 (27.9%) stddev-sans-first:25.25 15:57:49 INFO - PROCESS | 24039 | Values: 91.0 86.0 137.0 87.0 86.0 88.0 141.0 87.0 86.0 133.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.00 stddev:1.84 (1.6%) stddev-sans-first:1.87 15:57:49 INFO - PROCESS | 24039 | Values: 120.0 117.0 122.0 117.0 118.0 118.0 117.0 117.0 121.0 118.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 15:57:49 INFO - PROCESS | 24039 | Values: 148.0 147.0 147.0 147.0 146.0 147.0 148.0 147.0 147.0 147.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:57:49 INFO - PROCESS | 24039 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 15:57:49 INFO - PROCESS | 24039 | Values: 62.0 63.0 63.0 63.0 62.0 62.0 64.0 63.0 61.0 63.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:2.07 (3.1%) stddev-sans-first:2.13 15:57:49 INFO - PROCESS | 24039 | Values: 65.0 66.0 67.0 66.0 72.0 65.0 66.0 66.0 65.0 66.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:86.50 stddev:8.48 (9.8%) stddev-sans-first:8.80 15:57:49 INFO - PROCESS | 24039 | Values: 84.0 84.0 84.0 86.0 87.0 88.0 85.0 87.0 112.0 92.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#11] stanford-crypto-ccm Cycles:10 Average:135.80 Median:138.50 stddev:4.32 (3.1%) stddev-sans-first:4.50 15:57:49 INFO - PROCESS | 24039 | Values: 138.0 131.0 137.0 129.0 140.0 140.0 132.0 139.0 140.0 132.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:174.00 stddev:4.86 (2.8%) stddev-sans-first:3.89 15:57:49 INFO - PROCESS | 24039 | Values: 182.0 175.0 174.0 179.0 170.0 168.0 174.0 171.0 169.0 167.0 15:57:49 INFO - PROCESS | 24039 | 15:57:49 INFO - PROCESS | 24039 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.79 (2.6%) stddev-sans-first:1.56 15:57:49 INFO - PROCESS | 24039 | Values: 72.0 68.0 70.0 69.0 68.0 69.0 67.0 72.0 69.0 67.0 15:57:49 INFO - PROCESS | 24039 | -------- Summary: end -------- 15:57:49 INFO - PROCESS | 24039 | 15:57:50 INFO - TEST-INFO | 24039: exit 0 15:57:50 INFO - TEST-OK | kraken | took 65011ms 15:57:50 INFO - SUITE-END | took 1181s 15:57:51 INFO - Completed test suite (00:19:42) 15:57:51 INFO - javascript benchmark 15:57:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6440.235631697511, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2627.6189952456893, 2654.7477989590984, 2663.5205662980666, 2688.959665605016, 2690.2332835141974, 4672.509386514414, 4684.6252299816015, 4740.620750339973, 4765.523447655232, 4775.331453596499, 4655.371524844145, 4831.855044348676, 4856.0, 4858.854234372966, 4870.780814863331, 4423.0711550574415, 4461.442319710036, 4470.418845550074, 4586.0, 4596.03483268514, 4504.4594648642205, 4510.25580779171, 4570.657200709943, 4589.5639914208095, 4631.953680463186, 4373.234683930315, 4409.977950110229, 4416.955830441687, 4446.155230506193, 4446.421965144543, 4436.622887054572, 4442.200403927324, 4450.354698568722, 4487.102579484083, 4505.166544189336, 4309.030468144674, 4369.497507786614, 4405.163019026375, 4406.317020861771, 4461.464624245112, 4649.953500464986, 4704.835330763441, 4711.175544279771, 4724.291356296549, 4740.597049250818, 5160.535551800319, 5185.844424667266, 5188.7146206958605, 5207.0, 5238.109521381374, 5139.640225184238, 5224.216367544861, 5225.843224703265, 5231.084560201949, 5249.950009998016, 4892.412910450768, 4895.2167653175675, 4903.681260718006, 4916.0, 4949.579285760678, 5366.060939335601, 5388.757505912244, 5388.895276468393, 5392.379876314196, 5404.162354835007, 4934.037862616827, 5052.898942021139, 5071.41678706946, 5107.514786095315, 5141.0, 4995.0, 5163.199704045879, 5196.246544251062, 5209.843704688866, 5215.6609820361555, 5193.818216362409, 5197.7141257231215, 5199.220116982483, 5200.843974680765, 5218.843434696965, 4875.390576177978, 5013.874653133628, 5051.974740126276, 5064.442911279756, 5170.302009228706, 4364.0, 4380.627646650007, 4380.956190438119, 4384.517703052662, 4411.933820992687, 3795.658390744846, 3814.675752561008, 3844.75009124406, 3864.0339915021245, 3871.3031654302495, 4349.847755328612, 4464.129494748493, 4503.234450143418, 4520.254158063883, 4555.544445555418, 5301.522862942354, 5303.708296043755, 5309.601779866525, 5316.202569614588, 5353.544948679406, 4327.913441731211, 4506.031203291308, 4532.0, 4539.046800171935, 4577.22187228172, 4682.531746825358, 4703.764811759467, 4711.293306004127, 4718.834840780556, 4726.314684370747, 4860.4896485869185, 4897.289892965571, 4917.0, 4919.065377578248, 4948.752562371867, 5034.924476132935, 5172.9741351293005, 5246.947530524799, 5253.737313134252, 5295.788168473372, 4853.587445067209, 4872.317875497362, 4876.878078048077, 4882.707037577757, 4944.950550494449, 5126.948730512796, 5129.2819005340025, 5135.38375394955, 5136.8202112925865, 5186.2479940407675, 5014.272930425141, 5058.114829904678, 5069.366329208849, 5210.426853046086, 5218.139007063944, 4888.877778055577, 4913.877153071059, 4943.456219815889, 4994.750262486934, 5026.874328141826, 5005.824796132118, 5006.948540806471, 5025.221090730943, 5035.219540971156, 5060.443351231288, 4663.953360466352, 4754.334393185026, 4935.555799978019, 4944.777485013255, 4958.950410495993, 4920.40955085392, 4981.850544483671, 4990.351254337058, 4992.600591952562, 5042.268871013609, 4936.037472692934, 5008.4240312364545, 5018.924716129334, 5034.2196959469875, 5077.0], "value": 4746.730052816983, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.8057047380356, 579.5769088565349, 585.6456843609616, 605.8333958161505, 616.2912650451976, 36334.454983175274, 39985.200295994066, 40069.799651001704, 40086.997825054386, 40108.39837402434, 40596.79701601495, 41282.38076428856, 41621.58378416215, 41628.58371416285, 41721.374179387334, 7739.961300193505, 7878.960605196995, 7927.3658107351275, 7953.840923181533, 7979.640916158787, 7784.338331241851, 7847.1368149503505, 7899.052113746358, 7922.366210703159, 7928.524288542677, 7845.607719613997, 7903.36773058154, 7917.208279172066, 7932.325752311061, 7939.325157361631, 7837.255460731221, 7855.7250496232355, 7861.646225919819, 7861.921380786204, 7876.724314648988, 7794.766157015299, 7862.960685196595, 7865.921340786576, 7870.488418252795, 7893.447458677895, 37507.4373884392, 37621.24757504834, 37927.81036094829, 37944.810275948446, 38067.23865522702, 2995.9850200749074, 3141.6544180140168, 3149.9055028349185, 3166.9524957125664, 3241.3679332530164, 28838.134855954144, 29079.727216367017, 29220.561691574643, 29435.06606182313, 29943.401131977454, 3531.099569609733, 3572.4819901114192, 3590.35373632746, 3617.602063772983, 3672.540932383452, 249396.0, 249652.75173624197, 249714.0, 250101.0, 250274.0, 54820.17769733457, 55490.0, 55511.44488555063, 55511.444885551435, 55576.0, 35891.4616280756, 36917.446238306446, 37066.0, 37089.814550927615, 37091.629083708816, 15532.223388830513, 15548.144852033247, 15623.609409764847, 15625.531234062997, 15626.531204063896, 11822.408879555987, 11885.16803823724, 11913.22564033335, 11918.165728399103, 11949.462274197682, 18757.531061723566, 18818.52953676169, 18821.717674234897, 18882.716759248622, 19022.144003519876, 7002.124734408199, 7078.221395646578, 7194.676239569232, 7204.351608355273, 7282.96358518204, 836.8493671139132, 924.0805398628407, 950.2065775077888, 988.9159421449114, 1002.2633364477103, 28633.57049644257, 29286.414271714446, 29444.852775735984, 29470.97900883624, 29592.704072958993, 1026.440589878518, 1035.5650626736788, 1069.5668254356997, 1077.3266708307308, 1091.497910960967, 14798.852011479963, 15300.540983770505, 15388.846111538965, 15544.689106217813, 15566.22168891551, 406764.0, 408894.0, 412693.0, 413676.0, 415131.0, 50664.24003639948, 51399.743001285504, 51557.48442515602, 51733.0, 51865.2220216697, 1184.838857919244, 1224.6815827884636, 1236.07912105482, 1246.731952630189, 1258.1256027061288, 27505.724942750316, 28419.857900710365, 28490.43019139564, 28604.99765016429, 29094.27264318409, 44305.55694443014, 44465.333020005375, 44496.55503445054, 44590.554094459934, 44724.10551789011, 347832.0, 347881.0, 348923.0, 349476.0, 350586.0, 25774.355641109123, 25799.226023219333, 26529.204123876316, 26550.468990619695, 26597.734022659526, 42977.57022429736, 43186.56813431951, 43380.566194337654, 43810.0, 43867.56132438762, 37044.629553704115, 37927.24145517129, 37939.24121517499, 38244.80877595594, 38291.425628615034, 36874.63125368712, 37099.44350834793, 37214.255714886094, 37310.06724831792, 37880.24239515139, 32407.675923240466, 36135.0, 36867.0, 37101.0, 37824.0, 32426.027219183463, 33612.66387336095, 34055.148621284665, 34165.829170853984, 34194.316113678084, 25847.22458326178, 25853.353666158495, 26093.347666307734, 26139.35296862138, 26375.20874373772], "value": 17877.846033268605, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6453546453546, 360.57632282068573, 365.42992931027584, 377.588428612812, 390.58792973413057, 15382.230888455575, 16082.437114700988, 16536.917315413437, 16542.917285413587, 16558.172091395427, 15558.688826223497, 16116.919415402936, 16640.750388744178, 16649.583760405996, 16650.58373540659, 5051.292819005343, 5227.869303267411, 5258.47415258473, 5267.28891599633, 5280.973595132038, 3708.684761795251, 5212.374515058198, 5744.856378590548, 6155.784547540837, 6156.322804491502, 6151.64663773969, 6157.599756015845, 6196.659183744892, 6209.658468784215, 6236.064590311467, 5018.7992480300745, 5323.307969963918, 5326.334208223972, 5397.379301380352, 5408.972295263888, 5069.163588007974, 5201.063808514466, 5395.5953303502, 5405.486478784509, 5422.376426710939, 11150.442477876155, 11174.21780475367, 11281.887181128248, 11309.982101610813, 16758.83241167585, 1698.1424380687797, 1834.2479583370887, 1835.0457761963837, 1900.220909427142, 1988.35378501986, 9171.31215158859, 9251.814963700755, 10764.192685548614, 12738.0, 13375.26436046017, 1731.9307227710876, 1769.938052168168, 1785.6071664233848, 1822.526143202777, 1828.6068495273446, 69378.0, 71176.6441167796, 72785.27214727891, 72811.0, 73062.26937730607, 9578.281628877723, 9733.902660973441, 9862.950685246527, 10294.588216471257, 10356.430396328124, 13743.656408589864, 13843.238621875893, 13853.16880987144, 13857.51498697561, 13864.306784660726, 7373.8156546086775, 7469.178390377054, 7529.284717951786, 7551.622418879034, 7559.735409260649, 7147.499675022698, 7189.460790440738, 7241.348278654947, 7295.781126566211, 7413.777586672409, 4241.554636763157, 4259.16952622056, 4290.249206388869, 4352.194843953878, 4377.1683380157665, 1654.7352423612165, 1715.3395942562204, 1799.5231263715125, 1820.399268241477, 1839.5768973136253, 823.3865770001387, 875.711015364928, 906.243286855483, 959.712086374085, 1007.3200589601989, 16145.838541614434, 16337.591560210853, 16517.0, 16891.91554042222, 16976.57558561046, 878.2008372381102, 882.9072947340566, 954.8329042417549, 955.5890966884307, 957.6456711016968, 9218.58516366765, 9253.120953509406, 9337.953310233406, 9485.33602647808, 9560.808783824285, 411144.0, 413010.0, 418089.0, 421561.0, 422788.0, 63790.6810465954, 73667.0, 77063.61468192624, 78164.0, 78453.0, 871.6339137562239, 871.7471933139445, 878.7056336127341, 893.6425429828121, 894.5974311559825, 14415.711685766435, 14695.92652036733, 15927.124008179198, 16057.11685857289, 16373.263203156124, 25743.613845791955, 26052.609210862232, 26822.865885670446, 28667.713322866504, 36673.266534668626, 48655.0, 48922.266166006826, 49745.75127124486, 49829.75085124551, 49886.25170622371, 7741.806454838674, 7921.32668723165, 7943.364530837403, 7970.521768693897, 8109.513429194267, 8454.40819142654, 8776.122387761172, 11763.06550805454, 12135.817962730744, 12343.629691109281, 5527.060399731975, 5761.567882408753, 10320.27758056959, 10857.34855908648, 10970.945145274223, 10570.577176913144, 10579.94200579936, 10587.89412105869, 10644.8935510647, 10750.892491074988, 10925.453727313443, 10998.890011099787, 11001.119910407308, 11025.117990560575, 11102.2783519071, 7720.7683769487, 7799.49303295284, 7978.3218426434405, 8170.346372290322, 8212.753617391489, 10063.496825158567, 10912.890871091506, 10959.287646302964, 11009.449527523751, 11052.0], "value": 8107.259617711479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.939252733627, 1395.1629022586446, 1478.3716920308866, 1674.740415235639, 1704.4204970310093, 6489.772857949961, 7443.404527637789, 7455.589942553157, 7920.168382319853, 7962.084360298568, 7589.127250366209, 7728.07263128424, 7882.645280962356, 7888.526688398686, 7904.446688731791, 2039.5920815836819, 2071.564971356017, 2074.8858812765293, 2078.8025137611903, 2085.86441881278, 2151.064287035145, 2154.4183070570934, 2155.8706477611395, 2159.654455287154, 2182.3671135370723, 2152.0, 2164.4588852786806, 2165.740111186652, 2177.4665207024223, 2201.8899055047264, 2098.0558748563053, 2099.4436474334316, 2102.810747032767, 2112.767595564487, 2112.7781582933726, 2029.8274646655052, 2066.059942726056, 2090.5087304483436, 2094.696269040996, 2109.440998135495, 7734.342580880632, 7794.2205779422175, 7798.337141342992, 7843.843123137505, 7848.293653571206, 4244.639205667522, 4327.17783621111, 4356.6732495063, 4364.5635436456105, 4372.1692878353015, 2724.891004359843, 2789.4839454700946, 2893.7829662775375, 2934.5891575179494, 2946.175070980129, 891.663840737266, 912.32943786317, 924.366808736018, 930.3255140023497, 931.1061381074177, 10352.275340726152, 10354.17166626668, 10366.533505992324, 10374.585016599327, 10382.0, 4025.798710064485, 4046.979765101156, 4151.169766046803, 4152.1280531088205, 4155.10665206982, 8905.866412003825, 8961.775955601162, 8962.103789621116, 8975.0, 8982.056884027214, 4546.02260513991, 4609.032103258286, 4614.077184563033, 4625.982283897537, 4636.188666983265, 3527.753057286017, 3908.687305015592, 4025.77858217795, 4186.581341865789, 4252.829886804555, 3613.602503724588, 3615.2769446110883, 3678.4850120983083, 3722.1625134344554, 3733.75730577517, 1122.2480937771713, 1152.8097863852538, 1167.6146871532374, 1168.6318809574957, 1175.8236264560385, 852.2457625001917, 860.586918279217, 869.1221865915345, 882.748416701237, 899.6761165980242, 5393.325834270716, 5435.402105768362, 5616.269884914935, 5767.307923049261, 5778.046622307273, 867.6225841758729, 895.9676500175954, 899.3659470073583, 939.2345238630494, 955.5556666150273, 3732.1975775207916, 3758.3047136279874, 3808.7333886628226, 3854.730168888207, 3887.747296425723, 9310.441373517611, 9579.473128977834, 9603.471809050568, 9649.324547281623, 9657.420554766595, 9443.811123777487, 9464.668736594185, 9490.478023708623, 9540.522973851419, 9585.089416505421, 6857.27998560154, 7046.330598593126, 7129.073220481304, 7223.1693355264815, 7292.453066020176, 5484.808031718871, 5589.944100558942, 5622.353429355513, 5677.943220567907, 5697.800576979786, 8427.199416055464, 8710.564471776259, 8938.0, 8973.237274831712, 9015.323850711355, 8308.750737477885, 8615.396922215385, 9119.680811171576, 9126.17864392208, 9141.26869850397, 5852.502537284208, 6583.3087525811015, 6810.42111420515, 6840.760573379908, 7010.158780946319, 7195.496315257882, 7208.27917208275, 7279.636018199175, 7340.669669864976, 7371.852562948604, 6716.899246511404, 6920.550164239308, 6929.514933954776, 6962.3733863950465, 6992.965035174791, 6862.4853136014, 6863.897041544281, 6888.207856096613, 6924.238333783381, 7040.3663670269925, 5972.313183983897, 6150.477209437069, 6238.532110091852, 6265.216847894013, 6283.403076707888, 4598.540145985375, 4610.631149507964, 4623.676342655982, 4624.3757092793385, 4709.929351059668, 4415.823367065282, 4432.4513404793015, 4435.5120936697585, 4441.178381999341, 4477.171723231213], "value": 4122.342883132711, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.12093357127895, 301.4092378937283, 306.88645201275534, 308.10802726107903, 309.5526963537689, 4857.684250523723, 4953.504649535044, 5063.1645778446555, 5085.516875896793, 5104.05574968631, 5019.899602007967, 5023.196288593826, 5035.546800787928, 5045.192769156935, 5047.1167545679455, 3931.5085614298214, 3945.9013524661827, 3951.6443520083185, 3959.940600890989, 3970.9007274818146, 3849.7867305646405, 3872.5740168581433, 3945.9605403946025, 3951.683865290784, 3966.2152031585756, 3851.8074096295213, 3914.2367238388524, 3926.195129998358, 3942.7634341939433, 3952.0, 3902.1220225449188, 3906.726529142961, 3919.568847426781, 3944.1520073184115, 3957.28768821612, 3532.9116772080647, 3544.3620148373284, 3554.306910152521, 3554.6267641897743, 3560.928781424357, 4635.976820115911, 4828.913494463717, 4840.249761287007, 4936.5063493650705, 4962.932969411558, 4407.272799987998, 4431.2466880630045, 4435.179491793996, 4459.130469558438, 4467.15124126415, 5209.6874187548865, 5224.373075230958, 5251.054810134175, 5260.2372655965055, 5266.183741520071, 3514.7346955096145, 3703.4629978652943, 3720.5163328767358, 3731.794751288678, 3738.2336620992633, 5447.400785913546, 5480.479354461319, 5486.451354864522, 5495.697736624484, 5500.28496295479, 3807.600201978808, 3821.7706937583835, 3827.004978705557, 3836.2519308734527, 3838.098046958962, 3816.6183381662167, 3839.232153569297, 3839.865604703822, 3847.4228865670375, 3853.6339047790416, 4172.165566886574, 4389.956100439019, 4412.624926881187, 4427.247367947457, 4431.667624928144, 5239.371275446971, 5309.814156504503, 5329.067413202752, 5367.78528858849, 5385.0037743017665, 1258.1004581724112, 1304.0480449272084, 1319.6107148391247, 1330.767115754739, 1335.7929520924272, 839.8992120945526, 851.3997631669658, 854.6410507586828, 859.1623167411849, 865.731623196811, 4129.628333450004, 4136.565660605593, 4137.02134463186, 4147.605977432139, 4161.687873409446, 4811.511654050855, 4819.048285444401, 4837.927431088537, 4889.48660390661, 4895.559399654048, 4180.832766689298, 4214.620684138442, 4218.535961044283, 4221.176870510221, 4223.387608796707, 2502.4869901670113, 2519.6472493850874, 2528.5081801737065, 2585.4441295121637, 2596.870156492168, 4508.594226519629, 4583.541645835457, 4598.172328980749, 4616.984263462033, 4634.559716826896, 4777.829431789164, 4875.975620121877, 4913.77887995034, 4971.552560269521, 4992.475790042066, 4596.724196548218, 4761.738104404291, 5159.535641792257, 5161.380634323905, 5168.7415629218385, 5471.890562188813, 5482.972585137049, 5486.478784515464, 5491.341039075336, 5496.340439147328, 5238.449962753856, 5415.241866138665, 5440.591955603267, 5458.781648734165, 5472.261244731911, 5119.846404607868, 5162.380514338303, 5164.1995490699, 5168.689878607295, 5190.714510701948, 3765.378712512405, 3799.1072098056384, 3810.971037819829, 3818.6706109272764, 3825.1775868188474, 4947.975260123821, 5497.697626630574, 5504.697241651747, 5511.4213007633625, 5515.676237703003, 5213.582913366998, 5267.209918512254, 5294.549963253166, 5302.681839089667, 5325.414204437431, 5275.630705850554, 5287.074761916726, 5300.416954134965, 5344.9732751336, 5349.0, 5069.011542749128, 5174.922376164436, 5187.507186817246, 5217.0, 5220.546343218681, 5188.610854185876, 5289.915567308615, 5308.761105750174, 5316.30887984575, 5326.467353264798, 5151.192600033066, 5160.303359046481, 5213.869653258699, 5231.895362092813, 5244.344456942882], "value": 3888.306033195925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.8213082467731, 671.1878626861497, 681.0261326303385, 722.2885457824044, 749.5727435361845, 10697.144228461724, 12710.936445317771, 12838.229706217633, 13305.401256943425, 13360.265185414799, 13362.265075420848, 13488.932555337235, 13520.729585408286, 13565.25391103489, 13576.592702218924, 633.3128555517246, 788.5229436191108, 789.5144486141011, 799.0571126071234, 799.256691277112, 807.6002378822491, 811.1280373598375, 812.3217113710059, 812.6627449608405, 813.9023317201944, 852.4970267542149, 858.8969323681167, 860.1312674199041, 860.5482121885997, 861.8104017116225, 840.4789030800912, 843.3253397282181, 844.8690452979798, 845.0704225352125, 855.5337341149027, 621.8942779727457, 622.4304761143549, 623.6725718997517, 624.4286477872741, 624.665803794972, 13305.135166214262, 13378.732425351536, 13494.325283735774, 13547.119437236548, 13553.0, 12527.812082818766, 12643.258777958266, 13051.804222936664, 13145.474181032843, 13688.863111368859, 10111.89888101117, 10233.74415639615, 10300.587976480932, 10301.896981030168, 10319.690409287658, 3335.232896433834, 3359.160209947513, 3377.0, 3380.290139070798, 3493.0219538529254, 64381.356186438476, 64572.0, 65145.719899803276, 65160.0, 65360.67319663419, 10439.739006524745, 10722.41026743521, 10809.0, 10904.127669786398, 11377.431128443544, 20873.68689469659, 20901.790982089984, 20939.79060209409, 20939.895300523705, 20967.790322096887, 6591.176102987127, 6723.966380168068, 6735.461163106939, 6757.93242067573, 6871.278515755874, 3328.8002719836886, 3336.7997920124385, 3339.1819004344093, 3478.32172726321, 3530.3115892401247, 3033.1810411188853, 3175.063356309894, 3181.5227715842348, 3187.3306605612615, 3188.1870123118456, 461.15838594565054, 470.96938698984457, 475.41049099116844, 487.45892059813576, 488.8142505847826, 458.84628649402157, 498.5986281043722, 577.7255803493391, 583.4020129367348, 593.44809327326, 7504.812379690442, 7637.656305466267, 7747.30274275318, 7873.960630196812, 8007.639656215484, 450.36498537062585, 451.29597827389375, 578.3406916115631, 596.4035964035965, 625.4183609243447, 3579.964200358015, 3583.8208089595414, 3606.8016259105475, 3630.5098811660614, 3644.6879123515514, 77447.0, 96033.51983240039, 97819.51090244504, 98123.0, 99253.0, 52723.47276527262, 62430.0, 79729.0, 81366.59316703379, 88135.55932220299, 57445.0, 74369.6281518589, 76646.0, 76977.61511192408, 77978.61010695023, 7093.964530177316, 7171.928280717126, 7216.206217315986, 7240.746573869889, 7410.9258907412395, 44168.33747493855, 44860.32709509294, 45938.540614593425, 46139.76930115462, 46361.76819115883, 56484.435155647916, 62023.0, 62395.37604624077, 63292.68353658386, 63507.36492635014, 10474.738131546772, 10485.475726213506, 10770.623028193975, 10829.51267192994, 11061.778764424827, 10462.424566648908, 10504.632337868137, 10556.788864222519, 11694.415279236175, 11747.530098795953, 11301.491432885705, 11326.263792853439, 11471.0, 11499.195056345976, 11661.183717139718, 11712.414379280832, 12143.453544590691, 12146.696332591757, 12156.696082598006, 12335.50657973671, 10069.446180459852, 11508.712282192677, 11532.769344613229, 11614.419279036183, 11730.0, 9405.482698451651, 9519.809603808024, 9596.424214547149, 9953.104220620178, 10526.473676316307, 9520.428774273565, 10125.696229113139, 10211.897881021094, 10226.59093636276, 10259.230557708352], "value": 6469.538178855699, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 115.0, 113.0, 116.0, 116.0, 116.0, 114.0, 114.0, 113.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 159.0, 199.0, 133.0, 161.0, 164.0, 132.0, 191.0, 145.0], "value": 158.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 165.0, 169.0, 168.0, 164.0, 162.0, 163.0, 163.0, 162.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 157.0, 77.0, 76.0, 76.0, 77.0, 176.0, 81.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 137.0, 87.0, 86.0, 88.0, 141.0, 87.0, 86.0, 133.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 122.0, 117.0, 118.0, 118.0, 117.0, 117.0, 121.0, 118.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 64.0, 63.0, 61.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 72.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 87.0, 88.0, 85.0, 87.0, 112.0, 92.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 131.0, 137.0, 129.0, 140.0, 140.0, 132.0, 139.0, 140.0, 132.0], "value": 135.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 175.0, 174.0, 179.0, 170.0, 168.0, 174.0, 171.0, 169.0, 167.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 70.0, 69.0, 68.0, 69.0, 67.0, 72.0, 69.0, 67.0], "value": 69.1, "unit": "score"}]}]} 15:57:51 INFO - Return code: 0 15:57:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:57:51 ERROR - # TBPL SUCCESS # 15:57:51 INFO - Running post-action listener: _resource_record_post_action 15:57:51 INFO - ##### 15:57:51 INFO - ##### Finished run-tests step (success) 15:57:51 INFO - ##### 15:57:51 INFO - Running post-run listener: _resource_record_post_run 15:57:51 INFO - Total resource usage - Wall time: 1199s; CPU: 14.0%; Read bytes: 4096; Write bytes: 549179392; Read time: 12; Write time: 759500 15:57:51 INFO - TinderboxPrint: CPU usage
13.5% 15:57:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:57:51 INFO - TinderboxPrint: I/O write bytes / time
549,179,392 / 759,500 15:57:51 INFO - TinderboxPrint: CPU idle
8,273.3 (86.5%) 15:57:51 INFO - TinderboxPrint: CPU user
1,233.2 (12.9%) 15:57:51 INFO - TinderboxPrint: Swap in / out
0 / 0 15:57:51 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237674496; Read time: 0; Write time: 343020 15:57:51 INFO - run-tests - Wall time: 1184s; CPU: 14.0%; Read bytes: 4096; Write bytes: 295972864; Read time: 12; Write time: 409052 15:57:51 INFO - Running post-run listener: _upload_blobber_files 15:57:51 INFO - Blob upload gear active. 15:57:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:57:51 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 15:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:57:52 INFO - (blobuploader) - INFO - Open directory for files ... 15:57:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:57:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:58 INFO - (blobuploader) - INFO - Done attempting. 15:57:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:57:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:57:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:59 INFO - (blobuploader) - INFO - Done attempting. 15:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:00 INFO - (blobuploader) - INFO - Done attempting. 15:58:00 INFO - (blobuploader) - INFO - Iteration through files over. 15:58:00 INFO - Return code: 0 15:58:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:58:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:58:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/25ba688b6021184149451444daeb46d46558fae447253c5d4b2d9d76eee2d3da44f0ed2cadb8aec15dc2d228397d7c53e3285a3cd458d9033000d0af933881b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a9893d47650d57069400ff70d4492e5c6a70f568249da67c58aefa07afbdfa780ac176fdc7451a75c8962d820beb8bf21ab9313ae9eed7b2fe4e7ee151e6343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c9ebb8f5613246c6f9515fd2fd4bb99b38f09ff444046f6f4bb0402aa427d1b659353bb9578df416c906564f64ad97fe9680e90d095cd27b995a90acc40aea7"} 15:58:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:58:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:58:00 INFO - Contents: 15:58:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/25ba688b6021184149451444daeb46d46558fae447253c5d4b2d9d76eee2d3da44f0ed2cadb8aec15dc2d228397d7c53e3285a3cd458d9033000d0af933881b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a9893d47650d57069400ff70d4492e5c6a70f568249da67c58aefa07afbdfa780ac176fdc7451a75c8962d820beb8bf21ab9313ae9eed7b2fe4e7ee151e6343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c9ebb8f5613246c6f9515fd2fd4bb99b38f09ff444046f6f4bb0402aa427d1b659353bb9578df416c906564f64ad97fe9680e90d095cd27b995a90acc40aea7"} 15:58:00 INFO - Running post-run listener: copy_logs_to_upload_dir 15:58:00 INFO - Copying logs to upload dir... 15:58:00 INFO - mkdir: /builds/slave/test/build/upload/logs 15:58:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.343711 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-07 15:57:59.783667) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 15:57:59.787703) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/25ba688b6021184149451444daeb46d46558fae447253c5d4b2d9d76eee2d3da44f0ed2cadb8aec15dc2d228397d7c53e3285a3cd458d9033000d0af933881b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a9893d47650d57069400ff70d4492e5c6a70f568249da67c58aefa07afbdfa780ac176fdc7451a75c8962d820beb8bf21ab9313ae9eed7b2fe4e7ee151e6343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c9ebb8f5613246c6f9515fd2fd4bb99b38f09ff444046f6f4bb0402aa427d1b659353bb9578df416c906564f64ad97fe9680e90d095cd27b995a90acc40aea7"} build_url:https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011405 build_url: 'https://queue.taskcluster.net/v1/task/GYCm15cERqqXeWz1ymh3Sg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/25ba688b6021184149451444daeb46d46558fae447253c5d4b2d9d76eee2d3da44f0ed2cadb8aec15dc2d228397d7c53e3285a3cd458d9033000d0af933881b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a9893d47650d57069400ff70d4492e5c6a70f568249da67c58aefa07afbdfa780ac176fdc7451a75c8962d820beb8bf21ab9313ae9eed7b2fe4e7ee151e6343", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5c9ebb8f5613246c6f9515fd2fd4bb99b38f09ff444046f6f4bb0402aa427d1b659353bb9578df416c906564f64ad97fe9680e90d095cd27b995a90acc40aea7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 15:57:59.813563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:57:59.813897) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1465339034.922068-1868081094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:57:59.896303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 15:57:59.896624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 15:57:59.897083) ========= ========= Total master_lag: 0.17 =========