builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-046 starttime: 1470140949.42 results: success (0) buildid: 20160802052907 builduid: df2b9e1beec34b7b95b3bebead0d95e7 revision: 46045ec8a4aa09a341b7209170089ade3c27c1a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.423052) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.423471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.423749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.450928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.451256) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 05:29:09-- 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% 95.0M=0s 2016-08-02 05:29:09 (95.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096813 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.566559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.566889) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020382 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:09.620934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 05:29:09.621306) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 46045ec8a4aa09a341b7209170089ade3c27c1a5 --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 46045ec8a4aa09a341b7209170089ade3c27c1a5 --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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 05:29:09,905 truncating revision to first 12 chars 2016-08-02 05:29:09,906 Setting DEBUG logging. 2016-08-02 05:29:09,906 attempt 1/10 2016-08-02 05:29:09,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/46045ec8a4aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 05:29:10,926 unpacking tar archive at: fx-team-46045ec8a4aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.348522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 05:29:10.988076) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:10.988418) ========= script_repo_revision: 46045ec8a4aa09a341b7209170089ade3c27c1a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:10.988903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:10.989197) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 05:29:10.999640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-08-02 05:29:11.000049) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:29:11 INFO - MultiFileLogger online at 20160802 05:29:11 in /builds/slave/test 05:29:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:29:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:11 INFO - {'append_to_log': False, 05:29:11 INFO - 'base_work_dir': '/builds/slave/test', 05:29:11 INFO - 'blob_upload_branch': 'Fx-Team', 05:29:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:11 INFO - 'branch': 'Fx-Team', 05:29:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:11 INFO - 'config_files': ('talos/linux_config.py',), 05:29:11 INFO - 'default_actions': ('clobber', 05:29:11 INFO - 'read-buildbot-config', 05:29:11 INFO - 'download-and-extract', 05:29:11 INFO - 'populate-webroot', 05:29:11 INFO - 'create-virtualenv', 05:29:11 INFO - 'install', 05:29:11 INFO - 'run-tests'), 05:29:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:11 INFO - 'download_minidump_stackwalk': True, 05:29:11 INFO - 'download_symbols': 'ondemand', 05:29:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:11 INFO - 'installer_path': 'installer.exe', 05:29:11 INFO - 'log_level': 'info', 05:29:11 INFO - 'log_name': 'talos', 05:29:11 INFO - 'log_to_console': True, 05:29:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:29:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:29:11 INFO - 'opt_config_files': (), 05:29:11 INFO - 'pip_index': False, 05:29:11 INFO - 'sps_profile': False, 05:29:11 INFO - 'sps_profile_interval': 0, 05:29:11 INFO - 'suite': 'dromaeojs-e10s', 05:29:11 INFO - 'system_bits': '32', 05:29:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:11 INFO - 'title': 'talos-linux64-ix-046', 05:29:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:11 INFO - 'use_talos_json': True, 05:29:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:11 INFO - 'work_dir': 'build'} 05:29:11 INFO - [mozharness: 2016-08-02 12:29:11.374778Z] Running clobber step. 05:29:11 INFO - Running pre-action listener: _resource_record_pre_action 05:29:11 INFO - Running main action method: clobber 05:29:11 INFO - rmtree: /builds/slave/test/build 05:29:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:12 INFO - Running post-action listener: _resource_record_post_action 05:29:12 INFO - [mozharness: 2016-08-02 12:29:12.366277Z] Finished clobber step (success) 05:29:12 INFO - [mozharness: 2016-08-02 12:29:12.366376Z] Running read-buildbot-config step. 05:29:12 INFO - Running pre-action listener: _resource_record_pre_action 05:29:12 INFO - Running main action method: read_buildbot_config 05:29:12 INFO - Using buildbot properties: 05:29:12 INFO - { 05:29:12 INFO - "project": "", 05:29:12 INFO - "product": "firefox", 05:29:12 INFO - "script_repo_revision": "production", 05:29:12 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:29:12 INFO - "repository": "", 05:29:12 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:29:12 INFO - "buildid": "20160802052907", 05:29:12 INFO - "stage_platform": "linux64-pgo", 05:29:12 INFO - "basedir": "/builds/slave/test", 05:29:12 INFO - "buildnumber": 1001, 05:29:12 INFO - "platform": "ubuntu64_hw", 05:29:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:29:12 INFO - "slavebuilddir": "test", 05:29:12 INFO - "branch": "fx-team", 05:29:12 INFO - "revision": "46045ec8a4aa09a341b7209170089ade3c27c1a5", 05:29:12 INFO - "slavename": "talos-linux64-ix-046", 05:29:12 INFO - "builduid": "df2b9e1beec34b7b95b3bebead0d95e7", 05:29:12 INFO - "repo_path": "integration/fx-team" 05:29:12 INFO - } 05:29:12 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:29:12 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 05:29:12 INFO - Running post-action listener: _resource_record_post_action 05:29:12 INFO - [mozharness: 2016-08-02 12:29:12.368739Z] Finished read-buildbot-config step (success) 05:29:12 INFO - [mozharness: 2016-08-02 12:29:12.368830Z] Running download-and-extract step. 05:29:12 INFO - Running pre-action listener: _resource_record_pre_action 05:29:12 INFO - Running main action method: download_and_extract 05:29:12 INFO - mkdir: /builds/slave/test/build/tests 05:29:12 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')]} 05:29:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:29:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:29:12 INFO - Downloaded 1361 bytes. 05:29:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:29:12 INFO - Using the following test package requirements: 05:29:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:29:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:29:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:29:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'jsshell-linux-x86_64.zip'], 05:29:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:29:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:29:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:29:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:29:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:29:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:29:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:29:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:29:12 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')]} 05:29:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:29:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:29:20 INFO - Downloaded 21809159 bytes. 05:29:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:29:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:29:22 INFO - Return code: 0 05:29:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:29:22 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')]} 05:29:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:29:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:29:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:29:22 INFO - Downloaded 21809159 bytes. 05:29:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:29:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:29:23 INFO - Return code: 0 05:29: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')]} 05:29:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:29:30 INFO - Downloaded 14413723 bytes. 05:29:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:29:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:29:31 INFO - Return code: 0 05:29:31 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')]} 05:29:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:29:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:29:51 INFO - Downloaded 59388049 bytes. 05:29:51 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:29:51 INFO - mkdir: /builds/slave/test/properties 05:29:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:29:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:29:51 INFO - Contents: 05:29:51 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - Running post-action listener: set_extra_try_arguments 05:29:51 INFO - [mozharness: 2016-08-02 12:29:51.704209Z] Finished download-and-extract step (success) 05:29:51 INFO - [mozharness: 2016-08-02 12:29:51.704451Z] Running populate-webroot step. 05:29:51 INFO - Running pre-action listener: _resource_record_pre_action 05:29:51 INFO - Running main action method: populate_webroot 05:29:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:51 INFO - '--tppagecycles', 05:29:51 INFO - '7'], 05:29:51 INFO - 'tests': ['tsvgm']}}, 05:29:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:29:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:29:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['damp', 'tps']}, 05:29:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'tests': ['damp', 'tps']}, 05:29:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['dromaeo_dom']}, 05:29:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['basic_compositor_video']}, 05:29:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:29:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['a11yr', 05:29:51 INFO - 'ts_paint', 05:29:51 INFO - 'tpaint', 05:29:51 INFO - 'sessionrestore', 05:29:51 INFO - 'sessionrestore_no_auto_restore', 05:29:51 INFO - 'tabpaint']}, 05:29:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['tsvgx', 05:29:51 INFO - 'tsvgr_opacity', 05:29:51 INFO - 'tart', 05:29:51 INFO - 'tscrollx', 05:29:51 INFO - 'cart']}, 05:29:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:29:51 INFO - 'tsvgr_opacity', 05:29:51 INFO - 'tart', 05:29:51 INFO - 'tscrollx', 05:29:51 INFO - 'cart']}, 05:29:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'talos_options': ['--disable-e10s'], 05:29:51 INFO - 'tests': ['tp5o']}, 05:29:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'tests': ['tp5o']}, 05:29:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'talos_options': ['--disable-e10s', 05:29:51 INFO - '--xperf_path', 05:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:51 INFO - 'tests': ['tp5n']}, 05:29:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:51 INFO - 'talos_options': ['--xperf_path', 05:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:51 INFO - 'tests': ['tp5n']}}, 05:29:51 INFO - 'talos.zip': {'path': '', 05:29:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:51 INFO - Running post-action listener: _resource_record_post_action 05:29:51 INFO - [mozharness: 2016-08-02 12:29:51.723656Z] Finished populate-webroot step (success) 05:29:51 INFO - [mozharness: 2016-08-02 12:29:51.723746Z] Running create-virtualenv step. 05:29:51 INFO - Running pre-action listener: _resource_record_pre_action 05:29:51 INFO - Running main action method: create_virtualenv 05:29:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:51 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 05:29:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:51 INFO - Using real prefix '/usr' 05:29:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:54 INFO - Installing pip.................done. 05:29:54 INFO - Return code: 0 05:29:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:54 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:29:54 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 05:29:54 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 05:29:54 INFO - Using env: {'DISPLAY': ':0', 05:29:54 INFO - 'HOME': '/home/cltbld', 05:29:54 INFO - 'LANG': 'en_US.UTF-8', 05:29:54 INFO - 'LANGUAGE': 'en_US:en', 05:29:54 INFO - 'LOGNAME': 'cltbld', 05:29:54 INFO - 'MAIL': '/var/mail/cltbld', 05:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:54 INFO - 'MOZ_NO_REMOTE': '1', 05:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:54 INFO - 'NO_EM_RESTART': '1', 05:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:54 INFO - 'PWD': '/builds/slave/test', 05:29:54 INFO - 'SHELL': '/bin/bash', 05:29:54 INFO - 'SHLVL': '1', 05:29:54 INFO - 'TERM': 'linux', 05:29:54 INFO - 'TMOUT': '86400', 05:29:54 INFO - 'USER': 'cltbld', 05:29:54 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:54 INFO - '_': '/tools/buildbot/bin/python'} 05:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:54 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 05:29:54 INFO - Cleaning up... 05:29:54 INFO - Return code: 0 05:29:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:54 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:29:54 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 05:29:54 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 05:29:54 INFO - Using env: {'DISPLAY': ':0', 05:29:54 INFO - 'HOME': '/home/cltbld', 05:29:54 INFO - 'LANG': 'en_US.UTF-8', 05:29:54 INFO - 'LANGUAGE': 'en_US:en', 05:29:54 INFO - 'LOGNAME': 'cltbld', 05:29:54 INFO - 'MAIL': '/var/mail/cltbld', 05:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:54 INFO - 'MOZ_NO_REMOTE': '1', 05:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:54 INFO - 'NO_EM_RESTART': '1', 05:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:54 INFO - 'PWD': '/builds/slave/test', 05:29:54 INFO - 'SHELL': '/bin/bash', 05:29:54 INFO - 'SHLVL': '1', 05:29:54 INFO - 'TERM': 'linux', 05:29:54 INFO - 'TMOUT': '86400', 05:29:54 INFO - 'USER': 'cltbld', 05:29:54 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:54 INFO - '_': '/tools/buildbot/bin/python'} 05:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:54 INFO - Downloading/unpacking psutil>=3.1.1 05:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:56 INFO - Installing collected packages: psutil 05:29:56 INFO - Running setup.py install for psutil 05:29:56 INFO - building 'psutil._psutil_linux' extension 05:29:56 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 05:29:57 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 05:29:57 INFO - building 'psutil._psutil_posix' extension 05:29:57 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 05:29:57 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 05:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:57 INFO - Successfully installed psutil 05:29:57 INFO - Cleaning up... 05:29:57 INFO - Return code: 0 05:29:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:29:57 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')]} 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:57 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')]} 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:57 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:29:57 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 05:29:57 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 05:29:57 INFO - Using env: {'DISPLAY': ':0', 05:29:57 INFO - 'HOME': '/home/cltbld', 05:29:57 INFO - 'LANG': 'en_US.UTF-8', 05:29:57 INFO - 'LANGUAGE': 'en_US:en', 05:29:57 INFO - 'LOGNAME': 'cltbld', 05:29:57 INFO - 'MAIL': '/var/mail/cltbld', 05:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:57 INFO - 'MOZ_NO_REMOTE': '1', 05:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:57 INFO - 'NO_EM_RESTART': '1', 05:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:57 INFO - 'PWD': '/builds/slave/test', 05:29:57 INFO - 'SHELL': '/bin/bash', 05:29:57 INFO - 'SHLVL': '1', 05:29:57 INFO - 'TERM': 'linux', 05:29:57 INFO - 'TMOUT': '86400', 05:29:57 INFO - 'USER': 'cltbld', 05:29:57 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:57 INFO - '_': '/tools/buildbot/bin/python'} 05:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:29:59 INFO - Installing collected packages: mozsystemmonitor 05:29:59 INFO - Running setup.py install for mozsystemmonitor 05:29:59 INFO - Successfully installed mozsystemmonitor 05:29:59 INFO - Cleaning up... 05:29:59 INFO - Return code: 0 05:29:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:29:59 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')]} 05:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:59 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')]} 05:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:59 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:29:59 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 05:29:59 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 05:29:59 INFO - Using env: {'DISPLAY': ':0', 05:29:59 INFO - 'HOME': '/home/cltbld', 05:29:59 INFO - 'LANG': 'en_US.UTF-8', 05:29:59 INFO - 'LANGUAGE': 'en_US:en', 05:29:59 INFO - 'LOGNAME': 'cltbld', 05:29:59 INFO - 'MAIL': '/var/mail/cltbld', 05:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:59 INFO - 'MOZ_NO_REMOTE': '1', 05:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:59 INFO - 'NO_EM_RESTART': '1', 05:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:59 INFO - 'PWD': '/builds/slave/test', 05:29:59 INFO - 'SHELL': '/bin/bash', 05:29:59 INFO - 'SHLVL': '1', 05:29:59 INFO - 'TERM': 'linux', 05:29:59 INFO - 'TMOUT': '86400', 05:29:59 INFO - 'USER': 'cltbld', 05:29:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:59 INFO - '_': '/tools/buildbot/bin/python'} 05:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:59 INFO - Downloading/unpacking jsonschema==2.5.1 05:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:01 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 05:30:01 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 05:30:01 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 05:30:01 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 05:30:01 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 05:30:01 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 05:30:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:02 INFO - warning: no files found matching '*.txt' 05:30:02 INFO - no previously-included directories found matching 'build' 05:30:02 INFO - no previously-included directories found matching 'dist' 05:30:02 INFO - no previously-included directories found matching '.git*' 05:30:02 INFO - Installing collected packages: jsonschema, functools32 05:30:02 INFO - Running setup.py install for functools32 05:30:02 INFO - warning: no files found matching '*.txt' 05:30:02 INFO - no previously-included directories found matching 'build' 05:30:02 INFO - no previously-included directories found matching 'dist' 05:30:02 INFO - no previously-included directories found matching '.git*' 05:30:02 INFO - Successfully installed jsonschema functools32 05:30:02 INFO - Cleaning up... 05:30:02 INFO - Return code: 0 05:30:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30:02 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')]} 05:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:02 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')]} 05:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:30:02 INFO - Using env: {'DISPLAY': ':0', 05:30:02 INFO - 'HOME': '/home/cltbld', 05:30:02 INFO - 'LANG': 'en_US.UTF-8', 05:30:02 INFO - 'LANGUAGE': 'en_US:en', 05:30:02 INFO - 'LOGNAME': 'cltbld', 05:30:02 INFO - 'MAIL': '/var/mail/cltbld', 05:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:02 INFO - 'MOZ_NO_REMOTE': '1', 05:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:02 INFO - 'NO_EM_RESTART': '1', 05:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:02 INFO - 'PWD': '/builds/slave/test', 05:30:02 INFO - 'SHELL': '/bin/bash', 05:30:02 INFO - 'SHLVL': '1', 05:30:02 INFO - 'TERM': 'linux', 05:30:02 INFO - 'TMOUT': '86400', 05:30:02 INFO - 'USER': 'cltbld', 05:30:02 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:02 INFO - '_': '/tools/buildbot/bin/python'} 05:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:02 INFO - Cleaning up... 05:30:02 INFO - Return code: 0 05:30:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:02 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')]} 05:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:02 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')]} 05:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:02 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:02 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 05:30:02 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 05:30:02 INFO - Using env: {'DISPLAY': ':0', 05:30:02 INFO - 'HOME': '/home/cltbld', 05:30:02 INFO - 'LANG': 'en_US.UTF-8', 05:30:02 INFO - 'LANGUAGE': 'en_US:en', 05:30:02 INFO - 'LOGNAME': 'cltbld', 05:30:02 INFO - 'MAIL': '/var/mail/cltbld', 05:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:02 INFO - 'MOZ_NO_REMOTE': '1', 05:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:02 INFO - 'NO_EM_RESTART': '1', 05:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:02 INFO - 'PWD': '/builds/slave/test', 05:30:02 INFO - 'SHELL': '/bin/bash', 05:30:02 INFO - 'SHLVL': '1', 05:30:02 INFO - 'TERM': 'linux', 05:30:02 INFO - 'TMOUT': '86400', 05:30:02 INFO - 'USER': 'cltbld', 05:30:02 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:02 INFO - '_': '/tools/buildbot/bin/python'} 05:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:04 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 05:30:04 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 05:30:04 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 05:30:04 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 05:30:04 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 05:30:04 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 05:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:05 INFO - Downloading docopt-0.6.1.tar.gz 05:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:05 INFO - Running setup.py install for blobuploader 05:30:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:05 INFO - Running setup.py install for requests 05:30:05 INFO - Running setup.py install for docopt 05:30:05 INFO - Successfully installed blobuploader requests docopt 05:30:05 INFO - Cleaning up... 05:30:06 INFO - Return code: 0 05:30:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:06 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')]} 05:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:06 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')]} 05:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:06 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:06 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 05:30:06 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 05:30:06 INFO - Using env: {'DISPLAY': ':0', 05:30:06 INFO - 'HOME': '/home/cltbld', 05:30:06 INFO - 'LANG': 'en_US.UTF-8', 05:30:06 INFO - 'LANGUAGE': 'en_US:en', 05:30:06 INFO - 'LOGNAME': 'cltbld', 05:30:06 INFO - 'MAIL': '/var/mail/cltbld', 05:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:06 INFO - 'MOZ_NO_REMOTE': '1', 05:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:06 INFO - 'NO_EM_RESTART': '1', 05:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:06 INFO - 'PWD': '/builds/slave/test', 05:30:06 INFO - 'SHELL': '/bin/bash', 05:30:06 INFO - 'SHLVL': '1', 05:30:06 INFO - 'TERM': 'linux', 05:30:06 INFO - 'TMOUT': '86400', 05:30:06 INFO - 'USER': 'cltbld', 05:30:06 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:06 INFO - '_': '/tools/buildbot/bin/python'} 05:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:06 INFO - Running setup.py (path:/tmp/pip-PCJRDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:06 INFO - Running setup.py (path:/tmp/pip-ytv7FT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:06 INFO - Running setup.py (path:/tmp/pip-7giV3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:06 INFO - Running setup.py (path:/tmp/pip-TxVT4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:06 INFO - Running setup.py (path:/tmp/pip-XnzRzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:06 INFO - Running setup.py (path:/tmp/pip-Meh8Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:06 INFO - Running setup.py (path:/tmp/pip-tBwNuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:06 INFO - Running setup.py (path:/tmp/pip-fs5vGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:07 INFO - Running setup.py (path:/tmp/pip-U8Yube-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:07 INFO - Running setup.py (path:/tmp/pip-D5KgVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:07 INFO - Running setup.py (path:/tmp/pip-wjfWt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:07 INFO - Running setup.py (path:/tmp/pip-EJTcO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:07 INFO - Running setup.py (path:/tmp/pip-FhkSTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:07 INFO - Running setup.py (path:/tmp/pip-wdJldv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:07 INFO - Running setup.py (path:/tmp/pip-a9Iel4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:07 INFO - Running setup.py (path:/tmp/pip-iCEab4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:07 INFO - Running setup.py (path:/tmp/pip-0g9e84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:07 INFO - Running setup.py install for manifestparser 05:30:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:07 INFO - Running setup.py install for mozcrash 05:30:07 INFO - Running setup.py install for mozdebug 05:30:07 INFO - Running setup.py install for mozdevice 05:30:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for mozfile 05:30:08 INFO - Running setup.py install for mozhttpd 05:30:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for mozinfo 05:30:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for mozInstall 05:30:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for mozleak 05:30:08 INFO - Running setup.py install for mozlog 05:30:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for moznetwork 05:30:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:08 INFO - Running setup.py install for mozprocess 05:30:08 INFO - Running setup.py install for mozprofile 05:30:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:09 INFO - Running setup.py install for mozrunner 05:30:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:09 INFO - Running setup.py install for mozscreenshot 05:30:09 INFO - Running setup.py install for moztest 05:30:09 INFO - Running setup.py install for mozversion 05:30:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:09 INFO - Cleaning up... 05:30:09 INFO - Return code: 0 05:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:09 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')]} 05:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:09 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')]} 05:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:09 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:09 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 05:30:09 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 05:30:09 INFO - Using env: {'DISPLAY': ':0', 05:30:09 INFO - 'HOME': '/home/cltbld', 05:30:09 INFO - 'LANG': 'en_US.UTF-8', 05:30:09 INFO - 'LANGUAGE': 'en_US:en', 05:30:09 INFO - 'LOGNAME': 'cltbld', 05:30:09 INFO - 'MAIL': '/var/mail/cltbld', 05:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:09 INFO - 'MOZ_NO_REMOTE': '1', 05:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:09 INFO - 'NO_EM_RESTART': '1', 05:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:09 INFO - 'PWD': '/builds/slave/test', 05:30:09 INFO - 'SHELL': '/bin/bash', 05:30:09 INFO - 'SHLVL': '1', 05:30:09 INFO - 'TERM': 'linux', 05:30:09 INFO - 'TMOUT': '86400', 05:30:09 INFO - 'USER': 'cltbld', 05:30:09 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:09 INFO - '_': '/tools/buildbot/bin/python'} 05:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:09 INFO - Running setup.py (path:/tmp/pip-_CRyHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:09 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)) 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 INFO - Running setup.py (path:/tmp/pip-bnNguE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 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)) 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 INFO - Running setup.py (path:/tmp/pip-OD4b_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 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)) 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 INFO - Running setup.py (path:/tmp/pip-Xdm53b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 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)) 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:09 INFO - Running setup.py (path:/tmp/pip-LizbpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:10 INFO - Running setup.py (path:/tmp/pip-c5w51q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:10 INFO - Running setup.py (path:/tmp/pip-pKRFIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:10 INFO - Running setup.py (path:/tmp/pip-ZoNJ50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:10 INFO - Running setup.py (path:/tmp/pip-vRChby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:10 INFO - Running setup.py (path:/tmp/pip-Oj_A6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 INFO - Running setup.py (path:/tmp/pip-kBgGiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Running setup.py (path:/tmp/pip-5Qtj1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 INFO - Running setup.py (path:/tmp/pip-1RrwIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Running setup.py (path:/tmp/pip-2_XYxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:10 INFO - Running setup.py (path:/tmp/pip-0D1VrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:10 INFO - Running setup.py (path:/tmp/pip-_ZeOBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:10 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)) 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:10 INFO - Running setup.py (path:/tmp/pip-TBgKm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:10 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)) 05:30:10 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)) 05:30:10 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)) 05:30:10 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)) 05:30:10 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)) 05:30:10 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)) 05:30:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:10 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 05:30:10 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 05:30:10 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 05:30:10 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 05:30:10 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 05:30:10 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 05:30:12 INFO - Downloading blessings-1.6.tar.gz 05:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:12 INFO - Installing collected packages: blessings 05:30:12 INFO - Running setup.py install for blessings 05:30:12 INFO - Successfully installed blessings 05:30:12 INFO - Cleaning up... 05:30:13 INFO - Return code: 0 05:30:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:13 INFO - Reading from file tmpfile_stdout 05:30:13 INFO - Current package versions: 05:30:13 INFO - argparse == 1.2.1 05:30:13 INFO - blessings == 1.6 05:30:13 INFO - blobuploader == 1.2.4 05:30:13 INFO - docopt == 0.6.1 05:30:13 INFO - functools32 == 3.2.3-2 05:30:13 INFO - jsonschema == 2.5.1 05:30:13 INFO - manifestparser == 1.1 05:30:13 INFO - mozInstall == 1.12 05:30:13 INFO - mozcrash == 0.17 05:30:13 INFO - mozdebug == 0.1 05:30:13 INFO - mozdevice == 0.48 05:30:13 INFO - mozfile == 1.2 05:30:13 INFO - mozhttpd == 0.7 05:30:13 INFO - mozinfo == 0.9 05:30:13 INFO - mozleak == 0.1 05:30:13 INFO - mozlog == 3.3 05:30:13 INFO - moznetwork == 0.27 05:30:13 INFO - mozprocess == 0.23 05:30:13 INFO - mozprofile == 0.28 05:30:13 INFO - mozrunner == 6.12 05:30:13 INFO - mozscreenshot == 0.1 05:30:13 INFO - mozsystemmonitor == 0.3 05:30:13 INFO - moztest == 0.7 05:30:13 INFO - mozversion == 1.4 05:30:13 INFO - psutil == 3.1.1 05:30:13 INFO - requests == 1.2.3 05:30:13 INFO - wsgiref == 0.1.2 05:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:13 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 05:30:13 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 05:30:13 INFO - Using env: {'DISPLAY': ':0', 05:30:13 INFO - 'HOME': '/home/cltbld', 05:30:13 INFO - 'LANG': 'en_US.UTF-8', 05:30:13 INFO - 'LANGUAGE': 'en_US:en', 05:30:13 INFO - 'LOGNAME': 'cltbld', 05:30:13 INFO - 'MAIL': '/var/mail/cltbld', 05:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:13 INFO - 'MOZ_NO_REMOTE': '1', 05:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:13 INFO - 'NO_EM_RESTART': '1', 05:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:13 INFO - 'PWD': '/builds/slave/test', 05:30:13 INFO - 'SHELL': '/bin/bash', 05:30:13 INFO - 'SHLVL': '1', 05:30:13 INFO - 'TERM': 'linux', 05:30:13 INFO - 'TMOUT': '86400', 05:30:13 INFO - 'USER': 'cltbld', 05:30:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:13 INFO - '_': '/tools/buildbot/bin/python'} 05:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 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)) 05:30:13 INFO - Cleaning up... 05:30:13 INFO - Return code: 0 05:30:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 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 0x1b5f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e20180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, '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': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', '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 05:30:13 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 05:30:13 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 05:30:13 INFO - Using env: {'DISPLAY': ':0', 05:30:13 INFO - 'HOME': '/home/cltbld', 05:30:13 INFO - 'LANG': 'en_US.UTF-8', 05:30:13 INFO - 'LANGUAGE': 'en_US:en', 05:30:13 INFO - 'LOGNAME': 'cltbld', 05:30:13 INFO - 'MAIL': '/var/mail/cltbld', 05:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:13 INFO - 'MOZ_NO_REMOTE': '1', 05:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:13 INFO - 'NO_EM_RESTART': '1', 05:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:13 INFO - 'PWD': '/builds/slave/test', 05:30:13 INFO - 'SHELL': '/bin/bash', 05:30:13 INFO - 'SHLVL': '1', 05:30:13 INFO - 'TERM': 'linux', 05:30:13 INFO - 'TMOUT': '86400', 05:30:13 INFO - 'USER': 'cltbld', 05:30:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:13 INFO - '_': '/tools/buildbot/bin/python'} 05:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:13 INFO - Cleaning up... 05:30:13 INFO - Return code: 0 05:30:13 INFO - Running post-action listener: _resource_record_post_action 05:30:13 INFO - Running post-action listener: _start_resource_monitoring 05:30:13 INFO - Starting resource monitoring. 05:30:13 INFO - [mozharness: 2016-08-02 12:30:13.725700Z] Finished create-virtualenv step (success) 05:30:13 INFO - [mozharness: 2016-08-02 12:30:13.726178Z] Running install step. 05:30:13 INFO - Running pre-action listener: _resource_record_pre_action 05:30:13 INFO - Running main action method: install 05:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:13 INFO - Reading from file tmpfile_stdout 05:30:13 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:14 INFO - Reading from file tmpfile_stdout 05:30:14 INFO - Output received: 05:30:14 INFO - Usage: mozinstall [options] installer 05:30:14 INFO - Options: 05:30:14 INFO - -h, --help show this help message and exit 05:30:14 INFO - -d DEST, --destination=DEST 05:30:14 INFO - Directory to install application into. [default: 05:30:14 INFO - "/builds/slave/test"] 05:30:14 INFO - --app=APP Application being installed. [default: firefox] 05:30:14 INFO - mkdir: /builds/slave/test/build/application 05:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:30:30 INFO - Reading from file tmpfile_stdout 05:30:30 INFO - Output received: 05:30:30 INFO - /builds/slave/test/build/application/firefox/firefox 05:30:30 INFO - Running post-action listener: _resource_record_post_action 05:30:30 INFO - [mozharness: 2016-08-02 12:30:30.891007Z] Finished install step (success) 05:30:30 INFO - [mozharness: 2016-08-02 12:30:30.891284Z] Running run-tests step. 05:30:30 INFO - Running pre-action listener: _resource_record_pre_action 05:30:30 INFO - Running main action method: run_tests 05:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:30 INFO - Python 2.7.3 05:30:30 INFO - Return code: 0 05:30:30 INFO - grabbing minidump binary from tooltool 05:30:30 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')]} 05:30:30 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 0x1e1e3b0>, '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 0x1e1f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:30 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 05:30:30 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 05:30:30 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 05:30:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:30 INFO - Return code: 0 05:30:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:30:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:30:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 05:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 05:30:30 INFO - Using env: {'DISPLAY': ':0', 05:30:30 INFO - 'HOME': '/home/cltbld', 05:30:30 INFO - 'LANG': 'en_US.UTF-8', 05:30:30 INFO - 'LANGUAGE': 'en_US:en', 05:30:30 INFO - 'LOGNAME': 'cltbld', 05:30:30 INFO - 'MAIL': '/var/mail/cltbld', 05:30:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:30 INFO - 'MOZ_NO_REMOTE': '1', 05:30:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:30:30 INFO - 'NO_EM_RESTART': '1', 05:30:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:30 INFO - 'PWD': '/builds/slave/test', 05:30:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:30 INFO - 'SHELL': '/bin/bash', 05:30:30 INFO - 'SHLVL': '1', 05:30:30 INFO - 'TERM': 'linux', 05:30:30 INFO - 'TMOUT': '86400', 05:30:30 INFO - 'USER': 'cltbld', 05:30:30 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946', 05:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:30 INFO - '_': '/tools/buildbot/bin/python'} 05:30:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 05:30:31 INFO - mozversion application_buildid: 20160802023001 05:30:31 INFO - mozversion application_changeset: 46045ec8a4aa09a341b7209170089ade3c27c1a5 05:30:31 INFO - mozversion application_display_name: Nightly 05:30:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:31 INFO - mozversion application_name: Firefox 05:30:31 INFO - mozversion application_remotingname: firefox 05:30:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:30:31 INFO - mozversion application_vendor: Mozilla 05:30:31 INFO - mozversion application_version: 51.0a1 05:30:31 INFO - mozversion platform_buildid: 20160802023001 05:30:31 INFO - mozversion platform_changeset: 46045ec8a4aa09a341b7209170089ade3c27c1a5 05:30:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:30:31 INFO - mozversion platform_version: 51.0a1 05:30:31 INFO - using testdate: 1470141031 05:30:31 INFO - actual date: 1470141031 05:30:31 INFO - starting webserver on 'localhost:57300' 05:30:31 INFO - SUITE-START | Running 2 tests 05:30:31 INFO - TEST-START | dromaeo_css 05:30:31 INFO - operating with platform_type : linux_ 05:30:31 INFO - Initialising browser for dromaeo_css test... 05:30:31 INFO - TEST-INFO | started process 32656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4SKCfM/profile http://localhost:57300/getInfo.html) 05:30:35 INFO - PROCESS | 32656 | __metrics Screen width/height:1600/1200 05:30:35 INFO - PROCESS | 32656 | colorDepth:24 05:30:35 INFO - PROCESS | 32656 | Browser inner width/height: 1024/697 05:30:35 INFO - PROCESS | 32656 | __metrics 05:30:36 ERROR - PROCESS | 32656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:30:36 INFO - TEST-INFO | 32656: exit 0 05:30:36 INFO - Browser initialized. 05:30:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:36 INFO - TEST-INFO | started process 307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4SKCfM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:48 INFO - PROCESS | 307 | RSS: Main: 163450880 05:30:48 INFO - PROCESS | 307 | 05:31:38 INFO - PROCESS | 307 | 1470141098200 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] 05:33:47 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57300/tests/dromaeo/cssquery-ext.html) 05:33:48 INFO - PROCESS | 307 | RSS: Main: 168304640 05:33:48 INFO - PROCESS | 307 | 05:36:50 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-ext.html (next: http://localhost:57300/tests/dromaeo/cssquery-jquery.html) 05:36:51 INFO - PROCESS | 307 | RSS: Main: 166572032 05:36:51 INFO - PROCESS | 307 | 05:39:52 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57300/tests/dromaeo/cssquery-mootools.html) 05:39:53 INFO - PROCESS | 307 | RSS: Main: 169385984 05:39:53 INFO - PROCESS | 307 | 05:42:55 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57300/tests/dromaeo/cssquery-prototype.html) 05:42:55 INFO - PROCESS | 307 | RSS: Main: 163893248 05:42:55 INFO - PROCESS | 307 | 05:45:58 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57300/tests/dromaeo/cssquery-yui.html) 05:45:59 INFO - PROCESS | 307 | RSS: Main: 166211584 05:45:59 INFO - PROCESS | 307 | 05:49:01 INFO - PROCESS | 307 | Cycle 1(1): loaded http://localhost:57300/tests/dromaeo/cssquery-yui.html (next: http://localhost:57300/tests/dromaeo/cssquery-dojo.html) 05:49:02 INFO - PROCESS | 307 | RSS: Main: 169447424 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | __start_tp_report 05:49:02 INFO - PROCESS | 307 | _x_x_mozilla_page_load 05:49:02 INFO - PROCESS | 307 | _x_x_mozilla_page_load_details 05:49:02 INFO - PROCESS | 307 | |i|pagename|runs| 05:49:02 INFO - PROCESS | 307 | |0;dojo.html;3096.0866544369414;3106.5961425014743;3129.4054129715346;3136.2316232523035;3140.183552276407;5317.734113294339;5444.156155795853;5475.589330800186;5485.451454854518;5528.668279903203;5364.678119312844;5403.675779453226;5413.215083812845;5493.7802487900535;5494.478024587658;4951.975240123795;4985.601151907857;4993.300937868701;5034.395872495287;5039.974800125994;5051.191809310511;5057.26669632903;5110.412302585213;5150.510701483372;5159.277701121827;5044.520770526794;5052.242163675442;5084.51697088776;5100.081985242655;5116.616253780962;4949.554540091373;4970.776315065795;4983.875403114915;5016.122178618763;5061.291419201315;4968.726720030397;4979.875503112451;4998.250262460624;5001.774920128603;5037;5345.09133447311;5483.479069488392;5494.066008778517;5494.780208791643;5524.806631767869;5857.209276747627;5877.52979761618;5901.17383566301;5952.7321270542925;5973.581849270553;5966.940330596682;5975.940240597625;5979.940200597982;5987.670678112702;5993.6104153230335;5295.602829787705;5540.695261760601;5550.222968784374;5559.082751346023;5561.694106824123;5952.523798096142;5973.850653733604;6027.276726792726;6062.484688801413;6087.90417724805;5804.88390232193;5805.332386775491;5818.4763371296785;5825.4174582542255;5829.09649004405;5726.455986681259;5868.207791948118;5875.089361149029;5880.676562789087;5898.793542226002;5609.158626206102;5773.191753154561;5793.594448388657;5820.30156381237;5894.941050589525;5783.306003279549;5869.148973398833;5878.5297176225795;5907.202527658797;5917.64494130345;4522.389477420572;4532.660050496226;4533.342665313511;4538.682292239578;4563.475200352002;3967.4048892665755;3967.6429121379215;3971.185906889081;4006.0385507478577;4080.510338759368;5609.411011843779;5642.266505354278;5657.236273103161;5679.829605111853;5706.885862282731;5912.408759124053;5913;5920.526357891358;5923.141144534018;5931.377205393372;5511.090670039399;5529.170624406291;5564.165375193753;5639.718014099279;5644.74598643062;5108.744562771846;5130.948690513122;5131.61512886535;5158.3552055993005;5171.172612382076;5960.165576819249;5967.254093238345;6021.0968354746265;6028.487578555872;6031;5746;5805.709714514342;5829;5970.701464926649;5976.6712830792985;5961.076033214858;6083.6045657032155;6096.420840020191;6123.938760612336;6129.387061293835;5804.477597016289;5815.709214539172;5815.883682326415;5818.185454036354;5835.708214589169;5753.626014309224;5839.970800145972;5858.794942177004;5888.558358123244;5907.881842363215;5778.422157784188;5787.305523337226;5788.363280039276;5833.620814647034;5833.67914764692;5745.9425405745405;5756.16535602327;5783.681897495678;5786.276715410574;5789.102689083199;5562.694051827189;5581.888362232651;5631.183478395692;5653.858653533694;5720.19917211599;5724.856878578069;5756.971215143897;5769.94230057694;5773.16289138081;5776.364599893925;5558.638688485235;5617.4101719320515;5620.18507316445;5648.491635752637;5659.490645841894 05:49:02 INFO - PROCESS | 307 | |1;ext.html;552.9891435931706;587.820714682022;602.6504627316151;605.5125623872781;609.774383478113;32772.34455310895;44799.776001119986;45819.31271030937;45830.31254531185;45918.08163836721;46316;46609.067818643605;46777.53222467783;46925;46933.0613387732;9026.774330641738;9155.176034156924;9164;9178.86231706525;9180.678676246333;9047.411918225327;9054.547272636375;9101.089891010912;9107.089291070874;9141.725748227564;9078.228350590174;9141;9152.039035901202;9196.310276729238;9203.539823008856;9050.32122590805;9118.726438206864;9124.817503649923;9136.086391360877;9189.448633082036;8858.689945851864;8913.821723565557;8931.687390941286;8932.017478077407;8967.058458861791;43454;43725;43933.340999885026;43946;44188.337174942404;3603.9099022524383;4066.857659981916;4340.370646256275;4354.4992325882295;4454.844080457201;33063.50404743931;33541.49687754686;33580.664193357996;33821.49267760986;34132.14669633279;4379.737215767064;4513.323001549761;4562.977185114053;4596.241620132674;4707.7881495332795;351222;351573;351758;352592.23703881574;356732;59474.40525594775;60010.39989600048;60247.69876150591;60563.39436605666;60831.695841521396;37633;38969.610303896596;64505.67747161234;64819.35180648133;64836.78560893527;17172.65654686899;17245.482635520955;17323.913380433016;17344.219510122075;17395.91302043482;11513.539458421737;11522;11538.94230528842;11568.595099171489;11576.536938522533;19824.405267841987;19825.702614460795;20089.296874609317;20259.696104558443;20266;7392.852142957111;7459.030326057577;7543.8114047148165;7563.243675632392;7746.845063098706;1017.3590637898225;1037.8547003419528;1078.996533224109;1137.8577677790277;1208.0396085112357;31586;31761.205969850478;32716.83641581777;32901;33224.83387583047;1092.7432053467428;1104.1332553945274;1140.5950887434842;1165.9417029148508;1198.9100817438728;16498.58753531147;16989.235484402983;17070.48788536346;17165.313387464612;17174;620632;628321;631998;632727;635939;61732;61953.38046619476;62193.68903155455;62212;62312.01503879679;1345.2869978911192;1370.5134677189653;1375.7661197596433;1388.94444222232;1399.531157062375;30399.39201215919;31483.52774708427;31885.521717173797;32236.19409514687;33246.83376583102;52908.73545632247;52941.7352913233;53061.20408193803;53187;53221;518624;518774;519332;520215;522565;28300.292492687848;29144.56283155712;29229.269268268465;29316.85341573364;29580.408391831614;47233;49245.22373992057;50268.49731502638;50853;51344.91930484033;41441.1711765769;41925.1614967705;42058.78970605128;42176.78911605545;44199;41882.7905860481;42077;42160.789196053825;42409.151816964106;43629;41973.79013104915;42290.36564451474;42805.35791963185;43632.56367436285;45559.77220113878;35377.82311088428;38985.610143899336;39533.20933581255;39576.40635390414;40149;29314.267143321587;29718.8514057437;29719.554206686484;29934.700652994063;29993.4001319968 05:49:02 INFO - PROCESS | 307 | |2;jquery.html;381.3359404259872;381.35170210641917;389.9317619416601;391.669039661486;411.9382092686097;13596;18206.90896545517;18846.905765471187;19725.901370493164;19782.90108549457;15675.529734107995;15915.204239787994;15961.201939902987;18325.7251141233;18853.434396968114;5120.411152717429;5196.636235463519;5259.6055295852775;5263.131583288753;5303.840884773463;5468.043092458824;5570.554355651559;5642.266505354319;5642.887142257153;5656.066748986433;5546.473085056913;5687.090065589507;5741.3110426748635;5752.3384810746675;5760.251167348239;5787.160861675034;5846.123081537782;5850.882982340372;5900.498457631085;5902.61632993854;5582.720863956786;5698.173764804122;5707.885842283131;5709.942900570983;5715.342735585419;12848.229106253655;12976.545820896317;13125.278109703962;13160;13178.736425271536;2216.612092883755;2314.525522267931;2319.7912187903003;2386.570417324881;2540.2633236361435;11539.826902596467;14444.638884027878;14493.78259326111;14506.637334066627;14907.478238261609;1847.4088291746646;1935.7580302462193;1946.4549926020736;1966.4199061276959;1979.7525309336334;64655;71205.6439717798;71750.28249717488;80067.19932800655;80667.59666201631;10665.253432259668;10897.073748731287;11097.944510277397;11121.944390278159;11159.944200278947;14984.325705343286;15038.624034399227;15046.097234165985;15058.397664093533;15074.397024119131;7999.280064794197;8138.593070346459;8239.629216685264;8368.623411946477;8381.664733410717;7643.951478648924;7694.615269236516;7854.293113619688;8174.100848906615;8276.793080173044;4366.628836548929;4393.692441529126;4628.861134165981;4661.906761864744;4763.618550620351;1899.164367678217;1915.8371538419112;1933.825955663969;1987.9900600496906;1998.82010619045;936.8126374725082;948.5921053946731;964.7057647417605;1010.4190090697878;1056.6671498477954;19781.307654232034;19819;19931.701024484646;19999.800002000084;20095.59808803845;950.3062764182185;971.7084874537752;1007.4761124215366;1026.7843752811993;1067.4929408530886;10999.120070394349;11382.94308528452;11467.254628449124;11476.827847582294;11490.94254528722;499334;499721;500634;501585;507364;84633.57683211668;85144.57427712822;89071;89338.10661893428;89392;968.3512046928715;982.5676702250987;996.153269720746;997.581015973293;1007.7833265847878;17327;17716.202770875083;17729;17784.110794460485;18189.909050455193;40246;40667.59332406638;41071.1785764289;41948.58051419569;42135.36796947987;55807.44192558023;56953.71523142358;57433.138502921654;58135.127973079594;62592.37407625865;8085.474444161111;8257.669693212205;8321.334293256647;8341.290990265896;8465.873011904949;13191.274479903697;13698.246596437291;13723.45106195741;13944.233067181402;14100.576982690536;11776.646700598996;11971.401429928643;11991.76016479683;12101.818472723093;12454.065945053975;11388.943055285003;11589.6523104307;11829.112816538622;11916.463759130687;12140.817887731868;11969.16215864881;12130;12196.695082623006;12367.876321236672;12438.937805310916;8029.478083924526;8107.83784324322;8328.791780205544;8429.747107586782;8433.36749743784;9307.953460232655;11832.58585949522;12040.75918481643;12065.09511786637;12273.815892761795 05:49:02 INFO - PROCESS | 307 | |3;mootools.html;1512.470635277653;1519.9696006079878;1642.6468309313495;1759.9440335798515;1763.6560870630233;7857.882131768028;8409.117042710512;8641.351898607614;9076.001639819622;9153.313501487399;8728.60721267543;8984.775380615489;9012;9030.593623286952;9049.547522623876;2347.8708671023087;2364.385259832439;2367.633016882386;2367.893444794988;2375.061850569024;2292.3008482412856;2304.976950230501;2351.5179388225374;2360.8819559022068;2367.159658321297;2325.104834638659;2334.8599084055013;2337.030132495021;2342.5197834443984;2346.495503466754;2265.275111964172;2267.285804971437;2279.9772002280015;2282.395165281202;2351.9647205291935;2270.398344438725;2271.5911135995516;2276.8633881967135;2300.344401845466;2301.3326135420707;8747.518886461243;8781.64873405063;8831.735047948507;8834.469931804048;8856.51289179095;5226.634135610509;5325.866853328698;5358.598105142091;5361.463853614647;5370.382406023318;3214.6624604416666;3345.1107240607116;3450.4824276358745;3504.754667173299;3520.383932811748;1089.3300620118648;1090.8854570270166;1096.116560766321;1105.4748994227757;1127.6166103524758;12533.623991280185;12549.058820588401;12571.874281257162;12575.05687073464;12609.873901260962;4467.620252278528;4551.726896386162;4675.322078298627;4718.504557021532;4744.288356746447;10443.164546836235;10509.211809114347;10515.57937682484;10524.736881578021;10548.367097973993;5171.4052883918075;5203.141481655484;5212.087884620177;5222.843314700565;5245.10833158364;4276.764777937181;4690.366800481891;4749.525047495223;4779.784909679072;4847.878803029953;3926.862559810393;3947.4999500189742;4007.815740421477;4075.898102547401;4081.653059489977;1294.6698591859015;1305.3538498443331;1313.9802902956462;1321.253491777162;1324.43711422645;845.0324378586484;902.251131560803;927.1007123090591;941.6657086734248;981.5582987655583;5892.175095486636;6269.059641053878;6351.920173570504;6354;6643.800685979429;953.1659797677032;963.6675347005272;979.8187335342842;987.6543209876629;1037.4138611684525;4120.587941205856;4327.307630779061;4421.204183246982;4496.3255511673515;4532.206863798822;11038.668839934815;11463.598774042868;11469.426528673534;11480.483378247998;11508.94245528767;10975.396353200651;11028.889711102947;11189.160812939062;11222.607208747655;11239.83140252897;7794.259545343183;8373.958130209432;8468.237858592633;8487.321014318839;8489.915100848912;6089.147519347206;6213.627182369072;6227.50179985591;6258.468030217483;6535.411812936859;10505.579776808841;10517.474126293808;10611.681649550217;10659.200559957879;10670.786584268426;10122.848157277795;10314.329568578018;10353.327033742782;10545.36727796304;10566.735831603964;7747.457677962714;8295.294899933573;8420.11588783169;8453.196946290092;8680.696175633822;8781;8860.911390886009;8895.644174232959;8918.152775486318;8922.107789221025;7358.11702595692;7606.771796846104;7644.655990480331;7666.6166691664075;7888.684452621831;7317.963410182915;7352.742654007084;7501.137369202612;7637.427192960439;7723.189065148079;7079.256678048937;7236.023136876454;7243.45674074436;7251.093613298338;7265.854682906418;5451.1005684063275;5521.309836270466;5545.889082218414;5618.747156377891;5668.26312579362;5477.589180811376;5568.749406276808;5593.328800543798;5620.718964051863;5672.688002159921 05:49:02 INFO - PROCESS | 307 | |4;prototype.html;304.7957868228287;307.3376872839032;312.4999999999999;314.3791012749818;315.43537068647123;5570.331560212775;5596.944030559704;5624.072028115356;5627.099664053752;5652.802151924683;5472.315960504937;5479.972600136994;5519.199716041172;5567.8608034799045;5588.6646801191955;4070.837166513343;4137.710360274782;4241.363795430695;4255.9787201063955;4274.914501709979;4260.914781704364;4263.531011588722;4274.764887931163;4287.078278170193;4306.7631280279575;4226.112516371566;4289.6997210195295;4291.313389857623;4291.549387314334;4300.505441874177;4041.5958404159496;4203.726757760766;4234.978825105854;4244.639205667522;4300.612944834964;3687.8340474678434;3702.0374702577255;3705.2959937611768;3723.143676954289;3728.9627103728885;5370.650907691027;5383.923215356945;5404.837854864321;5416.241726158341;5436.972815135899;4514.984128571078;4703.035877645075;4762.976185119087;4845.975770121162;4851.660383773137;5696.829095127153;5700.37295897451;5711.600187986843;5765.279340082489;5775.220345253421;3888.183481468895;3888.9222215555815;3896.123372241223;3898.785566793825;3927.764334139932;5891.91162132564;5952.464278214981;5966.731497082642;5975.850603734898;6107.297660769024;4100.507939047274;4108.424820525129;4115.753054816721;4143.917121657611;4151.8339266429;4155.459790227251;4179.832806687759;4191.832326706897;4206.495220573551;4228;4747.264174053027;4758.477287268041;4786.8085276588545;4853.466118727008;4855.902881942412;5734.340550836707;5738.913916291259;5743.167240750068;5747.339056008529;5757;1402.4179965314281;1405.2552147361914;1408.978865317021;1411.1533080151826;1415.5965549818252;917.9947956987088;922.2207234886511;933.425943045036;940.3529065680088;949.6106596295624;5438.428964958702;5529.889402211933;5569.944300556943;5578.330600327987;5629.211910332557;5786.334571524329;5794.88410231793;5890.587658863838;5949.9107513387335;5961.433663801932;5461.781528738886;5467.552631184829;5492.615516913857;5497.47773961473;5653.378128405872;3112.863804711264;3132.9060128196193;3133.9529907051415;3137.8744850205735;3182.98408507956;5777.815547812688;6099.817005489754;6105.001224198158;6132.202813634199;6147.815565532952;6057.666828324396;6093.817185484442;6105.694715264219;6127.816165515042;6180.721867515973;6218;6236.220472440945;6245.812625621239;6257.499400047986;6259.186305780128;5671.943280567307;5906.40935906406;5985.371535988658;6002.519798416029;6059.818205453844;5941.19793827845;5965.463108320272;5973.611715238496;5987.64074155552;5988.8802223956145;6009.489193418433;6022.578419510767;6168.095476564658;6251.4061164189325;6264.154339164337;3970.4838371011583;3971.781552014667;3974.8012599369335;3978.681705463498;4018.65841403484;6013.308469525886;6016.458518733335;6026.186464827192;6039.305479869871;6053.576249662481;5757.337906140848;5789.3921138279875;5802.361740208658;5812.534997200299;5850.824475265749;5793.246877905845;5864.179014937998;5868.354481007171;5884.411558844081;5884.852878678067;5182.922256166085;5707.942920570741;5726;5778.537716982547;5789.681567513827;5763.682997435014;5797.304323481209;5803.82588522345;5826;5862.970685146547;5739.253896993364;5741.45456181662;5763.464075806632;5775.422457754191;5776.768929242783 05:49:02 INFO - PROCESS | 307 | |5;yui.html;730.8538292341533;756.8107973006748;758.4993904023344;787.4133770341095;804.6861723927666;15429.922850385747;16265.593360166002;16347;16390.508284751446;16423.014619122845;14519.5644130676;14676.412943482248;14786.18675972821;14818.629534261649;14886.255687215624;652.1977967100469;789.826238227589;792.9484583502068;806.1293802693077;819.4632515702194;805.2108933245394;805.60525342582;808.1474044882647;808.5916612110876;810.0846043970334;861.9827603447927;864.6238886084542;864.6498168241872;865.4288169807896;866.7053201911349;854.2738672128703;860.5611138319503;862.9611667474948;864.2869632553137;865.277493293101;617.2346290599669;624.4660815003198;625.32464937867;625.4027403829332;626.7336382037615;14200.57398278043;14247.78628320576;14313.856861431357;14330.426782928671;14357;14875.925620371829;15139.697206055816;15159.696806063926;15163.545093647208;15346.693066138614;12736.872631273662;12740.745185096339;12797.872021279762;12808.871911280954;12837.550685726048;3961.6830653547645;3969.2458432897656;4023.3361495353233;4062.837486500536;4105.34314509677;60269.698651506464;75196;76618;79221.60389198075;80141;11715.76568468626;11754.05967522596;11841.940790295994;11848.526058957717;11910.940445297718;21466.892665536885;21523.67714484284;21657.566848663253;21673.89163054206;21707.674384884238;8738.69414570487;8793.252573531321;8829.955850220707;8891.333150013776;9024.593893274818;4844.636652251095;5032.169692000852;5036.143855544493;5118;5125.923111153335;3704.759190652599;3805.891544071725;3854.3062248794927;3858.884233473;3867.477890484805;634.5621521150391;645.1677336236296;651.0137142229526;661.1933441201726;665.0489799586637;693.6011793218919;728.7230852276101;746.6901235987004;752.3792870881545;784.5920121536883;10250.692479225636;10668.413237272025;10810.56757729682;10836.566537338576;10996.780064398667;659.8152517295069;713.5861200503696;744.6128013432984;759.4038679636459;824.7360844529812;5075.644704870623;5114.667546609533;5190.662606930538;5230.738463076831;5262.184361423909;67328;69480.3051969484;76716.61641691756;122413.38793306155;123651;58192.709036455395;58401;59106.11340829805;97020;101695;60019.79861409747;90439.54780226057;91398;92974;97211.99999999859;9600.423974561549;9707.466089365153;9867.259955503223;9878.703638890845;9978.850317245102;48670.51329486801;49204.75397622989;49390.50609494003;50196;50402.747986259834;68831.31168688249;69749.65125174342;77757;79101.60449197717;81918.59040704758;10763.730906727395;11111.944215340845;11947.761044778881;12429.75140497203;12724.236545807282;15338.693226135638;15378;15754.126578248171;15799.68400632004;16899.563537099486;15075;15080;15173.468928587446;15574.610634733768;15983.600409989844;14457.710845783236;14541.781873271697;14642.780358294422;15353.155576443403;15780.97423667458;14332.283385830875;14427.42290308376;14593.781093283822;15261.771073433685;15271.465498707492;11543.76912461763;11570.305781653127;11600.593979210687;11921.701957450787;12326.876731232573;11668.183227174355;12168.513259469522;12231.75536489283;12587.118901676795;12616.243025418504 05:49:02 INFO - PROCESS | 307 | __end_tp_report 05:49:02 INFO - PROCESS | 307 | __start_cc_report 05:49:02 INFO - PROCESS | 307 | _x_x_mozilla_cycle_collect,367 05:49:02 INFO - PROCESS | 307 | __end_cc_report 05:49:02 INFO - PROCESS | 307 | __startTimestamp1470142142087__endTimestamp 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | ------- Summary: start ------- 05:49:02 INFO - PROCESS | 307 | Number of tests: 6 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#0] dojo.html Cycles:165 Average:5467.30 Median:5659.49 stddev:620.55 (11.0%) stddev-sans-first:593.92 05:49:02 INFO - PROCESS | 307 | Values: 3096.1 3106.6 3129.4 3136.2 3140.2 5317.7 5444.2 5475.6 5485.5 5528.7 5364.7 5403.7 5413.2 5493.8 5494.5 4952.0 4985.6 4993.3 5034.4 5040.0 5051.2 5057.3 5110.4 5150.5 5159.3 5044.5 5052.2 5084.5 5100.1 5116.6 4949.6 4970.8 4983.9 5016.1 5061.3 4968.7 4979.9 4998.3 5001.8 5037.0 5345.1 5483.5 5494.1 5494.8 5524.8 5857.2 5877.5 5901.2 5952.7 5973.6 5966.9 5975.9 5979.9 5987.7 5993.6 5295.6 5540.7 5550.2 5559.1 5561.7 5952.5 5973.9 6027.3 6062.5 6087.9 5804.9 5805.3 5818.5 5825.4 5829.1 5726.5 5868.2 5875.1 5880.7 5898.8 5609.2 5773.2 5793.6 5820.3 5894.9 5783.3 5869.1 5878.5 5907.2 5917.6 4522.4 4532.7 4533.3 4538.7 4563.5 3967.4 3967.6 3971.2 4006.0 4080.5 5609.4 5642.3 5657.2 5679.8 5706.9 5912.4 5913.0 5920.5 5923.1 5931.4 5511.1 5529.2 5564.2 5639.7 5644.7 5108.7 5130.9 5131.6 5158.4 5171.2 5960.2 5967.3 6021.1 6028.5 6031.0 5746.0 5805.7 5829.0 5970.7 5976.7 5961.1 6083.6 6096.4 6123.9 6129.4 5804.5 5815.7 5815.9 5818.2 5835.7 5753.6 5840.0 5858.8 5888.6 5907.9 5778.4 5787.3 5788.4 5833.6 5833.7 5745.9 5756.2 5783.7 5786.3 5789.1 5562.7 5581.9 5631.2 5653.9 5720.2 5724.9 5757.0 5769.9 5773.2 5776.4 5558.6 5617.4 5620.2 5648.5 5659.5 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#1] ext.html Cycles:180 Average:65855.61 Median:43678.78 stddev:136890.25 (313.4%) stddev-sans-first:137186.46 05:49:02 INFO - PROCESS | 307 | Values: 553.0 587.8 602.7 605.5 609.8 32772.3 44799.8 45819.3 45830.3 45918.1 46316.0 46609.1 46777.5 46925.0 46933.1 9026.8 9155.2 9164.0 9178.9 9180.7 9047.4 9054.5 9101.1 9107.1 9141.7 9078.2 9141.0 9152.0 9196.3 9203.5 9050.3 9118.7 9124.8 9136.1 9189.4 8858.7 8913.8 8931.7 8932.0 8967.1 43454.0 43725.0 43933.3 43946.0 44188.3 3603.9 4066.9 4340.4 4354.5 4454.8 33063.5 33541.5 33580.7 33821.5 34132.1 4379.7 4513.3 4563.0 4596.2 4707.8 351222.0 351573.0 351758.0 352592.2 356732.0 59474.4 60010.4 60247.7 60563.4 60831.7 37633.0 38969.6 64505.7 64819.4 64836.8 17172.7 17245.5 17323.9 17344.2 17395.9 11513.5 11522.0 11538.9 11568.6 11576.5 19824.4 19825.7 20089.3 20259.7 20266.0 7392.9 7459.0 7543.8 7563.2 7746.8 1017.4 1037.9 1079.0 1137.9 1208.0 31586.0 31761.2 32716.8 32901.0 33224.8 1092.7 1104.1 1140.6 1165.9 1198.9 16498.6 16989.2 17070.5 17165.3 17174.0 620632.0 628321.0 631998.0 632727.0 635939.0 61732.0 61953.4 62193.7 62212.0 62312.0 1345.3 1370.5 1375.8 1388.9 1399.5 30399.4 31483.5 31885.5 32236.2 33246.8 52908.7 52941.7 53061.2 53187.0 53221.0 518624.0 518774.0 519332.0 520215.0 522565.0 28300.3 29144.6 29229.3 29316.9 29580.4 47233.0 49245.2 50268.5 50853.0 51344.9 41441.2 41925.2 42058.8 42176.8 44199.0 41882.8 42077.0 42160.8 42409.2 43629.0 41973.8 42290.4 42805.4 43632.6 45559.8 35377.8 38985.6 39533.2 39576.4 40149.0 29314.3 29718.9 29719.6 29934.7 29993.4 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#2] jquery.html Cycles:180 Average:28767.44 Median:381.34 stddev:82481.96 (21629.3%) stddev-sans-first:82685.80 05:49:02 INFO - PROCESS | 307 | Values: 381.3 381.4 389.9 391.7 411.9 13596.0 18206.9 18846.9 19725.9 19782.9 15675.5 15915.2 15961.2 18325.7 18853.4 5120.4 5196.6 5259.6 5263.1 5303.8 5468.0 5570.6 5642.3 5642.9 5656.1 5546.5 5687.1 5741.3 5752.3 5760.3 5787.2 5846.1 5850.9 5900.5 5902.6 5582.7 5698.2 5707.9 5709.9 5715.3 12848.2 12976.5 13125.3 13160.0 13178.7 2216.6 2314.5 2319.8 2386.6 2540.3 11539.8 14444.6 14493.8 14506.6 14907.5 1847.4 1935.8 1946.5 1966.4 1979.8 64655.0 71205.6 71750.3 80067.2 80667.6 10665.3 10897.1 11097.9 11121.9 11159.9 14984.3 15038.6 15046.1 15058.4 15074.4 7999.3 8138.6 8239.6 8368.6 8381.7 7644.0 7694.6 7854.3 8174.1 8276.8 4366.6 4393.7 4628.9 4661.9 4763.6 1899.2 1915.8 1933.8 1988.0 1998.8 936.8 948.6 964.7 1010.4 1056.7 19781.3 19819.0 19931.7 19999.8 20095.6 950.3 971.7 1007.5 1026.8 1067.5 10999.1 11382.9 11467.3 11476.8 11490.9 499334.0 499721.0 500634.0 501585.0 507364.0 84633.6 85144.6 89071.0 89338.1 89392.0 968.4 982.6 996.2 997.6 1007.8 17327.0 17716.2 17729.0 17784.1 18189.9 40246.0 40667.6 41071.2 41948.6 42135.4 55807.4 56953.7 57433.1 58135.1 62592.4 8085.5 8257.7 8321.3 8341.3 8465.9 13191.3 13698.2 13723.5 13944.2 14100.6 11776.6 11971.4 11991.8 12101.8 12454.1 11388.9 11589.7 11829.1 11916.5 12140.8 11969.2 12130.0 12196.7 12367.9 12438.9 8029.5 8107.8 8328.8 8429.7 8433.4 9308.0 11832.6 12040.8 12065.1 12273.8 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#3] mootools.html Cycles:180 Average:5937.82 Median:4704.44 stddev:3388.00 (72.0%) stddev-sans-first:3381.19 05:49:02 INFO - PROCESS | 307 | Values: 1512.5 1520.0 1642.6 1759.9 1763.7 7857.9 8409.1 8641.4 9076.0 9153.3 8728.6 8984.8 9012.0 9030.6 9049.5 2347.9 2364.4 2367.6 2367.9 2375.1 2292.3 2305.0 2351.5 2360.9 2367.2 2325.1 2334.9 2337.0 2342.5 2346.5 2265.3 2267.3 2280.0 2282.4 2352.0 2270.4 2271.6 2276.9 2300.3 2301.3 8747.5 8781.6 8831.7 8834.5 8856.5 5226.6 5325.9 5358.6 5361.5 5370.4 3214.7 3345.1 3450.5 3504.8 3520.4 1089.3 1090.9 1096.1 1105.5 1127.6 12533.6 12549.1 12571.9 12575.1 12609.9 4467.6 4551.7 4675.3 4718.5 4744.3 10443.2 10509.2 10515.6 10524.7 10548.4 5171.4 5203.1 5212.1 5222.8 5245.1 4276.8 4690.4 4749.5 4779.8 4847.9 3926.9 3947.5 4007.8 4075.9 4081.7 1294.7 1305.4 1314.0 1321.3 1324.4 845.0 902.3 927.1 941.7 981.6 5892.2 6269.1 6351.9 6354.0 6643.8 953.2 963.7 979.8 987.7 1037.4 4120.6 4327.3 4421.2 4496.3 4532.2 11038.7 11463.6 11469.4 11480.5 11508.9 10975.4 11028.9 11189.2 11222.6 11239.8 7794.3 8374.0 8468.2 8487.3 8489.9 6089.1 6213.6 6227.5 6258.5 6535.4 10505.6 10517.5 10611.7 10659.2 10670.8 10122.8 10314.3 10353.3 10545.4 10566.7 7747.5 8295.3 8420.1 8453.2 8680.7 8781.0 8860.9 8895.6 8918.2 8922.1 7358.1 7606.8 7644.7 7666.6 7888.7 7318.0 7352.7 7501.1 7637.4 7723.2 7079.3 7236.0 7243.5 7251.1 7265.9 5451.1 5521.3 5545.9 5618.7 5668.3 5477.6 5568.7 5593.3 5620.7 5672.7 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#4] prototype.html Cycles:180 Average:4846.65 Median:5610.51 stddev:1468.86 (26.2%) stddev-sans-first:1432.87 05:49:02 INFO - PROCESS | 307 | Values: 304.8 307.3 312.5 314.4 315.4 5570.3 5596.9 5624.1 5627.1 5652.8 5472.3 5480.0 5519.2 5567.9 5588.7 4070.8 4137.7 4241.4 4256.0 4274.9 4260.9 4263.5 4274.8 4287.1 4306.8 4226.1 4289.7 4291.3 4291.5 4300.5 4041.6 4203.7 4235.0 4244.6 4300.6 3687.8 3702.0 3705.3 3723.1 3729.0 5370.7 5383.9 5404.8 5416.2 5437.0 4515.0 4703.0 4763.0 4846.0 4851.7 5696.8 5700.4 5711.6 5765.3 5775.2 3888.2 3888.9 3896.1 3898.8 3927.8 5891.9 5952.5 5966.7 5975.9 6107.3 4100.5 4108.4 4115.8 4143.9 4151.8 4155.5 4179.8 4191.8 4206.5 4228.0 4747.3 4758.5 4786.8 4853.5 4855.9 5734.3 5738.9 5743.2 5747.3 5757.0 1402.4 1405.3 1409.0 1411.2 1415.6 918.0 922.2 933.4 940.4 949.6 5438.4 5529.9 5569.9 5578.3 5629.2 5786.3 5794.9 5890.6 5949.9 5961.4 5461.8 5467.6 5492.6 5497.5 5653.4 3112.9 3132.9 3134.0 3137.9 3183.0 5777.8 6099.8 6105.0 6132.2 6147.8 6057.7 6093.8 6105.7 6127.8 6180.7 6218.0 6236.2 6245.8 6257.5 6259.2 5671.9 5906.4 5985.4 6002.5 6059.8 5941.2 5965.5 5973.6 5987.6 5988.9 6009.5 6022.6 6168.1 6251.4 6264.2 3970.5 3971.8 3974.8 3978.7 4018.7 6013.3 6016.5 6026.2 6039.3 6053.6 5757.3 5789.4 5802.4 5812.5 5850.8 5793.2 5864.2 5868.4 5884.4 5884.9 5182.9 5707.9 5726.0 5778.5 5789.7 5763.7 5797.3 5803.8 5826.0 5863.0 5739.3 5741.5 5763.5 5775.4 5776.8 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - PROCESS | 307 | [#5] yui.html Cycles:180 Average:19725.66 Median:49297.63 stddev:26909.07 (54.6%) stddev-sans-first:26946.76 05:49:02 INFO - PROCESS | 307 | Values: 730.9 756.8 758.5 787.4 804.7 15429.9 16265.6 16347.0 16390.5 16423.0 14519.6 14676.4 14786.2 14818.6 14886.3 652.2 789.8 792.9 806.1 819.5 805.2 805.6 808.1 808.6 810.1 862.0 864.6 864.6 865.4 866.7 854.3 860.6 863.0 864.3 865.3 617.2 624.5 625.3 625.4 626.7 14200.6 14247.8 14313.9 14330.4 14357.0 14875.9 15139.7 15159.7 15163.5 15346.7 12736.9 12740.7 12797.9 12808.9 12837.6 3961.7 3969.2 4023.3 4062.8 4105.3 60269.7 75196.0 76618.0 79221.6 80141.0 11715.8 11754.1 11841.9 11848.5 11910.9 21466.9 21523.7 21657.6 21673.9 21707.7 8738.7 8793.3 8830.0 8891.3 9024.6 4844.6 5032.2 5036.1 5118.0 5125.9 3704.8 3805.9 3854.3 3858.9 3867.5 634.6 645.2 651.0 661.2 665.0 693.6 728.7 746.7 752.4 784.6 10250.7 10668.4 10810.6 10836.6 10996.8 659.8 713.6 744.6 759.4 824.7 5075.6 5114.7 5190.7 5230.7 5262.2 67328.0 69480.3 76716.6 122413.4 123651.0 58192.7 58401.0 59106.1 97020.0 101695.0 60019.8 90439.5 91398.0 92974.0 97212.0 9600.4 9707.5 9867.3 9878.7 9978.9 48670.5 49204.8 49390.5 50196.0 50402.7 68831.3 69749.7 77757.0 79101.6 81918.6 10763.7 11111.9 11947.8 12429.8 12724.2 15338.7 15378.0 15754.1 15799.7 16899.6 15075.0 15080.0 15173.5 15574.6 15983.6 14457.7 14541.8 14642.8 15353.2 15781.0 14332.3 14427.4 14593.8 15261.8 15271.5 11543.8 11570.3 11600.6 11921.7 12326.9 11668.2 12168.5 12231.8 12587.1 12616.2 05:49:02 INFO - PROCESS | 307 | -------- Summary: end -------- 05:49:02 INFO - PROCESS | 307 | 05:49:02 INFO - TEST-INFO | 307: exit 0 05:49:02 INFO - TEST-OK | dromaeo_css | took 1111635ms 05:49:02 INFO - TEST-START | kraken 05:49:02 INFO - operating with platform_type : linux_ 05:49:02 INFO - Initialising browser for kraken test... 05:49:02 INFO - TEST-INFO | started process 557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_bvTI/profile http://localhost:57300/getInfo.html) 05:49:07 INFO - PROCESS | 557 | __metrics Screen width/height:1600/1200 05:49:07 INFO - PROCESS | 557 | colorDepth:24 05:49:07 INFO - PROCESS | 557 | Browser inner width/height: 1024/697 05:49:07 INFO - PROCESS | 557 | __metrics 05:49:07 ERROR - PROCESS | 557 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:49:08 INFO - TEST-INFO | 557: exit 0 05:49:08 INFO - Browser initialized. 05:49:08 INFO - Running cycle 1/1 for kraken test... 05:49:08 INFO - TEST-INFO | started process 676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx_bvTI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:20 INFO - PROCESS | 676 | RSS: Main: 163385344 05:49:20 INFO - PROCESS | 676 | 05:49:23 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57300/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:23 INFO - PROCESS | 676 | RSS: Main: 169033728 05:49:23 INFO - PROCESS | 676 | 05:49:26 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57300/tests/kraken/driver.html?testName=audio-dft) 05:49:27 INFO - PROCESS | 676 | RSS: Main: 167485440 05:49:27 INFO - PROCESS | 676 | 05:49:30 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57300/tests/kraken/driver.html?testName=audio-fft) 05:49:30 INFO - PROCESS | 676 | RSS: Main: 163835904 05:49:30 INFO - PROCESS | 676 | 05:49:32 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57300/tests/kraken/driver.html?testName=audio-oscillator) 05:49:33 INFO - PROCESS | 676 | RSS: Main: 165052416 05:49:33 INFO - PROCESS | 676 | 05:49:35 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57300/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:36 INFO - PROCESS | 676 | RSS: Main: 165580800 05:49:36 INFO - PROCESS | 676 | 05:49:39 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57300/tests/kraken/driver.html?testName=imaging-darkroom) 05:49:40 INFO - PROCESS | 676 | RSS: Main: 165838848 05:49:40 INFO - PROCESS | 676 | 05:49:43 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57300/tests/kraken/driver.html?testName=imaging-desaturate) 05:49:44 INFO - PROCESS | 676 | RSS: Main: 162963456 05:49:44 INFO - PROCESS | 676 | 05:49:47 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57300/tests/kraken/driver.html?testName=json-parse-financial) 05:49:47 INFO - PROCESS | 676 | RSS: Main: 164941824 05:49:47 INFO - PROCESS | 676 | 05:49:49 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57300/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:49:49 INFO - PROCESS | 676 | RSS: Main: 164909056 05:49:49 INFO - PROCESS | 676 | 05:49:51 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:52 INFO - PROCESS | 676 | RSS: Main: 165109760 05:49:52 INFO - PROCESS | 676 | 05:49:54 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:55 INFO - PROCESS | 676 | RSS: Main: 166346752 05:49:55 INFO - PROCESS | 676 | 05:49:57 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:58 INFO - PROCESS | 676 | RSS: Main: 166006784 05:49:58 INFO - PROCESS | 676 | 05:50:01 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:01 INFO - PROCESS | 676 | RSS: Main: 166113280 05:50:01 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | Cycle 1(1): loaded http://localhost:57300/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57300/tests/kraken/driver.html?testName=ai-astar) 05:50:03 INFO - PROCESS | 676 | RSS: Main: 166440960 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | __start_tp_report 05:50:03 INFO - PROCESS | 676 | _x_x_mozilla_page_load 05:50:03 INFO - PROCESS | 676 | _x_x_mozilla_page_load_details 05:50:03 INFO - PROCESS | 676 | |i|pagename|runs| 05:50:03 INFO - PROCESS | 676 | |0;ai-astar;102;111;107;111;113;114;112;112;111;112 05:50:03 INFO - PROCESS | 676 | |1;audio-beat-detection;154;119;148;183;120;152;149;123;190;119 05:50:03 INFO - PROCESS | 676 | |2;audio-dft;162;164;161;162;163;165;169;162;162;161 05:50:03 INFO - PROCESS | 676 | |3;audio-fft;74;74;77;180;78;75;75;76;171;76 05:50:03 INFO - PROCESS | 676 | |4;audio-oscillator;85;84;165;87;85;85;141;84;83;140 05:50:03 INFO - PROCESS | 676 | |5;imaging-gaussian-blur;117;117;118;117;118;117;117;117;117;118 05:50:03 INFO - PROCESS | 676 | |6;imaging-darkroom;148;147;147;147;147;148;147;147;146;147 05:50:03 INFO - PROCESS | 676 | |7;imaging-desaturate;105;97;95;96;95;96;96;96;96;95 05:50:03 INFO - PROCESS | 676 | |8;json-parse-financial;54;52;53;54;54;52;53;54;52;52 05:50:03 INFO - PROCESS | 676 | |9;json-stringify-tinderbox;65;64;63;64;68;68;64;64;64;64 05:50:03 INFO - PROCESS | 676 | |10;stanford-crypto-aes;76;82;76;86;77;76;76;78;85;82 05:50:03 INFO - PROCESS | 676 | |11;stanford-crypto-ccm;138;128;119;144;136;134;122;122;165;135 05:50:03 INFO - PROCESS | 676 | |12;stanford-crypto-pbkdf2;166;163;163;163;158;161;157;158;160;156 05:50:03 INFO - PROCESS | 676 | |13;stanford-crypto-sha256-iterative;67;64;64;63;63;63;62;64;64;63 05:50:03 INFO - PROCESS | 676 | __end_tp_report 05:50:03 INFO - PROCESS | 676 | __start_cc_report 05:50:03 INFO - PROCESS | 676 | _x_x_mozilla_cycle_collect,885 05:50:03 INFO - PROCESS | 676 | __end_cc_report 05:50:03 INFO - PROCESS | 676 | __startTimestamp1470142203577__endTimestamp 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | ------- Summary: start ------- 05:50:03 INFO - PROCESS | 676 | Number of tests: 14 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:3.50 (3.1%) stddev-sans-first:1.94 05:50:03 INFO - PROCESS | 676 | Values: 102.0 111.0 107.0 111.0 113.0 114.0 112.0 112.0 111.0 112.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:150.50 stddev:25.99 (17.3%) stddev-sans-first:27.39 05:50:03 INFO - PROCESS | 676 | Values: 154.0 119.0 148.0 183.0 120.0 152.0 149.0 123.0 190.0 119.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#2] audio-dft Cycles:10 Average:163.10 Median:162.50 stddev:2.42 (1.5%) stddev-sans-first:2.54 05:50:03 INFO - PROCESS | 676 | Values: 162.0 164.0 161.0 162.0 163.0 165.0 169.0 162.0 162.0 161.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#3] audio-fft Cycles:10 Average:95.60 Median:75.50 stddev:42.18 (55.9%) stddev-sans-first:44.01 05:50:03 INFO - PROCESS | 676 | Values: 74.0 74.0 77.0 180.0 78.0 75.0 75.0 76.0 171.0 76.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#4] audio-oscillator Cycles:10 Average:103.90 Median:84.50 stddev:31.62 (37.4%) stddev-sans-first:32.79 05:50:03 INFO - PROCESS | 676 | Values: 85.0 84.0 165.0 87.0 85.0 85.0 141.0 84.0 83.0 140.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:50:03 INFO - PROCESS | 676 | Values: 117.0 117.0 118.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 05:50:03 INFO - PROCESS | 676 | Values: 148.0 147.0 147.0 147.0 147.0 148.0 147.0 147.0 146.0 147.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.00 stddev:2.98 (3.1%) stddev-sans-first:0.67 05:50:03 INFO - PROCESS | 676 | Values: 105.0 97.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 95.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.50 stddev:0.94 (1.8%) stddev-sans-first:0.93 05:50:03 INFO - PROCESS | 676 | Values: 54.0 52.0 53.0 54.0 54.0 52.0 53.0 54.0 52.0 52.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:64.00 stddev:1.75 (2.7%) stddev-sans-first:1.86 05:50:03 INFO - PROCESS | 676 | Values: 65.0 64.0 63.0 64.0 68.0 68.0 64.0 64.0 64.0 64.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:80.00 stddev:3.98 (5.0%) stddev-sans-first:4.02 05:50:03 INFO - PROCESS | 676 | Values: 76.0 82.0 76.0 86.0 77.0 76.0 76.0 78.0 85.0 82.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:135.50 stddev:13.46 (9.9%) stddev-sans-first:14.21 05:50:03 INFO - PROCESS | 676 | Values: 138.0 128.0 119.0 144.0 136.0 134.0 122.0 122.0 165.0 135.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:162.00 stddev:3.24 (2.0%) stddev-sans-first:2.76 05:50:03 INFO - PROCESS | 676 | Values: 166.0 163.0 163.0 163.0 158.0 161.0 157.0 158.0 160.0 156.0 05:50:03 INFO - PROCESS | 676 | 05:50:03 INFO - PROCESS | 676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.34 (2.1%) stddev-sans-first:0.71 05:50:03 INFO - PROCESS | 676 | Values: 67.0 64.0 64.0 63.0 63.0 63.0 62.0 64.0 64.0 63.0 05:50:03 INFO - PROCESS | 676 | -------- Summary: end -------- 05:50:03 INFO - PROCESS | 676 | 05:50:04 INFO - TEST-INFO | 676: exit 0 05:50:04 INFO - TEST-OK | kraken | took 61760ms 05:50:04 INFO - SUITE-END | took 1173s 05:50:04 INFO - Completed test suite (00:19:33) 05:50:04 INFO - javascript benchmark 05:50:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7390.357840471627, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3096.0866544369414, 3106.5961425014743, 3129.4054129715346, 3136.2316232523035, 3140.183552276407, 5317.734113294339, 5444.156155795853, 5475.589330800186, 5485.451454854518, 5528.668279903203, 5364.678119312844, 5403.675779453226, 5413.215083812845, 5493.7802487900535, 5494.478024587658, 4951.975240123795, 4985.601151907857, 4993.300937868701, 5034.395872495287, 5039.974800125994, 5051.191809310511, 5057.26669632903, 5110.412302585213, 5150.510701483372, 5159.277701121827, 5044.520770526794, 5052.242163675442, 5084.51697088776, 5100.081985242655, 5116.616253780962, 4949.554540091373, 4970.776315065795, 4983.875403114915, 5016.122178618763, 5061.291419201315, 4968.726720030397, 4979.875503112451, 4998.250262460624, 5001.774920128603, 5037.0, 5345.09133447311, 5483.479069488392, 5494.066008778517, 5494.780208791643, 5524.806631767869, 5857.209276747627, 5877.52979761618, 5901.17383566301, 5952.7321270542925, 5973.581849270553, 5966.940330596682, 5975.940240597625, 5979.940200597982, 5987.670678112702, 5993.6104153230335, 5295.602829787705, 5540.695261760601, 5550.222968784374, 5559.082751346023, 5561.694106824123, 5952.523798096142, 5973.850653733604, 6027.276726792726, 6062.484688801413, 6087.90417724805, 5804.88390232193, 5805.332386775491, 5818.4763371296785, 5825.4174582542255, 5829.09649004405, 5726.455986681259, 5868.207791948118, 5875.089361149029, 5880.676562789087, 5898.793542226002, 5609.158626206102, 5773.191753154561, 5793.594448388657, 5820.30156381237, 5894.941050589525, 5783.306003279549, 5869.148973398833, 5878.5297176225795, 5907.202527658797, 5917.64494130345, 4522.389477420572, 4532.660050496226, 4533.342665313511, 4538.682292239578, 4563.475200352002, 3967.4048892665755, 3967.6429121379215, 3971.185906889081, 4006.0385507478577, 4080.510338759368, 5609.411011843779, 5642.266505354278, 5657.236273103161, 5679.829605111853, 5706.885862282731, 5912.408759124053, 5913.0, 5920.526357891358, 5923.141144534018, 5931.377205393372, 5511.090670039399, 5529.170624406291, 5564.165375193753, 5639.718014099279, 5644.74598643062, 5108.744562771846, 5130.948690513122, 5131.61512886535, 5158.3552055993005, 5171.172612382076, 5960.165576819249, 5967.254093238345, 6021.0968354746265, 6028.487578555872, 6031.0, 5746.0, 5805.709714514342, 5829.0, 5970.701464926649, 5976.6712830792985, 5961.076033214858, 6083.6045657032155, 6096.420840020191, 6123.938760612336, 6129.387061293835, 5804.477597016289, 5815.709214539172, 5815.883682326415, 5818.185454036354, 5835.708214589169, 5753.626014309224, 5839.970800145972, 5858.794942177004, 5888.558358123244, 5907.881842363215, 5778.422157784188, 5787.305523337226, 5788.363280039276, 5833.620814647034, 5833.67914764692, 5745.9425405745405, 5756.16535602327, 5783.681897495678, 5786.276715410574, 5789.102689083199, 5562.694051827189, 5581.888362232651, 5631.183478395692, 5653.858653533694, 5720.19917211599, 5724.856878578069, 5756.971215143897, 5769.94230057694, 5773.16289138081, 5776.364599893925, 5558.638688485235, 5617.4101719320515, 5620.18507316445, 5648.491635752637, 5659.490645841894], "value": 5424.771681080896, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.9891435931706, 587.820714682022, 602.6504627316151, 605.5125623872781, 609.774383478113, 32772.34455310895, 44799.776001119986, 45819.31271030937, 45830.31254531185, 45918.08163836721, 46316.0, 46609.067818643605, 46777.53222467783, 46925.0, 46933.0613387732, 9026.774330641738, 9155.176034156924, 9164.0, 9178.86231706525, 9180.678676246333, 9047.411918225327, 9054.547272636375, 9101.089891010912, 9107.089291070874, 9141.725748227564, 9078.228350590174, 9141.0, 9152.039035901202, 9196.310276729238, 9203.539823008856, 9050.32122590805, 9118.726438206864, 9124.817503649923, 9136.086391360877, 9189.448633082036, 8858.689945851864, 8913.821723565557, 8931.687390941286, 8932.017478077407, 8967.058458861791, 43454.0, 43725.0, 43933.340999885026, 43946.0, 44188.337174942404, 3603.9099022524383, 4066.857659981916, 4340.370646256275, 4354.4992325882295, 4454.844080457201, 33063.50404743931, 33541.49687754686, 33580.664193357996, 33821.49267760986, 34132.14669633279, 4379.737215767064, 4513.323001549761, 4562.977185114053, 4596.241620132674, 4707.7881495332795, 351222.0, 351573.0, 351758.0, 352592.23703881574, 356732.0, 59474.40525594775, 60010.39989600048, 60247.69876150591, 60563.39436605666, 60831.695841521396, 37633.0, 38969.610303896596, 64505.67747161234, 64819.35180648133, 64836.78560893527, 17172.65654686899, 17245.482635520955, 17323.913380433016, 17344.219510122075, 17395.91302043482, 11513.539458421737, 11522.0, 11538.94230528842, 11568.595099171489, 11576.536938522533, 19824.405267841987, 19825.702614460795, 20089.296874609317, 20259.696104558443, 20266.0, 7392.852142957111, 7459.030326057577, 7543.8114047148165, 7563.243675632392, 7746.845063098706, 1017.3590637898225, 1037.8547003419528, 1078.996533224109, 1137.8577677790277, 1208.0396085112357, 31586.0, 31761.205969850478, 32716.83641581777, 32901.0, 33224.83387583047, 1092.7432053467428, 1104.1332553945274, 1140.5950887434842, 1165.9417029148508, 1198.9100817438728, 16498.58753531147, 16989.235484402983, 17070.48788536346, 17165.313387464612, 17174.0, 620632.0, 628321.0, 631998.0, 632727.0, 635939.0, 61732.0, 61953.38046619476, 62193.68903155455, 62212.0, 62312.01503879679, 1345.2869978911192, 1370.5134677189653, 1375.7661197596433, 1388.94444222232, 1399.531157062375, 30399.39201215919, 31483.52774708427, 31885.521717173797, 32236.19409514687, 33246.83376583102, 52908.73545632247, 52941.7352913233, 53061.20408193803, 53187.0, 53221.0, 518624.0, 518774.0, 519332.0, 520215.0, 522565.0, 28300.292492687848, 29144.56283155712, 29229.269268268465, 29316.85341573364, 29580.408391831614, 47233.0, 49245.22373992057, 50268.49731502638, 50853.0, 51344.91930484033, 41441.1711765769, 41925.1614967705, 42058.78970605128, 42176.78911605545, 44199.0, 41882.7905860481, 42077.0, 42160.789196053825, 42409.151816964106, 43629.0, 41973.79013104915, 42290.36564451474, 42805.35791963185, 43632.56367436285, 45559.77220113878, 35377.82311088428, 38985.610143899336, 39533.20933581255, 39576.40635390414, 40149.0, 29314.267143321587, 29718.8514057437, 29719.554206686484, 29934.700652994063, 29993.4001319968], "value": 20842.137979229155, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.3359404259872, 381.35170210641917, 389.9317619416601, 391.669039661486, 411.9382092686097, 13596.0, 18206.90896545517, 18846.905765471187, 19725.901370493164, 19782.90108549457, 15675.529734107995, 15915.204239787994, 15961.201939902987, 18325.7251141233, 18853.434396968114, 5120.411152717429, 5196.636235463519, 5259.6055295852775, 5263.131583288753, 5303.840884773463, 5468.043092458824, 5570.554355651559, 5642.266505354319, 5642.887142257153, 5656.066748986433, 5546.473085056913, 5687.090065589507, 5741.3110426748635, 5752.3384810746675, 5760.251167348239, 5787.160861675034, 5846.123081537782, 5850.882982340372, 5900.498457631085, 5902.61632993854, 5582.720863956786, 5698.173764804122, 5707.885842283131, 5709.942900570983, 5715.342735585419, 12848.229106253655, 12976.545820896317, 13125.278109703962, 13160.0, 13178.736425271536, 2216.612092883755, 2314.525522267931, 2319.7912187903003, 2386.570417324881, 2540.2633236361435, 11539.826902596467, 14444.638884027878, 14493.78259326111, 14506.637334066627, 14907.478238261609, 1847.4088291746646, 1935.7580302462193, 1946.4549926020736, 1966.4199061276959, 1979.7525309336334, 64655.0, 71205.6439717798, 71750.28249717488, 80067.19932800655, 80667.59666201631, 10665.253432259668, 10897.073748731287, 11097.944510277397, 11121.944390278159, 11159.944200278947, 14984.325705343286, 15038.624034399227, 15046.097234165985, 15058.397664093533, 15074.397024119131, 7999.280064794197, 8138.593070346459, 8239.629216685264, 8368.623411946477, 8381.664733410717, 7643.951478648924, 7694.615269236516, 7854.293113619688, 8174.100848906615, 8276.793080173044, 4366.628836548929, 4393.692441529126, 4628.861134165981, 4661.906761864744, 4763.618550620351, 1899.164367678217, 1915.8371538419112, 1933.825955663969, 1987.9900600496906, 1998.82010619045, 936.8126374725082, 948.5921053946731, 964.7057647417605, 1010.4190090697878, 1056.6671498477954, 19781.307654232034, 19819.0, 19931.701024484646, 19999.800002000084, 20095.59808803845, 950.3062764182185, 971.7084874537752, 1007.4761124215366, 1026.7843752811993, 1067.4929408530886, 10999.120070394349, 11382.94308528452, 11467.254628449124, 11476.827847582294, 11490.94254528722, 499334.0, 499721.0, 500634.0, 501585.0, 507364.0, 84633.57683211668, 85144.57427712822, 89071.0, 89338.10661893428, 89392.0, 968.3512046928715, 982.5676702250987, 996.153269720746, 997.581015973293, 1007.7833265847878, 17327.0, 17716.202770875083, 17729.0, 17784.110794460485, 18189.909050455193, 40246.0, 40667.59332406638, 41071.1785764289, 41948.58051419569, 42135.36796947987, 55807.44192558023, 56953.71523142358, 57433.138502921654, 58135.127973079594, 62592.37407625865, 8085.474444161111, 8257.669693212205, 8321.334293256647, 8341.290990265896, 8465.873011904949, 13191.274479903697, 13698.246596437291, 13723.45106195741, 13944.233067181402, 14100.576982690536, 11776.646700598996, 11971.401429928643, 11991.76016479683, 12101.818472723093, 12454.065945053975, 11388.943055285003, 11589.6523104307, 11829.112816538622, 11916.463759130687, 12140.817887731868, 11969.16215864881, 12130.0, 12196.695082623006, 12367.876321236672, 12438.937805310916, 8029.478083924526, 8107.83784324322, 8328.791780205544, 8429.747107586782, 8433.36749743784, 9307.953460232655, 11832.58585949522, 12040.75918481643, 12065.09511786637, 12273.815892761795], "value": 9075.883617471041, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1512.470635277653, 1519.9696006079878, 1642.6468309313495, 1759.9440335798515, 1763.6560870630233, 7857.882131768028, 8409.117042710512, 8641.351898607614, 9076.001639819622, 9153.313501487399, 8728.60721267543, 8984.775380615489, 9012.0, 9030.593623286952, 9049.547522623876, 2347.8708671023087, 2364.385259832439, 2367.633016882386, 2367.893444794988, 2375.061850569024, 2292.3008482412856, 2304.976950230501, 2351.5179388225374, 2360.8819559022068, 2367.159658321297, 2325.104834638659, 2334.8599084055013, 2337.030132495021, 2342.5197834443984, 2346.495503466754, 2265.275111964172, 2267.285804971437, 2279.9772002280015, 2282.395165281202, 2351.9647205291935, 2270.398344438725, 2271.5911135995516, 2276.8633881967135, 2300.344401845466, 2301.3326135420707, 8747.518886461243, 8781.64873405063, 8831.735047948507, 8834.469931804048, 8856.51289179095, 5226.634135610509, 5325.866853328698, 5358.598105142091, 5361.463853614647, 5370.382406023318, 3214.6624604416666, 3345.1107240607116, 3450.4824276358745, 3504.754667173299, 3520.383932811748, 1089.3300620118648, 1090.8854570270166, 1096.116560766321, 1105.4748994227757, 1127.6166103524758, 12533.623991280185, 12549.058820588401, 12571.874281257162, 12575.05687073464, 12609.873901260962, 4467.620252278528, 4551.726896386162, 4675.322078298627, 4718.504557021532, 4744.288356746447, 10443.164546836235, 10509.211809114347, 10515.57937682484, 10524.736881578021, 10548.367097973993, 5171.4052883918075, 5203.141481655484, 5212.087884620177, 5222.843314700565, 5245.10833158364, 4276.764777937181, 4690.366800481891, 4749.525047495223, 4779.784909679072, 4847.878803029953, 3926.862559810393, 3947.4999500189742, 4007.815740421477, 4075.898102547401, 4081.653059489977, 1294.6698591859015, 1305.3538498443331, 1313.9802902956462, 1321.253491777162, 1324.43711422645, 845.0324378586484, 902.251131560803, 927.1007123090591, 941.6657086734248, 981.5582987655583, 5892.175095486636, 6269.059641053878, 6351.920173570504, 6354.0, 6643.800685979429, 953.1659797677032, 963.6675347005272, 979.8187335342842, 987.6543209876629, 1037.4138611684525, 4120.587941205856, 4327.307630779061, 4421.204183246982, 4496.3255511673515, 4532.206863798822, 11038.668839934815, 11463.598774042868, 11469.426528673534, 11480.483378247998, 11508.94245528767, 10975.396353200651, 11028.889711102947, 11189.160812939062, 11222.607208747655, 11239.83140252897, 7794.259545343183, 8373.958130209432, 8468.237858592633, 8487.321014318839, 8489.915100848912, 6089.147519347206, 6213.627182369072, 6227.50179985591, 6258.468030217483, 6535.411812936859, 10505.579776808841, 10517.474126293808, 10611.681649550217, 10659.200559957879, 10670.786584268426, 10122.848157277795, 10314.329568578018, 10353.327033742782, 10545.36727796304, 10566.735831603964, 7747.457677962714, 8295.294899933573, 8420.11588783169, 8453.196946290092, 8680.696175633822, 8781.0, 8860.911390886009, 8895.644174232959, 8918.152775486318, 8922.107789221025, 7358.11702595692, 7606.771796846104, 7644.655990480331, 7666.6166691664075, 7888.684452621831, 7317.963410182915, 7352.742654007084, 7501.137369202612, 7637.427192960439, 7723.189065148079, 7079.256678048937, 7236.023136876454, 7243.45674074436, 7251.093613298338, 7265.854682906418, 5451.1005684063275, 5521.309836270466, 5545.889082218414, 5618.747156377891, 5668.26312579362, 5477.589180811376, 5568.749406276808, 5593.328800543798, 5620.718964051863, 5672.688002159921], "value": 4734.310506213646, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.7957868228287, 307.3376872839032, 312.4999999999999, 314.3791012749818, 315.43537068647123, 5570.331560212775, 5596.944030559704, 5624.072028115356, 5627.099664053752, 5652.802151924683, 5472.315960504937, 5479.972600136994, 5519.199716041172, 5567.8608034799045, 5588.6646801191955, 4070.837166513343, 4137.710360274782, 4241.363795430695, 4255.9787201063955, 4274.914501709979, 4260.914781704364, 4263.531011588722, 4274.764887931163, 4287.078278170193, 4306.7631280279575, 4226.112516371566, 4289.6997210195295, 4291.313389857623, 4291.549387314334, 4300.505441874177, 4041.5958404159496, 4203.726757760766, 4234.978825105854, 4244.639205667522, 4300.612944834964, 3687.8340474678434, 3702.0374702577255, 3705.2959937611768, 3723.143676954289, 3728.9627103728885, 5370.650907691027, 5383.923215356945, 5404.837854864321, 5416.241726158341, 5436.972815135899, 4514.984128571078, 4703.035877645075, 4762.976185119087, 4845.975770121162, 4851.660383773137, 5696.829095127153, 5700.37295897451, 5711.600187986843, 5765.279340082489, 5775.220345253421, 3888.183481468895, 3888.9222215555815, 3896.123372241223, 3898.785566793825, 3927.764334139932, 5891.91162132564, 5952.464278214981, 5966.731497082642, 5975.850603734898, 6107.297660769024, 4100.507939047274, 4108.424820525129, 4115.753054816721, 4143.917121657611, 4151.8339266429, 4155.459790227251, 4179.832806687759, 4191.832326706897, 4206.495220573551, 4228.0, 4747.264174053027, 4758.477287268041, 4786.8085276588545, 4853.466118727008, 4855.902881942412, 5734.340550836707, 5738.913916291259, 5743.167240750068, 5747.339056008529, 5757.0, 1402.4179965314281, 1405.2552147361914, 1408.978865317021, 1411.1533080151826, 1415.5965549818252, 917.9947956987088, 922.2207234886511, 933.425943045036, 940.3529065680088, 949.6106596295624, 5438.428964958702, 5529.889402211933, 5569.944300556943, 5578.330600327987, 5629.211910332557, 5786.334571524329, 5794.88410231793, 5890.587658863838, 5949.9107513387335, 5961.433663801932, 5461.781528738886, 5467.552631184829, 5492.615516913857, 5497.47773961473, 5653.378128405872, 3112.863804711264, 3132.9060128196193, 3133.9529907051415, 3137.8744850205735, 3182.98408507956, 5777.815547812688, 6099.817005489754, 6105.001224198158, 6132.202813634199, 6147.815565532952, 6057.666828324396, 6093.817185484442, 6105.694715264219, 6127.816165515042, 6180.721867515973, 6218.0, 6236.220472440945, 6245.812625621239, 6257.499400047986, 6259.186305780128, 5671.943280567307, 5906.40935906406, 5985.371535988658, 6002.519798416029, 6059.818205453844, 5941.19793827845, 5965.463108320272, 5973.611715238496, 5987.64074155552, 5988.8802223956145, 6009.489193418433, 6022.578419510767, 6168.095476564658, 6251.4061164189325, 6264.154339164337, 3970.4838371011583, 3971.781552014667, 3974.8012599369335, 3978.681705463498, 4018.65841403484, 6013.308469525886, 6016.458518733335, 6026.186464827192, 6039.305479869871, 6053.576249662481, 5757.337906140848, 5789.3921138279875, 5802.361740208658, 5812.534997200299, 5850.824475265749, 5793.246877905845, 5864.179014937998, 5868.354481007171, 5884.411558844081, 5884.852878678067, 5182.922256166085, 5707.942920570741, 5726.0, 5778.537716982547, 5789.681567513827, 5763.682997435014, 5797.304323481209, 5803.82588522345, 5826.0, 5862.970685146547, 5739.253896993364, 5741.45456181662, 5763.464075806632, 5775.422457754191, 5776.768929242783], "value": 4363.823056953536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.8538292341533, 756.8107973006748, 758.4993904023344, 787.4133770341095, 804.6861723927666, 15429.922850385747, 16265.593360166002, 16347.0, 16390.508284751446, 16423.014619122845, 14519.5644130676, 14676.412943482248, 14786.18675972821, 14818.629534261649, 14886.255687215624, 652.1977967100469, 789.826238227589, 792.9484583502068, 806.1293802693077, 819.4632515702194, 805.2108933245394, 805.60525342582, 808.1474044882647, 808.5916612110876, 810.0846043970334, 861.9827603447927, 864.6238886084542, 864.6498168241872, 865.4288169807896, 866.7053201911349, 854.2738672128703, 860.5611138319503, 862.9611667474948, 864.2869632553137, 865.277493293101, 617.2346290599669, 624.4660815003198, 625.32464937867, 625.4027403829332, 626.7336382037615, 14200.57398278043, 14247.78628320576, 14313.856861431357, 14330.426782928671, 14357.0, 14875.925620371829, 15139.697206055816, 15159.696806063926, 15163.545093647208, 15346.693066138614, 12736.872631273662, 12740.745185096339, 12797.872021279762, 12808.871911280954, 12837.550685726048, 3961.6830653547645, 3969.2458432897656, 4023.3361495353233, 4062.837486500536, 4105.34314509677, 60269.698651506464, 75196.0, 76618.0, 79221.60389198075, 80141.0, 11715.76568468626, 11754.05967522596, 11841.940790295994, 11848.526058957717, 11910.940445297718, 21466.892665536885, 21523.67714484284, 21657.566848663253, 21673.89163054206, 21707.674384884238, 8738.69414570487, 8793.252573531321, 8829.955850220707, 8891.333150013776, 9024.593893274818, 4844.636652251095, 5032.169692000852, 5036.143855544493, 5118.0, 5125.923111153335, 3704.759190652599, 3805.891544071725, 3854.3062248794927, 3858.884233473, 3867.477890484805, 634.5621521150391, 645.1677336236296, 651.0137142229526, 661.1933441201726, 665.0489799586637, 693.6011793218919, 728.7230852276101, 746.6901235987004, 752.3792870881545, 784.5920121536883, 10250.692479225636, 10668.413237272025, 10810.56757729682, 10836.566537338576, 10996.780064398667, 659.8152517295069, 713.5861200503696, 744.6128013432984, 759.4038679636459, 824.7360844529812, 5075.644704870623, 5114.667546609533, 5190.662606930538, 5230.738463076831, 5262.184361423909, 67328.0, 69480.3051969484, 76716.61641691756, 122413.38793306155, 123651.0, 58192.709036455395, 58401.0, 59106.11340829805, 97020.0, 101695.0, 60019.79861409747, 90439.54780226057, 91398.0, 92974.0, 97211.99999999859, 9600.423974561549, 9707.466089365153, 9867.259955503223, 9878.703638890845, 9978.850317245102, 48670.51329486801, 49204.75397622989, 49390.50609494003, 50196.0, 50402.747986259834, 68831.31168688249, 69749.65125174342, 77757.0, 79101.60449197717, 81918.59040704758, 10763.730906727395, 11111.944215340845, 11947.761044778881, 12429.75140497203, 12724.236545807282, 15338.693226135638, 15378.0, 15754.126578248171, 15799.68400632004, 16899.563537099486, 15075.0, 15080.0, 15173.468928587446, 15574.610634733768, 15983.600409989844, 14457.710845783236, 14541.781873271697, 14642.780358294422, 15353.155576443403, 15780.97423667458, 14332.283385830875, 14427.42290308376, 14593.781093283822, 15261.771073433685, 15271.465498707492, 11543.76912461763, 11570.305781653127, 11600.593979210687, 11921.701957450787, 12326.876731232573, 11668.183227174355, 12168.513259469522, 12231.75536489283, 12587.118901676795, 12616.243025418504], "value": 7684.442555175308, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 107.0, 111.0, 113.0, 114.0, 112.0, 112.0, 111.0, 112.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 148.0, 183.0, 120.0, 152.0, 149.0, 123.0, 190.0, 119.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 162.0, 163.0, 165.0, 169.0, 162.0, 162.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 180.0, 78.0, 75.0, 75.0, 76.0, 171.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 165.0, 87.0, 85.0, 85.0, 141.0, 84.0, 83.0, 140.0], "value": 103.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 146.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 97.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 54.0, 52.0, 53.0, 54.0, 52.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 64.0, 68.0, 68.0, 64.0, 64.0, 64.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 76.0, 86.0, 77.0, 76.0, 76.0, 78.0, 85.0, 82.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 128.0, 119.0, 144.0, 136.0, 134.0, 122.0, 122.0, 165.0, 135.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 163.0, 163.0, 158.0, 161.0, 157.0, 158.0, 160.0, 156.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 63.0, 63.0, 63.0, 62.0, 64.0, 64.0, 63.0], "value": 63.7, "unit": "score"}]}]} 05:50:04 INFO - Return code: 0 05:50:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:04 ERROR - # TBPL SUCCESS # 05:50:04 INFO - Running post-action listener: _resource_record_post_action 05:50:04 INFO - [mozharness: 2016-08-02 12:50:04.878067Z] Finished run-tests step (success) 05:50:04 INFO - Running post-run listener: _resource_record_post_run 05:50:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 308211712; Read time: 0; Write time: 255304 05:50:04 INFO - TinderboxPrint: CPU usage
13.2% 05:50:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:50:04 INFO - TinderboxPrint: I/O write bytes / time
308,211,712 / 255,304 05:50:04 INFO - TinderboxPrint: CPU idle
8,242.2 (86.7%) 05:50:04 INFO - TinderboxPrint: CPU user
1,206.8 (12.7%) 05:50:04 INFO - TinderboxPrint: Swap in / out
0 / 0 05:50:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 19963904; Read time: 0; Write time: 9120 05:50:04 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 287805440; Read time: 0; Write time: 246056 05:50:05 INFO - Running post-run listener: _upload_blobber_files 05:50:05 INFO - Blob upload gear active. 05:50:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:05 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 05:50:05 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:50:05 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:50:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:50:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:06 INFO - (blobuploader) - INFO - Done attempting. 05:50:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:50:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:50:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:07 INFO - (blobuploader) - INFO - Done attempting. 05:50:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:13 INFO - (blobuploader) - INFO - Done attempting. 05:50:13 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:13 INFO - Return code: 0 05:50:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffc5e305172bac1affa3c3c3998b74fd741ebac2c6355a6527bd2cec18e3a3058fb9efc11136bba1548cbb498c0fa34807f2975729adf60a0a05a287ba47a3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaf0673b982383bb0b9d48438e47e228415b64fb77e75805fed9288c7668f45f8e9328f1b4f15eaf04312906b2d50e08c894dad944dc496711d071bdcfc3979c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1eebc19f7bb43fbc12ba24f6e7606be0b11a5c238895a0bf5f66c43931fdea6c949069dece1a37b67bbd24203dff81506b77178699b638b5b2240f0792852f74"} 05:50:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:13 INFO - Contents: 05:50:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffc5e305172bac1affa3c3c3998b74fd741ebac2c6355a6527bd2cec18e3a3058fb9efc11136bba1548cbb498c0fa34807f2975729adf60a0a05a287ba47a3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaf0673b982383bb0b9d48438e47e228415b64fb77e75805fed9288c7668f45f8e9328f1b4f15eaf04312906b2d50e08c894dad944dc496711d071bdcfc3979c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1eebc19f7bb43fbc12ba24f6e7606be0b11a5c238895a0bf5f66c43931fdea6c949069dece1a37b67bbd24203dff81506b77178699b638b5b2240f0792852f74"} 05:50:13 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:13 INFO - Copying logs to upload dir... 05:50:13 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.941173 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-08-02 05:50:12.958201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 05:50:12.961742) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 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/sha512/4ffc5e305172bac1affa3c3c3998b74fd741ebac2c6355a6527bd2cec18e3a3058fb9efc11136bba1548cbb498c0fa34807f2975729adf60a0a05a287ba47a3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaf0673b982383bb0b9d48438e47e228415b64fb77e75805fed9288c7668f45f8e9328f1b4f15eaf04312906b2d50e08c894dad944dc496711d071bdcfc3979c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1eebc19f7bb43fbc12ba24f6e7606be0b11a5c238895a0bf5f66c43931fdea6c949069dece1a37b67bbd24203dff81506b77178699b638b5b2240f0792852f74"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1470130201/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffc5e305172bac1affa3c3c3998b74fd741ebac2c6355a6527bd2cec18e3a3058fb9efc11136bba1548cbb498c0fa34807f2975729adf60a0a05a287ba47a3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aaf0673b982383bb0b9d48438e47e228415b64fb77e75805fed9288c7668f45f8e9328f1b4f15eaf04312906b2d50e08c894dad944dc496711d071bdcfc3979c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1eebc19f7bb43fbc12ba24f6e7606be0b11a5c238895a0bf5f66c43931fdea6c949069dece1a37b67bbd24203dff81506b77178699b638b5b2240f0792852f74"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 05:50:12.986642) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:50:12.986952) ========= 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=652eb03db935cfc1c15ebda0000001ad-1470140753.126350-247845946 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004422 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 05:50:13.071156) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 05:50:13.071604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 05:50:13.071888) ========= ========= Total master_lag: 0.18 =========