builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-012 starttime: 1469557489.3 results: success (0) buildid: 20160726111730 builduid: 71d3529609b1432180b9cb9cee5639df revision: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.304862) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.305474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.305798) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.320852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.321192) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 11:24:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.8M=0s 2016-07-26 11:24:49 (34.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109860 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.445026) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.445355) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016262 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.495243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:49.495608) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac --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 ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac --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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 11:24:49,595 truncating revision to first 12 chars 2016-07-26 11:24:49,595 Setting DEBUG logging. 2016-07-26 11:24:49,595 attempt 1/10 2016-07-26 11:24:49,595 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ceb63dec9267?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 11:24:49,898 unpacking tar archive at: fx-team-ceb63dec9267/testing/mozharness/ program finished with exit code 0 elapsedTime=0.635143 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:50.147431) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:50.147806) ========= script_repo_revision: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:50.148398) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:50.148714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:50.160031) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-07-26 11:24:50.160661) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:24:50 INFO - MultiFileLogger online at 20160726 11:24:50 in /builds/slave/test 11:24:50 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 11:24:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:24:50 INFO - {'append_to_log': False, 11:24:50 INFO - 'base_work_dir': '/builds/slave/test', 11:24:50 INFO - 'blob_upload_branch': 'Fx-Team', 11:24:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:24:50 INFO - 'branch': 'Fx-Team', 11:24:50 INFO - 'buildbot_json_path': 'buildprops.json', 11:24:50 INFO - 'config_files': ('talos/linux_config.py',), 11:24:50 INFO - 'default_actions': ('clobber', 11:24:50 INFO - 'read-buildbot-config', 11:24:50 INFO - 'download-and-extract', 11:24:50 INFO - 'populate-webroot', 11:24:50 INFO - 'create-virtualenv', 11:24:50 INFO - 'install', 11:24:50 INFO - 'run-tests'), 11:24:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:24:50 INFO - 'download_minidump_stackwalk': True, 11:24:50 INFO - 'download_symbols': 'ondemand', 11:24:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:24:50 INFO - 'tooltool.py': '/tools/tooltool.py', 11:24:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:24:50 INFO - '/tools/misc-python/virtualenv.py')}, 11:24:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:24:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:24:50 INFO - 'installer_path': 'installer.exe', 11:24:50 INFO - 'log_level': 'info', 11:24:50 INFO - 'log_name': 'talos', 11:24:50 INFO - 'log_to_console': True, 11:24:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:24:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:24:50 INFO - 'opt_config_files': (), 11:24:50 INFO - 'pip_index': False, 11:24:50 INFO - 'sps_profile': False, 11:24:50 INFO - 'sps_profile_interval': 0, 11:24:50 INFO - 'suite': 'dromaeojs-e10s', 11:24:50 INFO - 'system_bits': '32', 11:24:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:24:50 INFO - 'title': 'talos-linux64-ix-012', 11:24:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:24:50 INFO - 'use_talos_json': True, 11:24:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:24:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:24:50 INFO - 'work_dir': 'build'} 11:24:50 INFO - [mozharness: 2016-07-26 18:24:50.352897Z] Running clobber step. 11:24:50 INFO - Running pre-action listener: _resource_record_pre_action 11:24:50 INFO - Running main action method: clobber 11:24:50 INFO - rmtree: /builds/slave/test/build 11:24:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:24:50 INFO - Running post-action listener: _resource_record_post_action 11:24:50 INFO - [mozharness: 2016-07-26 18:24:50.811612Z] Finished clobber step (success) 11:24:50 INFO - [mozharness: 2016-07-26 18:24:50.811714Z] Running read-buildbot-config step. 11:24:50 INFO - Running pre-action listener: _resource_record_pre_action 11:24:50 INFO - Running main action method: read_buildbot_config 11:24:50 INFO - Using buildbot properties: 11:24:50 INFO - { 11:24:50 INFO - "project": "", 11:24:50 INFO - "product": "firefox", 11:24:50 INFO - "script_repo_revision": "production", 11:24:50 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 11:24:50 INFO - "repository": "", 11:24:50 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 11:24:50 INFO - "buildid": "20160726111730", 11:24:50 INFO - "stage_platform": "linux64-pgo", 11:24:50 INFO - "basedir": "/builds/slave/test", 11:24:50 INFO - "buildnumber": 979, 11:24:50 INFO - "platform": "ubuntu64_hw", 11:24:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:24:50 INFO - "slavebuilddir": "test", 11:24:50 INFO - "branch": "fx-team", 11:24:50 INFO - "revision": "ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac", 11:24:50 INFO - "slavename": "talos-linux64-ix-012", 11:24:50 INFO - "builduid": "71d3529609b1432180b9cb9cee5639df", 11:24:50 INFO - "repo_path": "integration/fx-team" 11:24:50 INFO - } 11:24:50 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:24:50 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.test_packages.json. 11:24:50 INFO - Running post-action listener: _resource_record_post_action 11:24:50 INFO - [mozharness: 2016-07-26 18:24:50.814081Z] Finished read-buildbot-config step (success) 11:24:50 INFO - [mozharness: 2016-07-26 18:24:50.814168Z] Running download-and-extract step. 11:24:50 INFO - Running pre-action listener: _resource_record_pre_action 11:24:50 INFO - Running main action method: download_and_extract 11:24:50 INFO - mkdir: /builds/slave/test/build/tests 11:24:50 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')]} 11:24:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:24:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:24:50 INFO - Downloaded 1361 bytes. 11:24:50 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:24:50 INFO - Using the following test package requirements: 11:24:50 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:24:50 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:24:50 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:24:50 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'jsshell-linux-x86_64.zip'], 11:24:50 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:24:50 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:24:50 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:24:50 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:24:50 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:24:50 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:24:50 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:24:50 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:24:50 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')]} 11:24:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:24:50 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:51 INFO - Downloaded 24313303 bytes. 11:24:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:52 INFO - Return code: 0 11:24:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:52 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:24:52 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:52 INFO - Downloaded 24313303 bytes. 11:24:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:54 INFO - Return code: 0 11:24: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')]} 11:24:54 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:24:54 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:24:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:24:54 INFO - Downloaded 14413725 bytes. 11:24:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:24:54 INFO - Return code: 0 11:24: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')]} 11:24:54 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:24:54 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:24:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:24:55 INFO - Downloaded 60940042 bytes. 11:24:55 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:24:55 INFO - mkdir: /builds/slave/test/properties 11:24:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:24:55 INFO - Writing to file /builds/slave/test/properties/build_url 11:24:55 INFO - Contents: 11:24:55 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:24:55 INFO - Running post-action listener: _resource_record_post_action 11:24:55 INFO - Running post-action listener: set_extra_try_arguments 11:24:55 INFO - [mozharness: 2016-07-26 18:24:55.721690Z] Finished download-and-extract step (success) 11:24:55 INFO - [mozharness: 2016-07-26 18:24:55.721924Z] Running populate-webroot step. 11:24:55 INFO - Running pre-action listener: _resource_record_pre_action 11:24:55 INFO - Running main action method: populate_webroot 11:24:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:24:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:24:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:24:55 INFO - '--tppagecycles', 11:24:55 INFO - '7'], 11:24:55 INFO - 'tests': ['tsvgm']}}, 11:24:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:24:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:24:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:24:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:24:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['damp', 'tps']}, 11:24:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'tests': ['damp', 'tps']}, 11:24:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['dromaeo_dom']}, 11:24:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:24:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['basic_compositor_video']}, 11:24:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:24:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'other-e10s': {'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['a11yr', 11:24:55 INFO - 'ts_paint', 11:24:55 INFO - 'tpaint', 11:24:55 INFO - 'sessionrestore', 11:24:55 INFO - 'sessionrestore_no_auto_restore', 11:24:55 INFO - 'tabpaint']}, 11:24:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['tsvgx', 11:24:55 INFO - 'tsvgr_opacity', 11:24:55 INFO - 'tart', 11:24:55 INFO - 'tscrollx', 11:24:55 INFO - 'cart']}, 11:24:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:24:55 INFO - 'tsvgr_opacity', 11:24:55 INFO - 'tart', 11:24:55 INFO - 'tscrollx', 11:24:55 INFO - 'cart']}, 11:24:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'talos_options': ['--disable-e10s'], 11:24:55 INFO - 'tests': ['tp5o']}, 11:24:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'tests': ['tp5o']}, 11:24:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'talos_options': ['--disable-e10s', 11:24:55 INFO - '--xperf_path', 11:24:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:55 INFO - 'tests': ['tp5n']}, 11:24:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:55 INFO - 'talos_options': ['--xperf_path', 11:24:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:55 INFO - 'tests': ['tp5n']}}, 11:24:55 INFO - 'talos.zip': {'path': '', 11:24:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:24:55 INFO - Running post-action listener: _resource_record_post_action 11:24:55 INFO - [mozharness: 2016-07-26 18:24:55.739894Z] Finished populate-webroot step (success) 11:24:55 INFO - [mozharness: 2016-07-26 18:24:55.739983Z] Running create-virtualenv step. 11:24:55 INFO - Running pre-action listener: _resource_record_pre_action 11:24:55 INFO - Running main action method: create_virtualenv 11:24:55 INFO - Creating virtualenv /builds/slave/test/build/venv 11:24:55 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 11:24:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:24:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:24:55 INFO - Using real prefix '/usr' 11:24:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:24:56 INFO - Installing distribute.............................................................................................................................................................................................done. 11:24:58 INFO - Installing pip.................done. 11:24:58 INFO - Return code: 0 11:24:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:24:58 INFO - Using env: {'DISPLAY': ':0', 11:24:58 INFO - 'HOME': '/home/cltbld', 11:24:58 INFO - 'LANG': 'en_US.UTF-8', 11:24:58 INFO - 'LANGUAGE': 'en_US:en', 11:24:58 INFO - 'LOGNAME': 'cltbld', 11:24:58 INFO - 'MAIL': '/var/mail/cltbld', 11:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:58 INFO - 'MOZ_NO_REMOTE': '1', 11:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:58 INFO - 'NO_EM_RESTART': '1', 11:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:58 INFO - 'PWD': '/builds/slave/test', 11:24:58 INFO - 'SHELL': '/bin/bash', 11:24:58 INFO - 'SHLVL': '1', 11:24:58 INFO - 'TERM': 'linux', 11:24:58 INFO - 'TMOUT': '86400', 11:24:58 INFO - 'USER': 'cltbld', 11:24:58 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:58 INFO - '_': '/tools/buildbot/bin/python'} 11:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:58 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 11:24:58 INFO - Cleaning up... 11:24:58 INFO - Return code: 0 11:24:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:24:58 INFO - Using env: {'DISPLAY': ':0', 11:24:58 INFO - 'HOME': '/home/cltbld', 11:24:58 INFO - 'LANG': 'en_US.UTF-8', 11:24:58 INFO - 'LANGUAGE': 'en_US:en', 11:24:58 INFO - 'LOGNAME': 'cltbld', 11:24:58 INFO - 'MAIL': '/var/mail/cltbld', 11:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:58 INFO - 'MOZ_NO_REMOTE': '1', 11:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:58 INFO - 'NO_EM_RESTART': '1', 11:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:58 INFO - 'PWD': '/builds/slave/test', 11:24:58 INFO - 'SHELL': '/bin/bash', 11:24:58 INFO - 'SHLVL': '1', 11:24:58 INFO - 'TERM': 'linux', 11:24:58 INFO - 'TMOUT': '86400', 11:24:58 INFO - 'USER': 'cltbld', 11:24:58 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:58 INFO - '_': '/tools/buildbot/bin/python'} 11:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:58 INFO - Downloading/unpacking psutil>=3.1.1 11:24:58 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 11:24:58 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 11:24:58 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 11:24:58 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 11:24:58 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 11:24:58 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 11:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:25:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:00 INFO - Installing collected packages: psutil 11:25:00 INFO - Running setup.py install for psutil 11:25:00 INFO - building 'psutil._psutil_linux' extension 11:25:00 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 11:25:01 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 11:25:01 INFO - building 'psutil._psutil_posix' extension 11:25:01 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 11:25:01 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 11:25:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:01 INFO - Successfully installed psutil 11:25:01 INFO - Cleaning up... 11:25:01 INFO - Return code: 0 11:25:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:25:01 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')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:01 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:01 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 11:25:01 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 11:25:01 INFO - Using env: {'DISPLAY': ':0', 11:25:01 INFO - 'HOME': '/home/cltbld', 11:25:01 INFO - 'LANG': 'en_US.UTF-8', 11:25:01 INFO - 'LANGUAGE': 'en_US:en', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MAIL': '/var/mail/cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SHLVL': '1', 11:25:01 INFO - 'TERM': 'linux', 11:25:01 INFO - 'TMOUT': '86400', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - '_': '/tools/buildbot/bin/python'} 11:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:25: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 11:25: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 11:25: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 11:25: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 11:25: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 11:25: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 11:25:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:25:03 INFO - Installing collected packages: mozsystemmonitor 11:25:03 INFO - Running setup.py install for mozsystemmonitor 11:25:03 INFO - Successfully installed mozsystemmonitor 11:25:03 INFO - Cleaning up... 11:25:03 INFO - Return code: 0 11:25:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:03 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:03 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 11:25:03 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 11:25:03 INFO - Using env: {'DISPLAY': ':0', 11:25:03 INFO - 'HOME': '/home/cltbld', 11:25:03 INFO - 'LANG': 'en_US.UTF-8', 11:25:03 INFO - 'LANGUAGE': 'en_US:en', 11:25:03 INFO - 'LOGNAME': 'cltbld', 11:25:03 INFO - 'MAIL': '/var/mail/cltbld', 11:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:03 INFO - 'MOZ_NO_REMOTE': '1', 11:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:03 INFO - 'NO_EM_RESTART': '1', 11:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:03 INFO - 'PWD': '/builds/slave/test', 11:25:03 INFO - 'SHELL': '/bin/bash', 11:25:03 INFO - 'SHLVL': '1', 11:25:03 INFO - 'TERM': 'linux', 11:25:03 INFO - 'TMOUT': '86400', 11:25:03 INFO - 'USER': 'cltbld', 11:25:03 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:03 INFO - '_': '/tools/buildbot/bin/python'} 11:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:03 INFO - Downloading/unpacking jsonschema==2.5.1 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:03 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 11:25:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:25:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:25: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 11:25: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 11:25: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 11:25: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 11:25: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 11:25: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 11:25:05 INFO - Downloading functools32-3.2.3-2.tar.gz 11:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:25:06 INFO - warning: no files found matching '*.txt' 11:25:06 INFO - no previously-included directories found matching 'build' 11:25:06 INFO - no previously-included directories found matching 'dist' 11:25:06 INFO - no previously-included directories found matching '.git*' 11:25:06 INFO - Installing collected packages: jsonschema, functools32 11:25:06 INFO - Running setup.py install for functools32 11:25:06 INFO - warning: no files found matching '*.txt' 11:25:06 INFO - no previously-included directories found matching 'build' 11:25:06 INFO - no previously-included directories found matching 'dist' 11:25:06 INFO - no previously-included directories found matching '.git*' 11:25:06 INFO - Successfully installed jsonschema functools32 11:25:06 INFO - Cleaning up... 11:25:06 INFO - Return code: 0 11:25:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25: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')]} 11:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:06 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:06 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 11:25:06 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 11:25:06 INFO - Using env: {'DISPLAY': ':0', 11:25:06 INFO - 'HOME': '/home/cltbld', 11:25:06 INFO - 'LANG': 'en_US.UTF-8', 11:25:06 INFO - 'LANGUAGE': 'en_US:en', 11:25:06 INFO - 'LOGNAME': 'cltbld', 11:25:06 INFO - 'MAIL': '/var/mail/cltbld', 11:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:06 INFO - 'MOZ_NO_REMOTE': '1', 11:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:06 INFO - 'NO_EM_RESTART': '1', 11:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:06 INFO - 'PWD': '/builds/slave/test', 11:25:06 INFO - 'SHELL': '/bin/bash', 11:25:06 INFO - 'SHLVL': '1', 11:25:06 INFO - 'TERM': 'linux', 11:25:06 INFO - 'TMOUT': '86400', 11:25:06 INFO - 'USER': 'cltbld', 11:25:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:06 INFO - '_': '/tools/buildbot/bin/python'} 11:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:25:06 INFO - Cleaning up... 11:25:06 INFO - Return code: 0 11:25:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25: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')]} 11:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:06 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:06 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 11:25:06 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 11:25:06 INFO - Using env: {'DISPLAY': ':0', 11:25:06 INFO - 'HOME': '/home/cltbld', 11:25:06 INFO - 'LANG': 'en_US.UTF-8', 11:25:06 INFO - 'LANGUAGE': 'en_US:en', 11:25:06 INFO - 'LOGNAME': 'cltbld', 11:25:06 INFO - 'MAIL': '/var/mail/cltbld', 11:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:06 INFO - 'MOZ_NO_REMOTE': '1', 11:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:06 INFO - 'NO_EM_RESTART': '1', 11:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:06 INFO - 'PWD': '/builds/slave/test', 11:25:06 INFO - 'SHELL': '/bin/bash', 11:25:06 INFO - 'SHLVL': '1', 11:25:06 INFO - 'TERM': 'linux', 11:25:06 INFO - 'TMOUT': '86400', 11:25:06 INFO - 'USER': 'cltbld', 11:25:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:06 INFO - '_': '/tools/buildbot/bin/python'} 11:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:06 INFO - Downloading/unpacking blobuploader==1.2.4 11:25:06 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 11:25:06 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 11:25:06 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 11:25:06 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 11:25:06 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 11:25:06 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 11:25:08 INFO - Downloading blobuploader-1.2.4.tar.gz 11:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:25:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:25:08 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 11:25:08 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 11:25:08 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 11:25:08 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 11:25:08 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 11:25:08 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 11:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:25:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:25:09 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 11:25:09 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 11:25:09 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 11:25:09 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 11:25:09 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 11:25:09 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 11:25:09 INFO - Downloading docopt-0.6.1.tar.gz 11:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:25:09 INFO - Installing collected packages: blobuploader, requests, docopt 11:25:09 INFO - Running setup.py install for blobuploader 11:25:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:25:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:25:09 INFO - Running setup.py install for requests 11:25:09 INFO - Running setup.py install for docopt 11:25:09 INFO - Successfully installed blobuploader requests docopt 11:25:09 INFO - Cleaning up... 11:25:09 INFO - Return code: 0 11:25:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25: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')]} 11:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:09 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:09 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 11:25:09 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 11:25:09 INFO - Using env: {'DISPLAY': ':0', 11:25:09 INFO - 'HOME': '/home/cltbld', 11:25:09 INFO - 'LANG': 'en_US.UTF-8', 11:25:09 INFO - 'LANGUAGE': 'en_US:en', 11:25:09 INFO - 'LOGNAME': 'cltbld', 11:25:09 INFO - 'MAIL': '/var/mail/cltbld', 11:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:09 INFO - 'MOZ_NO_REMOTE': '1', 11:25:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:09 INFO - 'NO_EM_RESTART': '1', 11:25:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:09 INFO - 'PWD': '/builds/slave/test', 11:25:09 INFO - 'SHELL': '/bin/bash', 11:25:09 INFO - 'SHLVL': '1', 11:25:09 INFO - 'TERM': 'linux', 11:25:09 INFO - 'TMOUT': '86400', 11:25:09 INFO - 'USER': 'cltbld', 11:25:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:09 INFO - '_': '/tools/buildbot/bin/python'} 11:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:10 INFO - Running setup.py (path:/tmp/pip-Yonfjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:10 INFO - Running setup.py (path:/tmp/pip-O9ngUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:25:10 INFO - Running setup.py (path:/tmp/pip-v83O0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:25:10 INFO - Running setup.py (path:/tmp/pip-r8tOwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:25:10 INFO - Running setup.py (path:/tmp/pip-qrOUMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:25:10 INFO - Running setup.py (path:/tmp/pip-21XOmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:25:10 INFO - Running setup.py (path:/tmp/pip-7iAVrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:25:10 INFO - Running setup.py (path:/tmp/pip-IbPM7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:25:10 INFO - Running setup.py (path:/tmp/pip-fikSv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:25:10 INFO - Running setup.py (path:/tmp/pip-dE_teT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:25:10 INFO - Running setup.py (path:/tmp/pip-oAkOOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:25:10 INFO - Running setup.py (path:/tmp/pip-KKGUKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:25:11 INFO - Running setup.py (path:/tmp/pip-TFwTEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:25:11 INFO - Running setup.py (path:/tmp/pip-iJ2jGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:11 INFO - Running setup.py (path:/tmp/pip-AbGCJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:25:11 INFO - Running setup.py (path:/tmp/pip-RCbFjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:25:11 INFO - Running setup.py (path:/tmp/pip-cDoN34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:25:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:25:11 INFO - Running setup.py install for manifestparser 11:25:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:25:11 INFO - Running setup.py install for mozcrash 11:25:11 INFO - Running setup.py install for mozdebug 11:25:11 INFO - Running setup.py install for mozdevice 11:25:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:25:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:25:11 INFO - Running setup.py install for mozfile 11:25:11 INFO - Running setup.py install for mozhttpd 11:25:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:25:11 INFO - Running setup.py install for mozinfo 11:25:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for mozInstall 11:25:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for mozleak 11:25:12 INFO - Running setup.py install for mozlog 11:25:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for moznetwork 11:25:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for mozprocess 11:25:12 INFO - Running setup.py install for mozprofile 11:25:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for mozrunner 11:25:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:25:12 INFO - Running setup.py install for mozscreenshot 11:25:12 INFO - Running setup.py install for moztest 11:25:13 INFO - Running setup.py install for mozversion 11:25:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:25:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:25:13 INFO - Cleaning up... 11:25:13 INFO - Return code: 0 11:25:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25: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')]} 11:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25: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')]} 11:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:13 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:13 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 11:25:13 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 11:25:13 INFO - Using env: {'DISPLAY': ':0', 11:25:13 INFO - 'HOME': '/home/cltbld', 11:25:13 INFO - 'LANG': 'en_US.UTF-8', 11:25:13 INFO - 'LANGUAGE': 'en_US:en', 11:25:13 INFO - 'LOGNAME': 'cltbld', 11:25:13 INFO - 'MAIL': '/var/mail/cltbld', 11:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:13 INFO - 'MOZ_NO_REMOTE': '1', 11:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:13 INFO - 'NO_EM_RESTART': '1', 11:25:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:13 INFO - 'PWD': '/builds/slave/test', 11:25:13 INFO - 'SHELL': '/bin/bash', 11:25:13 INFO - 'SHLVL': '1', 11:25:13 INFO - 'TERM': 'linux', 11:25:13 INFO - 'TMOUT': '86400', 11:25:13 INFO - 'USER': 'cltbld', 11:25:13 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:13 INFO - '_': '/tools/buildbot/bin/python'} 11:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:13 INFO - Running setup.py (path:/tmp/pip-0M581e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:13 INFO - Running setup.py (path:/tmp/pip-ObKIdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:25:13 INFO - Running setup.py (path:/tmp/pip-i3JpBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:25:13 INFO - Running setup.py (path:/tmp/pip-aNvYt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:25:13 INFO - Running setup.py (path:/tmp/pip-uxwN4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:25:13 INFO - Running setup.py (path:/tmp/pip-N4eIZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:25:13 INFO - Running setup.py (path:/tmp/pip-3ui09A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:25:13 INFO - Running setup.py (path:/tmp/pip-J6K9pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:25:13 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)) 11:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:25:13 INFO - Running setup.py (path:/tmp/pip-pgs_aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:25:14 INFO - Running setup.py (path:/tmp/pip-vSl3y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:25:14 INFO - Running setup.py (path:/tmp/pip-ZVsCUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:25:14 INFO - Running setup.py (path:/tmp/pip-gBylw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:25:14 INFO - Running setup.py (path:/tmp/pip-G5CO4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:25:14 INFO - Running setup.py (path:/tmp/pip-egLfeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:14 INFO - Running setup.py (path:/tmp/pip-1zcPxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:25:14 INFO - Running setup.py (path:/tmp/pip-5wkrfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:25:14 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)) 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:25:14 INFO - Running setup.py (path:/tmp/pip-qwrORI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:25:14 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)) 11:25:14 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)) 11:25:14 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)) 11:25:14 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)) 11:25:14 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)) 11:25:14 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)) 11:25:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:25:14 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 11:25:14 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 11:25:14 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 11:25:14 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 11:25:14 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 11:25:14 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 11:25:16 INFO - Downloading blessings-1.6.tar.gz 11:25:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:25:16 INFO - Installing collected packages: blessings 11:25:16 INFO - Running setup.py install for blessings 11:25:16 INFO - Successfully installed blessings 11:25:16 INFO - Cleaning up... 11:25:16 INFO - Return code: 0 11:25:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:25:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:16 INFO - Reading from file tmpfile_stdout 11:25:16 INFO - Current package versions: 11:25:16 INFO - argparse == 1.2.1 11:25:16 INFO - blessings == 1.6 11:25:16 INFO - blobuploader == 1.2.4 11:25:16 INFO - docopt == 0.6.1 11:25:16 INFO - functools32 == 3.2.3-2 11:25:16 INFO - jsonschema == 2.5.1 11:25:16 INFO - manifestparser == 1.1 11:25:16 INFO - mozInstall == 1.12 11:25:16 INFO - mozcrash == 0.17 11:25:16 INFO - mozdebug == 0.1 11:25:16 INFO - mozdevice == 0.48 11:25:16 INFO - mozfile == 1.2 11:25:16 INFO - mozhttpd == 0.7 11:25:16 INFO - mozinfo == 0.9 11:25:16 INFO - mozleak == 0.1 11:25:16 INFO - mozlog == 3.3 11:25:16 INFO - moznetwork == 0.27 11:25:16 INFO - mozprocess == 0.23 11:25:16 INFO - mozprofile == 0.28 11:25:16 INFO - mozrunner == 6.12 11:25:16 INFO - mozscreenshot == 0.1 11:25:16 INFO - mozsystemmonitor == 0.3 11:25:16 INFO - moztest == 0.7 11:25:16 INFO - mozversion == 1.4 11:25:16 INFO - psutil == 3.1.1 11:25:16 INFO - requests == 1.2.3 11:25:16 INFO - wsgiref == 0.1.2 11:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:16 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')]} 11:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:16 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')]} 11:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:16 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:16 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 11:25:16 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 11:25:16 INFO - Using env: {'DISPLAY': ':0', 11:25:16 INFO - 'HOME': '/home/cltbld', 11:25:16 INFO - 'LANG': 'en_US.UTF-8', 11:25:16 INFO - 'LANGUAGE': 'en_US:en', 11:25:16 INFO - 'LOGNAME': 'cltbld', 11:25:16 INFO - 'MAIL': '/var/mail/cltbld', 11:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:16 INFO - 'MOZ_NO_REMOTE': '1', 11:25:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:16 INFO - 'NO_EM_RESTART': '1', 11:25:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:16 INFO - 'PWD': '/builds/slave/test', 11:25:16 INFO - 'SHELL': '/bin/bash', 11:25:16 INFO - 'SHLVL': '1', 11:25:16 INFO - 'TERM': 'linux', 11:25:16 INFO - 'TMOUT': '86400', 11:25:16 INFO - 'USER': 'cltbld', 11:25:16 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:16 INFO - '_': '/tools/buildbot/bin/python'} 11:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 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)) 11:25:17 INFO - Cleaning up... 11:25:17 INFO - Return code: 0 11:25:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:25:17 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 0x1969030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1acc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', '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 11:25:17 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 11:25:17 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 11:25:17 INFO - Using env: {'DISPLAY': ':0', 11:25:17 INFO - 'HOME': '/home/cltbld', 11:25:17 INFO - 'LANG': 'en_US.UTF-8', 11:25:17 INFO - 'LANGUAGE': 'en_US:en', 11:25:17 INFO - 'LOGNAME': 'cltbld', 11:25:17 INFO - 'MAIL': '/var/mail/cltbld', 11:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:17 INFO - 'MOZ_NO_REMOTE': '1', 11:25:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:17 INFO - 'NO_EM_RESTART': '1', 11:25:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:17 INFO - 'PWD': '/builds/slave/test', 11:25:17 INFO - 'SHELL': '/bin/bash', 11:25:17 INFO - 'SHLVL': '1', 11:25:17 INFO - 'TERM': 'linux', 11:25:17 INFO - 'TMOUT': '86400', 11:25:17 INFO - 'USER': 'cltbld', 11:25:17 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:17 INFO - '_': '/tools/buildbot/bin/python'} 11:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:25:17 INFO - Cleaning up... 11:25:17 INFO - Return code: 0 11:25:17 INFO - Running post-action listener: _resource_record_post_action 11:25:17 INFO - Running post-action listener: _start_resource_monitoring 11:25:17 INFO - Starting resource monitoring. 11:25:17 INFO - [mozharness: 2016-07-26 18:25:17.440373Z] Finished create-virtualenv step (success) 11:25:17 INFO - [mozharness: 2016-07-26 18:25:17.440800Z] Running install step. 11:25:17 INFO - Running pre-action listener: _resource_record_pre_action 11:25:17 INFO - Running main action method: install 11:25:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:17 INFO - Reading from file tmpfile_stdout 11:25:17 INFO - Detecting whether we're running mozinstall >=1.0... 11:25:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:25:17 INFO - Reading from file tmpfile_stdout 11:25:17 INFO - Output received: 11:25:17 INFO - Usage: mozinstall [options] installer 11:25:17 INFO - Options: 11:25:17 INFO - -h, --help show this help message and exit 11:25:17 INFO - -d DEST, --destination=DEST 11:25:17 INFO - Directory to install application into. [default: 11:25:17 INFO - "/builds/slave/test"] 11:25:17 INFO - --app=APP Application being installed. [default: firefox] 11:25:17 INFO - mkdir: /builds/slave/test/build/application 11:25:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:25:34 INFO - Reading from file tmpfile_stdout 11:25:35 INFO - Output received: 11:25:35 INFO - /builds/slave/test/build/application/firefox/firefox 11:25:35 INFO - Running post-action listener: _resource_record_post_action 11:25:35 INFO - [mozharness: 2016-07-26 18:25:35.001561Z] Finished install step (success) 11:25:35 INFO - [mozharness: 2016-07-26 18:25:35.001841Z] Running run-tests step. 11:25:35 INFO - Running pre-action listener: _resource_record_pre_action 11:25:35 INFO - Running main action method: run_tests 11:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:25:35 INFO - Python 2.7.3 11:25:35 INFO - Return code: 0 11:25:35 INFO - grabbing minidump binary from tooltool 11:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:35 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 0x1c283b0>, '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 0x1c292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c29740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:25:35 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 11:25:35 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 11:25:35 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 11:25:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:25:35 INFO - Return code: 0 11:25:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:25:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:25:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:25:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:25:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:25:35 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/1469547029/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 11:25:35 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/1469547029/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 11:25:35 INFO - Using env: {'DISPLAY': ':0', 11:25:35 INFO - 'HOME': '/home/cltbld', 11:25:35 INFO - 'LANG': 'en_US.UTF-8', 11:25:35 INFO - 'LANGUAGE': 'en_US:en', 11:25:35 INFO - 'LOGNAME': 'cltbld', 11:25:35 INFO - 'MAIL': '/var/mail/cltbld', 11:25:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:25:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:35 INFO - 'MOZ_NO_REMOTE': '1', 11:25:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:35 INFO - 'NO_EM_RESTART': '1', 11:25:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:35 INFO - 'PWD': '/builds/slave/test', 11:25:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:25:35 INFO - 'SHELL': '/bin/bash', 11:25:35 INFO - 'SHLVL': '1', 11:25:35 INFO - 'TERM': 'linux', 11:25:35 INFO - 'TMOUT': '86400', 11:25:35 INFO - 'USER': 'cltbld', 11:25:35 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698', 11:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:35 INFO - '_': '/tools/buildbot/bin/python'} 11:25:35 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/1469547029/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 11:25:35 INFO - mozversion application_buildid: 20160726083029 11:25:35 INFO - mozversion application_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 11:25:35 INFO - mozversion application_display_name: Nightly 11:25:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:25:35 INFO - mozversion application_name: Firefox 11:25:35 INFO - mozversion application_remotingname: firefox 11:25:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:25:35 INFO - mozversion application_vendor: Mozilla 11:25:35 INFO - mozversion application_version: 50.0a1 11:25:35 INFO - mozversion platform_buildid: 20160726083029 11:25:35 INFO - mozversion platform_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 11:25:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:25:35 INFO - mozversion platform_version: 50.0a1 11:25:35 INFO - using testdate: 1469557535 11:25:35 INFO - actual date: 1469557535 11:25:35 INFO - starting webserver on 'localhost:42374' 11:25:35 INFO - SUITE-START | Running 2 tests 11:25:35 INFO - TEST-START | dromaeo_css 11:25:35 INFO - operating with platform_type : linux_ 11:25:35 INFO - Initialising browser for dromaeo_css test... 11:25:35 INFO - TEST-INFO | started process 9240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBgo5SS/profile http://localhost:42374/getInfo.html) 11:25:38 INFO - PROCESS | 9240 | JavaScript error: , line 0: uncaught exception: undefined 11:25:39 INFO - PROCESS | 9240 | __metrics Screen width/height:1600/1200 11:25:39 INFO - PROCESS | 9240 | colorDepth:24 11:25:39 INFO - PROCESS | 9240 | Browser inner width/height: 1024/697 11:25:39 INFO - PROCESS | 9240 | __metrics 11:25:39 ERROR - PROCESS | 9240 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 11:25:40 INFO - TEST-INFO | 9240: exit 0 11:25:40 INFO - Browser initialized. 11:25:40 INFO - Running cycle 1/1 for dromaeo_css test... 11:25:40 INFO - TEST-INFO | started process 9358 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBgo5SS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:25:52 INFO - PROCESS | 9358 | RSS: Main: 165257216 11:25:52 INFO - PROCESS | 9358 | 11:26:41 INFO - PROCESS | 9358 | 1469557601920 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] 11:28:50 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42374/tests/dromaeo/cssquery-ext.html) 11:28:51 INFO - PROCESS | 9358 | RSS: Main: 170401792 11:28:51 INFO - PROCESS | 9358 | 11:31:53 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-ext.html (next: http://localhost:42374/tests/dromaeo/cssquery-jquery.html) 11:31:54 INFO - PROCESS | 9358 | RSS: Main: 167870464 11:31:54 INFO - PROCESS | 9358 | 11:34:55 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42374/tests/dromaeo/cssquery-mootools.html) 11:34:56 INFO - PROCESS | 9358 | RSS: Main: 171429888 11:34:56 INFO - PROCESS | 9358 | 11:37:58 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42374/tests/dromaeo/cssquery-prototype.html) 11:37:58 INFO - PROCESS | 9358 | RSS: Main: 166027264 11:37:58 INFO - PROCESS | 9358 | 11:41:01 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42374/tests/dromaeo/cssquery-yui.html) 11:41:01 INFO - PROCESS | 9358 | RSS: Main: 167948288 11:41:01 INFO - PROCESS | 9358 | 11:44:04 INFO - PROCESS | 9358 | Cycle 1(1): loaded http://localhost:42374/tests/dromaeo/cssquery-yui.html (next: http://localhost:42374/tests/dromaeo/cssquery-dojo.html) 11:44:05 INFO - PROCESS | 9358 | RSS: Main: 171020288 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | __start_tp_report 11:44:05 INFO - PROCESS | 9358 | _x_x_mozilla_page_load 11:44:05 INFO - PROCESS | 9358 | _x_x_mozilla_page_load_details 11:44:05 INFO - PROCESS | 9358 | |i|pagename|runs| 11:44:05 INFO - PROCESS | 9358 | |0;dojo.html;3100.1939495731112;3127.999040307103;3143.104215298639;3153.98423007885;3161.1464904475793;5218.1650935850275;5396.676199428032;5426.158945363465;5431.945680543194;5447.645903016301;5277.656952298098;5340.866478338044;5428.728563571825;5461.15352120421;5519;4901.215805471125;4937.284093806405;5025.195968645017;5068.949310506903;5079.79680812767;4967.7019378837385;5028.094942910275;5119.308893299412;5119.385673719159;5129.897402051956;4800.927986080212;4999.175136102539;5006.123928312531;5059.772310246048;5064.493550644943;4787.16224660683;4868.172410690156;4888.364512613373;4897.559219670211;4927.531884470969;4849.151398505247;4851.27230915362;4853.902921941577;4858.076965376567;4899.632527560448;5311.282976798121;5336.30628018359;5347.25138480613;5348.277982472356;5424.321959755031;5836.212111364954;5895.852603684899;5907.7046147692445;5925.022371308729;5952.434518720715;5945.256842894638;5959.642421454727;5964;5973.313068997034;5982.551308651869;5310.044192045391;5348.866278343073;5527.917081243784;5532.529734972532;5537.750801213955;5865.765369385177;5939.90111829313;5949.702514874239;5982.760689572456;5985.581009329305;5681.659100453986;5689.971550142222;5692.402297758758;5692.601517893708;5701.344345400249;5556.972215138899;5759.049756790083;5771.769129234867;5775.306963164448;5796.797112101056;5711.600187986801;5822.359540450547;5856.677882716406;5932.673702946293;5939.10913362999;5664.6034777566;5672.064109421982;5715.714214289269;5742.052561327418;5797.797077102281;4374.956250437519;4382.167388196232;4384.320430333303;4392.2752745797225;4420.557944205554;3856.3444214483607;3882.068303607114;3895.5130608673917;3900.4929359183125;3929.4891664084057;5475.4524547545725;5493.478119578634;5525.585581081435;5532.087205611109;5547.972260138674;5780.739866706008;5786.594938354275;5788.913166302509;5792.102224155263;5798.797042103506;5346.759395827197;5504.752286147133;5511.338639363222;5614.242077319591;5675.148727690879;5128.615353848401;5129.333186685707;5141.948580514222;5161.458046905096;5175.741212939338;6128.63228206309;6158.322584515699;6161.4146656067605;6163.260408750979;6180.47465965398;5692.288463942007;5733.8566535836935;5745.109508026263;5961.552883533753;5991;6022.337542870368;6028.125921741235;6034.336223015377;6057;6076.513878889767;5679.8864022720145;5684.516816070681;5729;5737.168110624019;5737.282839645044;5689.77240910359;5718.685472299064;5732.7706891723865;5810.622309549865;5871.588988770745;5620.185073164286;5641.323041235078;5655.321361436654;5664.5751568631695;5699.0311647020935;5579.693116878449;5583.302087239095;5583.832485025456;5624.74688639004;5625.409332020079;5494.835154945358;5502.752376143163;5583.4416558345465;5597.384287728428;5613.887722245614;5628.521575665951;5662.660240385425;5692.345380281155;5693;5729.570282228933;5538.9169162461785;5570.972145139411;5571.498565129158;5580.190872323572;5588.552915766811 11:44:05 INFO - PROCESS | 9358 | |1;ext.html;568.2499101186432;594.6313285762827;598.9880202395952;614.9046897730852;620.1658768955753;42491.57508424915;43983.900402489955;44019.779901100446;44052.339214911764;47798.52201477984;45312;45509.544904551025;45988.31017534732;46090.30864537034;46279;9065.093490650932;9158.450492970444;9212.447253164799;9222.907770922271;9226.492542910137;9086.22767064797;9164.679236226733;9165.908340916605;9172.174504294611;9175.678851240207;9088.272938164931;9186.989431162567;9214.539273036355;9220.07799220076;9278.35051546392;9093.590788414504;9142.131497507726;9189.632414703437;9195.908040919572;9202.539873006357;9064.909350906473;9102.226310763594;9114.179723824887;9115.407498512575;9145.862812057825;43481.347779783326;43731.562684373064;43834.342484862755;43852.56147438517;43988;3699.315626609086;3740.1584643455276;3804.714646401531;3857.8263978121054;4038.2932986727205;32317.192070198198;32781.18047048819;32802.17994550155;33088;33114.834425827954;4433.512313645497;4489.331095660509;4542.909141817178;4596.88507787308;4695.178343789823;349744;350503;351236;351681;352024.99999999744;53576;53991;53997.73001134969;54065;54221;63276;63614.68192659007;63820;63826.36173638297;64101.67949160224;15525.223738813014;15659.608509787118;15664.451744188898;15675.294611742498;15746.527604171893;12427.689307767198;12443;12469.251844889335;12488.313142777053;12515.436805343781;17430.56423589395;17929.820701792814;18213.45359639213;18288.81711182898;18293.268269269345;6704.266260829766;6759.425448836904;6903.275156108536;7008.894866577006;7128.893066604005;1005.4269066631949;1041.8124737547162;1080.400377790318;1131.8132508136066;1132.6998345438465;31666.05001849948;31729.84135079356;32223.194420139684;32532.674673253438;32765.672343276263;1108.3128460354633;1156.2195518025467;1182.3024415594841;1197.6407077876602;1204.4399354300292;17133.485995420157;17178.656426871643;17228.224729887184;17303;17354.392596259324;599927;604531;605050;605402;609885;59372.406275936686;59615.40384596099;59786;59803.70098149481;60158.398416016156;1342.5099838558856;1358.65354334646;1359.5377571625497;1384.2386687321932;1408.5703860322494;31181;33352;34457.655423445445;34601.48097778485;34700.30599388049;50550;51214.48785512097;51231.2315315278;51362.48637513727;51477.7426112867;424468;429265;429641;431553;433476;28300.71699282981;28502.85748571244;28622.713772862004;28640.28399289951;28693.56959645565;48155.75922120367;49254.50745492499;49807;50879;50967.74516127395;41835.581644184385;42949.78525107355;43375.34936976011;44346.334804977305;44525.33212001887;42004.36993445162;42092.579074208865;42095;43091.56908431001;43125.13749725051;41150;41823.7908810454;42024;42110.57889421067;42260.78869605633;35635.82182089073;37348.81325593355;37649.811750942165;37881.54818341136;38141;27982.3004424891;28549.857250713612;28661;28742.856285719274;28947.276318092216 11:44:05 INFO - PROCESS | 9358 | |2;jquery.html;363.7690066807576;385.1065527975097;387.53882879373555;390.0424457955719;406.97151199416055;17847.375341863037;20684.896575517138;20889.895550522226;20906.16375344988;20909.477263068413;18358.816411835876;18619;18681.626367472643;18753;19694.606107877833;5363.839084827452;5600.215969764236;5766.0486019806685;5785.82642520725;6371.362863713638;6470.385313395243;6471.676416179196;6495.512836537255;6496.740130394778;6505.707243174045;6264.906026409608;6272.905906411407;6321.399467050623;6328.528597212626;6356.2054743157105;6143.9385606143815;6286.968565157191;6299.086632438294;6336.714847831859;6360.52296077796;5459.317585301837;5572.5263352615075;5619.550435965154;5637.8590535236535;5641.633293835928;16759;19959.501012474804;20719;20762.5847483051;23254.41863953398;2272.5682120397073;2604.492124035814;2642.616820561008;2721.6189733437336;2948.7346138847606;9732.805343893082;12131.575394861226;12143.878561214451;13558.322083895864;14810.629734256621;1899.5156235159945;1910.843939416647;1939.3890924358923;2013.9395818125479;2042.7446569178853;75652;83522.58238708768;83584.58207708923;84841;87103.56448217719;10479.895201048044;10613.097886679565;10788.572571171424;10909.18181136418;11000.504977276041;14733.778993315109;14817.925910370379;14887.627809304637;14895.255237238094;14912.254387280593;7334.303241192185;7763.534187948741;7824.37405007598;7847.568383738949;8050.879236811453;7145.749898753519;7287.6356182190675;7417.443691723142;7467.813304667318;7628.389728821681;4377.759223242752;4487.102579484116;4565.333653216619;4573.794179261941;4574.954250457453;1912.598354345603;1975.1605567633699;2003.6994450832492;2004.3986803958753;2032.8780273183656;915.8259930613162;979.9461029643295;1025.5231317437278;1036.1917704190744;1073.2058276875057;19574.412767616996;19972.10125544354;20090.89954550247;20347.491312717302;20504.69242961357;966.7583104223944;976.8827740671023;982.8771403574553;1026.373911913732;1076.4187338836941;9357.672481463253;10946.288491248044;11014.889851101547;11043.668689939315;11244.437778111062;487489;487877;501262;502635;510348;95441;95752.52123739476;95754.52122739481;95778.52110739541;95846.52076739572;987.8666380038746;1024.1294899335505;1024.7028361775;1025.261811495722;1043.5147656339839;17241.39655112065;17798.555036124202;18023;18328.81671183271;18342.1746021432;39617;40367;40785.79607101945;40887.1822563553;42127;53594;55623;56418;57964;58092;7964.123946365779;8059.637316320897;8148.266656000988;8234.217749313806;8350.540720260444;12197.268163910194;12819.423125959167;13178.60464186076;13256.80114798298;13393.263370514325;10785.62250321235;11194.817010683164;11266.605668801552;11465.31208127515;11702.473388697359;11324.773504530029;11446.828297575377;11636.010939069934;11701.356425396685;11826.76346473083;11059.61291354831;11270.661880143609;11586.304821710724;11796.528138874692;11817.231879927776;7684.193159718373;7876.606169691607;7887.053553573608;7910.169432209766;8035.236652518001;10614.203934704774;11106.944465277622;11110.16673749456;11275.210735248455;11368.715782105513 11:44:05 INFO - PROCESS | 9358 | |3;mootools.html;1508.4117194294226;1519.8480151984802;1667.897710258918;1782.6523827853566;1795.8563314934806;7753.92246077539;8677.524820780467;8692.739217823468;9329.20701740352;9330.160285574288;8794.340424468159;9033.683821066264;9126.589303481343;9128.634854605807;9130.132637399436;2302.597045517032;2316.6177580699164;2319.153508969231;2320.8491448055906;2336;2257.2889539794996;2266.977330226693;2339.2280547419396;2340.157543284416;2363.196513185517;2218.5895609312247;2246.7416247131628;2248.6064938635673;2251.2570851618925;2262.1856131792542;2268.3988742983124;2270.9894097126785;2274.55646149001;2286;2291.541691661674;2275.5335156293086;2289.748127705951;2306.4695120122296;2307.2962746362355;2308.215206829686;9068.68259610917;9141.451512909247;9164.725058248263;9166.908330916673;9203.907960920438;5151.459096794858;5218.582513398919;5260.763265653017;5283.471652834686;5289.44460831611;3159.557661927332;3250.2686895448574;3309.0900002499266;3360.630330663625;3371.544841446422;1110.4281295133012;1130.4969288666546;1137.7496950670923;1140.7718456308771;1165.626999360205;12692.11155219135;12724.109312348139;12772.61682149537;12772.872271277354;12826.679333016657;4272.124214536013;4347.282698354736;4511.323301504801;4512.4133862597655;4593.517680643485;10574.841377379345;10640.574377024986;10661.786764264827;10703.197260205516;10773.622923197807;5245.213218017328;5269.499397557226;5273.973630131824;5289.629725919226;5302.814401495929;4152.211079894811;4672.18236808564;4766.308885211625;4803.447603525569;4812.109759694467;3671.8714844980295;3786.091338078841;3805.124821291063;3866.5360156780803;3869.380899056194;1228.8832560906699;1239.6590937492263;1243.1981372015;1246.5512415530402;1247.8065899785547;984.1634610581092;987.3088837813559;995.7112437393075;1000.5697550053546;1032.99483502582;6250.218722659668;6344.555881088372;6370.4903607712195;6521.380468855451;6776.627285499345;1000.0199804191933;1012.8531362952331;1018.3584341864726;1041.1670663469195;1049.6378749331468;4254.574542545783;4339.978300108479;4464.575865292792;4484.753338566345;4616.446026476844;11074;11559.248648837798;11601.18791684574;11614.535418583331;11680.70798230051;11393.373364465035;11460.426978651201;11488.88511114895;11496;11549;7650.8087297818;7933.563653999086;7985.920140798634;8217.876731849026;8320.583970801435;6304.905426418516;6332.746690132343;6447.774327898689;6473.255575608865;6515.381038801306;10497.737556561147;10569.682909512727;10671.35971841692;10872.184586155912;10882.12942964545;10667.146628269564;10770.623028193975;10789;10814.405207713336;10840.403777792297;7748.380129589506;8028.157043510348;8079.555624440713;8095.311898488694;8176.509409435453;8132.715354962548;8191.918080819116;8195.795105122419;8248.876266856121;8272.751817445485;7558.639444899971;7586.089669239726;7813.257740514642;7814.257645523666;7838.216178382117;7502.887456688263;7683.078030636136;7697.8845317319165;7726.575038372944;7754.534727916343;6719.227288861844;6804.149481314836;6816.863662726817;6822.044913712183;6831.555948863308;5372.4627537247525;5410.594205434687;5452.345718513804;5506.394296627448;5611.074172761449;5418.810341638024;5424.484673955968;5446.972765136149;5497.642653227527;5643.633163844501 11:44:05 INFO - PROCESS | 9358 | |4;prototype.html;314.83313843662836;323.26296045017364;326.0950861359726;329.1540740297435;329.344604237567;5429.239906413095;5482.13607373493;5605.7197140143035;5642.7178641067985;5651.2370829937945;5518.641288316257;5519.337679478458;5533.778648854051;5537.640053396526;5551.861203469915;4330.913381732364;4332.805023773922;4332.985161899708;4336.284513055342;4337.197618440583;4101.876943691694;4321.762303073346;4341.934870976938;4397.494288156871;4398.824047038114;4249.19265339586;4279.379489973952;4281.464816897887;4303.35449682547;4313.088665151026;4286.721363111404;4293.441852559147;4309.418228539138;4313.633341165973;4321.36396797953;3649.120702838014;3749.3626083565587;3760.172761992357;3762.322781899257;3784.8864534064023;5394.379646340682;5398.24424580559;5420.512153906128;5448.45515448456;5471.37079235889;4934.432540257881;4935.876603084916;4954.405471343426;4960.677555958851;4964.9573589546235;5625.128105143709;5745.48290653839;5793.478586927196;5798.9130163047585;5802.823941022117;4095.508538975342;4114.444549985744;4126.3397856343145;4153;4161.313383291753;5868.589198756132;6045.3047899491685;6048.7882924097885;6078;6110.6944652767625;4204.831806727697;4224.260754367973;4224.809883555247;4225.471816022997;4227.577242275809;4276.764777937244;4276.8289268429535;4285.100128972888;4288.957110428918;4299.484061912591;4920.6555541111775;4935.777889994959;4940.456549779521;4943.629227807929;4950.9009819803405;5677.801276955287;5698.857528561819;5856.912146317809;5882.735276912549;5900.970495147582;1453.4694836384856;1476.5422718957157;1481.9851801481848;1484.6882154747625;1485.6805786755683;905.42053086025;930.1628534319032;944.7260294514651;952.2049089010616;953.620716312804;5030.84907452777;5060.2662613920775;5077.949220507821;5084.491550844886;5085.389753229562;5222.634415590948;5244.059697911797;5274.947250527523;5308.389535203424;5340.305760251143;4719.183114500942;5025.7989680412375;5033.005361809791;5048.469910659402;5057.544820966131;2861.642294713161;2866.0398766413487;2884.3510210202535;2884.9134525963836;2905.5205891027745;5453.700046497402;5484;5484.533814625722;5554.527865131509;5563.443655634485;5114.3095682083185;5427.185922111715;5433.864153396196;5438.347398312228;5458.372287187024;5488.204210389471;5488.231647569343;5499.642523236057;5586.776528938797;5610.971945140248;5114.8977020460125;5347.342323879454;5422.48486393792;5450.781968721365;5472.835814925558;5200.4279529251;5204.245384419314;5273.020743280034;5287.867803304948;5307.150855863043;5489.7648029193115;5562.97085039269;5581.627808181425;5610.438956104357;5615.354234262949;3713.1274150574054;3870.832333893029;3905.3556163232747;3907.6092390761833;3912.237113762789;5311.707856067954;5345.705986170642;5356.982173387044;5668.26312579362;5803.883922321445;5789.681567513659;5845.304861590092;5852.882942341214;5863.1791549184;5900.793472228452;5881.941180588139;5888.676122813115;5888.911666325094;5892.351841297368;5910.379410161871;5614.354349249726;5703.344115426779;5720.628159169641;5734.05388110957;5746.13807928814;5883.882322353444;5900.409959004237;5916.378780228014;5935.762569497172;5952.196453478899;5594.328680558359;5700.258966334516;5764.616492041963;5773.826785196451;5810.622309549865 11:44:05 INFO - PROCESS | 9358 | |5;yui.html;705.08691244838;712.6365553567682;762.9504082234654;794.5113755040652;875.5315905990296;16474.670506589875;17037.233324500397;17042.318307267695;17045.31818727251;17084.31662733491;14957.177355245458;15863;16555.420560280392;16563.25465354059;16809.075500847448;791.0981481111537;791.7862177212149;792.71858490236;796.6295672512281;800.5516910530122;799.7640695994667;805.556943680976;807.3823525003377;807.5356669914793;820.9343252539812;872.7992561710811;874.1433395272637;875.1729615513342;877.3639111644071;878.6704985630389;865.7143142762873;870.6212797433137;872.040755169315;873.3929918706501;873.7422460374205;640.5612155673379;642.4025656139788;642.7139922734385;642.810370940569;644.2365796531079;14200.715985680228;14254.3585538651;14256.572302830931;14260.215688137148;14261.857381426156;14496.855031449655;14526.564203073925;14672.19302938338;14676.19280939548;14724.926375368163;12450.688732781662;12605.558805441766;12609.28542431952;12786.104972651918;12801.29592872401;3919.8432062717457;3960.32674445345;3985.362342025291;4063.7358571692744;4150.564190759957;62127.37872621261;63764.68117659382;64330;76581;77624.61187694082;11812.468438920268;11822;11917.642470725892;11945.670594234214;12162.756744865053;19271.22915083409;19403.80596194048;19459.12433940476;19533.413997580094;19637.50906227356;8465.534395608178;8491.108433614505;8654.023567428361;8719.171678690514;8809.559522023874;4817.686850354716;4847.587955023862;4869.902601947942;4898.608111351084;4941.357623508921;3290.4570745826672;3324.1024923270584;3370.915727106842;3377.341418423433;3403.370376480359;572.705056895699;576.2854060964457;581.9476247137778;600.0579090827447;603.5835273661247;681.141761380664;691.8962155676588;700.2192555300904;703.8381172330393;722.1370462297609;9945.950270248602;10147.34042287249;10170.440625765506;10231.846522302172;10292.845607315896;667.1926968368329;680.6256558892688;705.1538154214962;730.8063363208754;758.8140905478083;4639.768011599406;4763.54746299101;4907.288443175791;4913.385826771653;4971.577415919687;75987;105015.47492262642;116713;121946.39026804987;124533;60554.697226514465;81610.59194703988;103474;103876.48061759643;105974.4701276504;88466.55766721125;89578;90440.54779726059;92916.53541732248;93964;9282.628694852401;9346.719598412059;9429.3399462037;9449.43303401798;9455.432674039579;38089.238215236095;43621.781891090344;52659.57712748659;53375.733121335696;54477.45522544724;75518.24481755112;78332;79308;80853.59573202096;82027.58986205031;11621.709457263636;11927.463264153308;11963.461644225858;12025;12074.094442916992;13554.796678049639;13938;14188.716225675635;14224.217668027944;14712.558623240891;11945.761084778082;12104.878951210376;12406.937965310115;12850.807237891626;12927.224366538037;11847;12334;12644.30456324911;12751.171173873669;12774.425150868048;12550.937245313715;12816.743665126458;13249.337533123113;13287.40206690682;13449;10350.378977261389;10507;10610;10640.148788097089;10881.020708136306;9687.985345067877;9918.355306905029;10377.273590848568;11090.467771418245;11160.88839111631 11:44:05 INFO - PROCESS | 9358 | __end_tp_report 11:44:05 INFO - PROCESS | 9358 | __start_cc_report 11:44:05 INFO - PROCESS | 9358 | _x_x_mozilla_cycle_collect,346 11:44:05 INFO - PROCESS | 9358 | __end_cc_report 11:44:05 INFO - PROCESS | 9358 | __startTimestamp1469558645176__endTimestamp 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | ------- Summary: start ------- 11:44:05 INFO - PROCESS | 9358 | Number of tests: 6 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#0] dojo.html Cycles:165 Average:5399.19 Median:5583.44 stddev:624.27 (11.2%) stddev-sans-first:599.57 11:44:05 INFO - PROCESS | 9358 | Values: 3100.2 3128.0 3143.1 3154.0 3161.1 5218.2 5396.7 5426.2 5431.9 5447.6 5277.7 5340.9 5428.7 5461.2 5519.0 4901.2 4937.3 5025.2 5068.9 5079.8 4967.7 5028.1 5119.3 5119.4 5129.9 4800.9 4999.2 5006.1 5059.8 5064.5 4787.2 4868.2 4888.4 4897.6 4927.5 4849.2 4851.3 4853.9 4858.1 4899.6 5311.3 5336.3 5347.3 5348.3 5424.3 5836.2 5895.9 5907.7 5925.0 5952.4 5945.3 5959.6 5964.0 5973.3 5982.6 5310.0 5348.9 5527.9 5532.5 5537.8 5865.8 5939.9 5949.7 5982.8 5985.6 5681.7 5690.0 5692.4 5692.6 5701.3 5557.0 5759.0 5771.8 5775.3 5796.8 5711.6 5822.4 5856.7 5932.7 5939.1 5664.6 5672.1 5715.7 5742.1 5797.8 4375.0 4382.2 4384.3 4392.3 4420.6 3856.3 3882.1 3895.5 3900.5 3929.5 5475.5 5493.5 5525.6 5532.1 5548.0 5780.7 5786.6 5788.9 5792.1 5798.8 5346.8 5504.8 5511.3 5614.2 5675.1 5128.6 5129.3 5141.9 5161.5 5175.7 6128.6 6158.3 6161.4 6163.3 6180.5 5692.3 5733.9 5745.1 5961.6 5991.0 6022.3 6028.1 6034.3 6057.0 6076.5 5679.9 5684.5 5729.0 5737.2 5737.3 5689.8 5718.7 5732.8 5810.6 5871.6 5620.2 5641.3 5655.3 5664.6 5699.0 5579.7 5583.3 5583.8 5624.7 5625.4 5494.8 5502.8 5583.4 5597.4 5613.9 5628.5 5662.7 5692.3 5693.0 5729.6 5538.9 5571.0 5571.5 5580.2 5588.6 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#1] ext.html Cycles:180 Average:62437.87 Median:237339.07 stddev:126003.62 (53.1%) stddev-sans-first:126271.47 11:44:05 INFO - PROCESS | 9358 | Values: 568.2 594.6 599.0 614.9 620.2 42491.6 43983.9 44019.8 44052.3 47798.5 45312.0 45509.5 45988.3 46090.3 46279.0 9065.1 9158.5 9212.4 9222.9 9226.5 9086.2 9164.7 9165.9 9172.2 9175.7 9088.3 9187.0 9214.5 9220.1 9278.4 9093.6 9142.1 9189.6 9195.9 9202.5 9064.9 9102.2 9114.2 9115.4 9145.9 43481.3 43731.6 43834.3 43852.6 43988.0 3699.3 3740.2 3804.7 3857.8 4038.3 32317.2 32781.2 32802.2 33088.0 33114.8 4433.5 4489.3 4542.9 4596.9 4695.2 349744.0 350503.0 351236.0 351681.0 352025.0 53576.0 53991.0 53997.7 54065.0 54221.0 63276.0 63614.7 63820.0 63826.4 64101.7 15525.2 15659.6 15664.5 15675.3 15746.5 12427.7 12443.0 12469.3 12488.3 12515.4 17430.6 17929.8 18213.5 18288.8 18293.3 6704.3 6759.4 6903.3 7008.9 7128.9 1005.4 1041.8 1080.4 1131.8 1132.7 31666.1 31729.8 32223.2 32532.7 32765.7 1108.3 1156.2 1182.3 1197.6 1204.4 17133.5 17178.7 17228.2 17303.0 17354.4 599927.0 604531.0 605050.0 605402.0 609885.0 59372.4 59615.4 59786.0 59803.7 60158.4 1342.5 1358.7 1359.5 1384.2 1408.6 31181.0 33352.0 34457.7 34601.5 34700.3 50550.0 51214.5 51231.2 51362.5 51477.7 424468.0 429265.0 429641.0 431553.0 433476.0 28300.7 28502.9 28622.7 28640.3 28693.6 48155.8 49254.5 49807.0 50879.0 50967.7 41835.6 42949.8 43375.3 44346.3 44525.3 42004.4 42092.6 42095.0 43091.6 43125.1 41150.0 41823.8 42024.0 42110.6 42260.8 35635.8 37348.8 37649.8 37881.5 38141.0 27982.3 28549.9 28661.0 28742.9 28947.3 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#2] jquery.html Cycles:180 Average:29290.89 Median:2835.18 stddev:82164.47 (2898.0%) stddev-sans-first:82366.25 11:44:05 INFO - PROCESS | 9358 | Values: 363.8 385.1 387.5 390.0 407.0 17847.4 20684.9 20889.9 20906.2 20909.5 18358.8 18619.0 18681.6 18753.0 19694.6 5363.8 5600.2 5766.0 5785.8 6371.4 6470.4 6471.7 6495.5 6496.7 6505.7 6264.9 6272.9 6321.4 6328.5 6356.2 6143.9 6287.0 6299.1 6336.7 6360.5 5459.3 5572.5 5619.6 5637.9 5641.6 16759.0 19959.5 20719.0 20762.6 23254.4 2272.6 2604.5 2642.6 2721.6 2948.7 9732.8 12131.6 12143.9 13558.3 14810.6 1899.5 1910.8 1939.4 2013.9 2042.7 75652.0 83522.6 83584.6 84841.0 87103.6 10479.9 10613.1 10788.6 10909.2 11000.5 14733.8 14817.9 14887.6 14895.3 14912.3 7334.3 7763.5 7824.4 7847.6 8050.9 7145.7 7287.6 7417.4 7467.8 7628.4 4377.8 4487.1 4565.3 4573.8 4575.0 1912.6 1975.2 2003.7 2004.4 2032.9 915.8 979.9 1025.5 1036.2 1073.2 19574.4 19972.1 20090.9 20347.5 20504.7 966.8 976.9 982.9 1026.4 1076.4 9357.7 10946.3 11014.9 11043.7 11244.4 487489.0 487877.0 501262.0 502635.0 510348.0 95441.0 95752.5 95754.5 95778.5 95846.5 987.9 1024.1 1024.7 1025.3 1043.5 17241.4 17798.6 18023.0 18328.8 18342.2 39617.0 40367.0 40785.8 40887.2 42127.0 53594.0 55623.0 56418.0 57964.0 58092.0 7964.1 8059.6 8148.3 8234.2 8350.5 12197.3 12819.4 13178.6 13256.8 13393.3 10785.6 11194.8 11266.6 11465.3 11702.5 11324.8 11446.8 11636.0 11701.4 11826.8 11059.6 11270.7 11586.3 11796.5 11817.2 7684.2 7876.6 7887.1 7910.2 8035.2 10614.2 11106.9 11110.2 11275.2 11368.7 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#3] mootools.html Cycles:180 Average:5937.45 Median:4343.63 stddev:3442.11 (79.2%) stddev-sans-first:3435.68 11:44:05 INFO - PROCESS | 9358 | Values: 1508.4 1519.8 1667.9 1782.7 1795.9 7753.9 8677.5 8692.7 9329.2 9330.2 8794.3 9033.7 9126.6 9128.6 9130.1 2302.6 2316.6 2319.2 2320.8 2336.0 2257.3 2267.0 2339.2 2340.2 2363.2 2218.6 2246.7 2248.6 2251.3 2262.2 2268.4 2271.0 2274.6 2286.0 2291.5 2275.5 2289.7 2306.5 2307.3 2308.2 9068.7 9141.5 9164.7 9166.9 9203.9 5151.5 5218.6 5260.8 5283.5 5289.4 3159.6 3250.3 3309.1 3360.6 3371.5 1110.4 1130.5 1137.7 1140.8 1165.6 12692.1 12724.1 12772.6 12772.9 12826.7 4272.1 4347.3 4511.3 4512.4 4593.5 10574.8 10640.6 10661.8 10703.2 10773.6 5245.2 5269.5 5274.0 5289.6 5302.8 4152.2 4672.2 4766.3 4803.4 4812.1 3671.9 3786.1 3805.1 3866.5 3869.4 1228.9 1239.7 1243.2 1246.6 1247.8 984.2 987.3 995.7 1000.6 1033.0 6250.2 6344.6 6370.5 6521.4 6776.6 1000.0 1012.9 1018.4 1041.2 1049.6 4254.6 4340.0 4464.6 4484.8 4616.4 11074.0 11559.2 11601.2 11614.5 11680.7 11393.4 11460.4 11488.9 11496.0 11549.0 7650.8 7933.6 7985.9 8217.9 8320.6 6304.9 6332.7 6447.8 6473.3 6515.4 10497.7 10569.7 10671.4 10872.2 10882.1 10667.1 10770.6 10789.0 10814.4 10840.4 7748.4 8028.2 8079.6 8095.3 8176.5 8132.7 8191.9 8195.8 8248.9 8272.8 7558.6 7586.1 7813.3 7814.3 7838.2 7502.9 7683.1 7697.9 7726.6 7754.5 6719.2 6804.1 6816.9 6822.0 6831.6 5372.5 5410.6 5452.3 5506.4 5611.1 5418.8 5424.5 5447.0 5497.6 5643.6 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#4] prototype.html Cycles:180 Average:4711.39 Median:5310.05 stddev:1368.94 (25.8%) stddev-sans-first:1332.42 11:44:05 INFO - PROCESS | 9358 | Values: 314.8 323.3 326.1 329.2 329.3 5429.2 5482.1 5605.7 5642.7 5651.2 5518.6 5519.3 5533.8 5537.6 5551.9 4330.9 4332.8 4333.0 4336.3 4337.2 4101.9 4321.8 4341.9 4397.5 4398.8 4249.2 4279.4 4281.5 4303.4 4313.1 4286.7 4293.4 4309.4 4313.6 4321.4 3649.1 3749.4 3760.2 3762.3 3784.9 5394.4 5398.2 5420.5 5448.5 5471.4 4934.4 4935.9 4954.4 4960.7 4965.0 5625.1 5745.5 5793.5 5798.9 5802.8 4095.5 4114.4 4126.3 4153.0 4161.3 5868.6 6045.3 6048.8 6078.0 6110.7 4204.8 4224.3 4224.8 4225.5 4227.6 4276.8 4276.8 4285.1 4289.0 4299.5 4920.7 4935.8 4940.5 4943.6 4950.9 5677.8 5698.9 5856.9 5882.7 5901.0 1453.5 1476.5 1482.0 1484.7 1485.7 905.4 930.2 944.7 952.2 953.6 5030.8 5060.3 5077.9 5084.5 5085.4 5222.6 5244.1 5274.9 5308.4 5340.3 4719.2 5025.8 5033.0 5048.5 5057.5 2861.6 2866.0 2884.4 2884.9 2905.5 5453.7 5484.0 5484.5 5554.5 5563.4 5114.3 5427.2 5433.9 5438.3 5458.4 5488.2 5488.2 5499.6 5586.8 5611.0 5114.9 5347.3 5422.5 5450.8 5472.8 5200.4 5204.2 5273.0 5287.9 5307.2 5489.8 5563.0 5581.6 5610.4 5615.4 3713.1 3870.8 3905.4 3907.6 3912.2 5311.7 5345.7 5357.0 5668.3 5803.9 5789.7 5845.3 5852.9 5863.2 5900.8 5881.9 5888.7 5888.9 5892.4 5910.4 5614.4 5703.3 5720.6 5734.1 5746.1 5883.9 5900.4 5916.4 5935.8 5952.2 5594.3 5700.3 5764.6 5773.8 5810.6 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - PROCESS | 9358 | [#5] yui.html Cycles:180 Average:20295.62 Median:24130.77 stddev:29301.38 (121.4%) stddev-sans-first:29346.66 11:44:05 INFO - PROCESS | 9358 | Values: 705.1 712.6 763.0 794.5 875.5 16474.7 17037.2 17042.3 17045.3 17084.3 14957.2 15863.0 16555.4 16563.3 16809.1 791.1 791.8 792.7 796.6 800.6 799.8 805.6 807.4 807.5 820.9 872.8 874.1 875.2 877.4 878.7 865.7 870.6 872.0 873.4 873.7 640.6 642.4 642.7 642.8 644.2 14200.7 14254.4 14256.6 14260.2 14261.9 14496.9 14526.6 14672.2 14676.2 14724.9 12450.7 12605.6 12609.3 12786.1 12801.3 3919.8 3960.3 3985.4 4063.7 4150.6 62127.4 63764.7 64330.0 76581.0 77624.6 11812.5 11822.0 11917.6 11945.7 12162.8 19271.2 19403.8 19459.1 19533.4 19637.5 8465.5 8491.1 8654.0 8719.2 8809.6 4817.7 4847.6 4869.9 4898.6 4941.4 3290.5 3324.1 3370.9 3377.3 3403.4 572.7 576.3 581.9 600.1 603.6 681.1 691.9 700.2 703.8 722.1 9946.0 10147.3 10170.4 10231.8 10292.8 667.2 680.6 705.2 730.8 758.8 4639.8 4763.5 4907.3 4913.4 4971.6 75987.0 105015.5 116713.0 121946.4 124533.0 60554.7 81610.6 103474.0 103876.5 105974.5 88466.6 89578.0 90440.5 92916.5 93964.0 9282.6 9346.7 9429.3 9449.4 9455.4 38089.2 43621.8 52659.6 53375.7 54477.5 75518.2 78332.0 79308.0 80853.6 82027.6 11621.7 11927.5 11963.5 12025.0 12074.1 13554.8 13938.0 14188.7 14224.2 14712.6 11945.8 12104.9 12406.9 12850.8 12927.2 11847.0 12334.0 12644.3 12751.2 12774.4 12550.9 12816.7 13249.3 13287.4 13449.0 10350.4 10507.0 10610.0 10640.1 10881.0 9688.0 9918.4 10377.3 11090.5 11160.9 11:44:05 INFO - PROCESS | 9358 | -------- Summary: end -------- 11:44:05 INFO - PROCESS | 9358 | 11:44:05 INFO - TEST-INFO | 9358: exit 0 11:44:05 INFO - TEST-OK | dromaeo_css | took 1110654ms 11:44:05 INFO - TEST-START | kraken 11:44:05 INFO - operating with platform_type : linux_ 11:44:05 INFO - Initialising browser for kraken test... 11:44:05 INFO - TEST-INFO | started process 9602 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8l2dNA/profile http://localhost:42374/getInfo.html) 11:44:08 INFO - PROCESS | 9602 | JavaScript error: , line 0: uncaught exception: undefined 11:44:09 INFO - PROCESS | 9602 | __metrics Screen width/height:1600/1200 11:44:09 INFO - PROCESS | 9602 | colorDepth:24 11:44:09 INFO - PROCESS | 9602 | Browser inner width/height: 1024/697 11:44:09 INFO - PROCESS | 9602 | __metrics 11:44:10 ERROR - PROCESS | 9602 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 11:44:11 INFO - TEST-INFO | 9602: exit 0 11:44:11 INFO - Browser initialized. 11:44:11 INFO - Running cycle 1/1 for kraken test... 11:44:11 INFO - TEST-INFO | started process 9720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8l2dNA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:44:22 INFO - PROCESS | 9720 | RSS: Main: 164990976 11:44:22 INFO - PROCESS | 9720 | 11:44:25 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42374/tests/kraken/driver.html?testName=audio-beat-detection) 11:44:26 INFO - PROCESS | 9720 | RSS: Main: 170549248 11:44:26 INFO - PROCESS | 9720 | 11:44:29 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42374/tests/kraken/driver.html?testName=audio-dft) 11:44:29 INFO - PROCESS | 9720 | RSS: Main: 169218048 11:44:29 INFO - PROCESS | 9720 | 11:44:32 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42374/tests/kraken/driver.html?testName=audio-fft) 11:44:33 INFO - PROCESS | 9720 | RSS: Main: 165703680 11:44:33 INFO - PROCESS | 9720 | 11:44:35 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42374/tests/kraken/driver.html?testName=audio-oscillator) 11:44:35 INFO - PROCESS | 9720 | RSS: Main: 166551552 11:44:35 INFO - PROCESS | 9720 | 11:44:38 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42374/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:44:38 INFO - PROCESS | 9720 | RSS: Main: 167194624 11:44:38 INFO - PROCESS | 9720 | 11:44:41 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42374/tests/kraken/driver.html?testName=imaging-darkroom) 11:44:42 INFO - PROCESS | 9720 | RSS: Main: 167157760 11:44:42 INFO - PROCESS | 9720 | 11:44:46 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42374/tests/kraken/driver.html?testName=imaging-desaturate) 11:44:46 INFO - PROCESS | 9720 | RSS: Main: 164823040 11:44:46 INFO - PROCESS | 9720 | 11:44:49 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42374/tests/kraken/driver.html?testName=json-parse-financial) 11:44:50 INFO - PROCESS | 9720 | RSS: Main: 166338560 11:44:50 INFO - PROCESS | 9720 | 11:44:51 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42374/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:44:52 INFO - PROCESS | 9720 | RSS: Main: 166768640 11:44:52 INFO - PROCESS | 9720 | 11:44:54 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:44:54 INFO - PROCESS | 9720 | RSS: Main: 166440960 11:44:54 INFO - PROCESS | 9720 | 11:44:56 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:44:57 INFO - PROCESS | 9720 | RSS: Main: 166780928 11:44:57 INFO - PROCESS | 9720 | 11:45:00 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:45:00 INFO - PROCESS | 9720 | RSS: Main: 167563264 11:45:00 INFO - PROCESS | 9720 | 11:45:03 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:45:04 INFO - PROCESS | 9720 | RSS: Main: 167518208 11:45:04 INFO - PROCESS | 9720 | 11:45:05 INFO - PROCESS | 9720 | Cycle 1(1): loaded http://localhost:42374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42374/tests/kraken/driver.html?testName=ai-astar) 11:45:06 INFO - PROCESS | 9720 | RSS: Main: 168603648 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | __start_tp_report 11:45:06 INFO - PROCESS | 9720 | _x_x_mozilla_page_load 11:45:06 INFO - PROCESS | 9720 | _x_x_mozilla_page_load_details 11:45:06 INFO - PROCESS | 9720 | |i|pagename|runs| 11:45:06 INFO - PROCESS | 9720 | |0;ai-astar;101;111;106;111;113;111;111;112;111;111 11:45:06 INFO - PROCESS | 9720 | |1;audio-beat-detection;153;123;143;172;125;151;153;119;177;126 11:45:06 INFO - PROCESS | 9720 | |2;audio-dft;164;164;163;162;163;165;172;163;161;164 11:45:06 INFO - PROCESS | 9720 | |3;audio-fft;74;76;76;182;77;74;76;75;166;76 11:45:06 INFO - PROCESS | 9720 | |4;audio-oscillator;85;83;162;88;84;84;141;83;83;153 11:45:06 INFO - PROCESS | 9720 | |5;imaging-gaussian-blur;118;118;117;118;120;118;117;118;117;117 11:45:06 INFO - PROCESS | 9720 | |6;imaging-darkroom;149;148;147;148;147;149;147;148;147;147 11:45:06 INFO - PROCESS | 9720 | |7;imaging-desaturate;96;96;95;95;95;100;95;96;96;97 11:45:06 INFO - PROCESS | 9720 | |8;json-parse-financial;53;52;55;51;52;59;56;51;51;52 11:45:06 INFO - PROCESS | 9720 | |9;json-stringify-tinderbox;65;65;64;64;65;64;63;63;63;64 11:45:06 INFO - PROCESS | 9720 | |10;stanford-crypto-aes;78;76;78;77;77;77;85;79;80;76 11:45:06 INFO - PROCESS | 9720 | |11;stanford-crypto-ccm;141;134;119;120;128;122;128;136;163;129 11:45:06 INFO - PROCESS | 9720 | |12;stanford-crypto-pbkdf2;155;156;169;160;156;155;157;156;157;158 11:45:06 INFO - PROCESS | 9720 | |13;stanford-crypto-sha256-iterative;65;65;65;66;63;63;63;63;63;65 11:45:06 INFO - PROCESS | 9720 | __end_tp_report 11:45:06 INFO - PROCESS | 9720 | __start_cc_report 11:45:06 INFO - PROCESS | 9720 | _x_x_mozilla_cycle_collect,915 11:45:06 INFO - PROCESS | 9720 | __end_cc_report 11:45:06 INFO - PROCESS | 9720 | __startTimestamp1469558706057__endTimestamp 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | ------- Summary: start ------- 11:45:06 INFO - PROCESS | 9720 | Number of tests: 14 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:3.58 (3.2%) stddev-sans-first:1.92 11:45:06 INFO - PROCESS | 9720 | Values: 101.0 111.0 106.0 111.0 113.0 111.0 111.0 112.0 111.0 111.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:152.00 stddev:20.64 (13.6%) stddev-sans-first:21.65 11:45:06 INFO - PROCESS | 9720 | Values: 153.0 123.0 143.0 172.0 125.0 151.0 153.0 119.0 177.0 126.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:3.00 (1.8%) stddev-sans-first:3.18 11:45:06 INFO - PROCESS | 9720 | Values: 164.0 164.0 163.0 162.0 163.0 165.0 172.0 163.0 161.0 164.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#3] audio-fft Cycles:10 Average:95.20 Median:76.00 stddev:41.71 (54.9%) stddev-sans-first:43.53 11:45:06 INFO - PROCESS | 9720 | Values: 74.0 76.0 76.0 182.0 77.0 74.0 76.0 75.0 166.0 76.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:83.50 stddev:33.12 (39.7%) stddev-sans-first:34.36 11:45:06 INFO - PROCESS | 9720 | Values: 85.0 83.0 162.0 88.0 84.0 84.0 141.0 83.0 83.0 153.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 11:45:06 INFO - PROCESS | 9720 | Values: 118.0 118.0 117.0 118.0 120.0 118.0 117.0 118.0 117.0 117.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 11:45:06 INFO - PROCESS | 9720 | Values: 149.0 148.0 147.0 148.0 147.0 149.0 147.0 148.0 147.0 147.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:1.52 (1.6%) stddev-sans-first:1.62 11:45:06 INFO - PROCESS | 9720 | Values: 96.0 96.0 95.0 95.0 95.0 100.0 95.0 96.0 96.0 97.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:52.50 stddev:2.66 (5.1%) stddev-sans-first:2.82 11:45:06 INFO - PROCESS | 9720 | Values: 53.0 52.0 55.0 51.0 52.0 59.0 56.0 51.0 51.0 52.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 11:45:06 INFO - PROCESS | 9720 | Values: 65.0 65.0 64.0 64.0 65.0 64.0 63.0 63.0 63.0 64.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:2.67 (3.4%) stddev-sans-first:2.83 11:45:06 INFO - PROCESS | 9720 | Values: 78.0 76.0 78.0 77.0 77.0 77.0 85.0 79.0 80.0 76.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:131.50 stddev:12.98 (9.9%) stddev-sans-first:13.35 11:45:06 INFO - PROCESS | 9720 | Values: 141.0 134.0 119.0 120.0 128.0 122.0 128.0 136.0 163.0 129.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:157.00 stddev:4.18 (2.7%) stddev-sans-first:4.29 11:45:06 INFO - PROCESS | 9720 | Values: 155.0 156.0 169.0 160.0 156.0 155.0 157.0 156.0 157.0 158.0 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - PROCESS | 9720 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.22 11:45:06 INFO - PROCESS | 9720 | Values: 65.0 65.0 65.0 66.0 63.0 63.0 63.0 63.0 63.0 65.0 11:45:06 INFO - PROCESS | 9720 | -------- Summary: end -------- 11:45:06 INFO - PROCESS | 9720 | 11:45:06 INFO - TEST-INFO | 9720: exit 0 11:45:07 INFO - TEST-OK | kraken | took 61087ms 11:45:07 INFO - SUITE-END | took 1171s 11:45:07 INFO - Completed test suite (00:19:31) 11:45:07 INFO - javascript benchmark 11:45:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7319.896500516453, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.1939495731112, 3127.999040307103, 3143.104215298639, 3153.98423007885, 3161.1464904475793, 5218.1650935850275, 5396.676199428032, 5426.158945363465, 5431.945680543194, 5447.645903016301, 5277.656952298098, 5340.866478338044, 5428.728563571825, 5461.15352120421, 5519.0, 4901.215805471125, 4937.284093806405, 5025.195968645017, 5068.949310506903, 5079.79680812767, 4967.7019378837385, 5028.094942910275, 5119.308893299412, 5119.385673719159, 5129.897402051956, 4800.927986080212, 4999.175136102539, 5006.123928312531, 5059.772310246048, 5064.493550644943, 4787.16224660683, 4868.172410690156, 4888.364512613373, 4897.559219670211, 4927.531884470969, 4849.151398505247, 4851.27230915362, 4853.902921941577, 4858.076965376567, 4899.632527560448, 5311.282976798121, 5336.30628018359, 5347.25138480613, 5348.277982472356, 5424.321959755031, 5836.212111364954, 5895.852603684899, 5907.7046147692445, 5925.022371308729, 5952.434518720715, 5945.256842894638, 5959.642421454727, 5964.0, 5973.313068997034, 5982.551308651869, 5310.044192045391, 5348.866278343073, 5527.917081243784, 5532.529734972532, 5537.750801213955, 5865.765369385177, 5939.90111829313, 5949.702514874239, 5982.760689572456, 5985.581009329305, 5681.659100453986, 5689.971550142222, 5692.402297758758, 5692.601517893708, 5701.344345400249, 5556.972215138899, 5759.049756790083, 5771.769129234867, 5775.306963164448, 5796.797112101056, 5711.600187986801, 5822.359540450547, 5856.677882716406, 5932.673702946293, 5939.10913362999, 5664.6034777566, 5672.064109421982, 5715.714214289269, 5742.052561327418, 5797.797077102281, 4374.956250437519, 4382.167388196232, 4384.320430333303, 4392.2752745797225, 4420.557944205554, 3856.3444214483607, 3882.068303607114, 3895.5130608673917, 3900.4929359183125, 3929.4891664084057, 5475.4524547545725, 5493.478119578634, 5525.585581081435, 5532.087205611109, 5547.972260138674, 5780.739866706008, 5786.594938354275, 5788.913166302509, 5792.102224155263, 5798.797042103506, 5346.759395827197, 5504.752286147133, 5511.338639363222, 5614.242077319591, 5675.148727690879, 5128.615353848401, 5129.333186685707, 5141.948580514222, 5161.458046905096, 5175.741212939338, 6128.63228206309, 6158.322584515699, 6161.4146656067605, 6163.260408750979, 6180.47465965398, 5692.288463942007, 5733.8566535836935, 5745.109508026263, 5961.552883533753, 5991.0, 6022.337542870368, 6028.125921741235, 6034.336223015377, 6057.0, 6076.513878889767, 5679.8864022720145, 5684.516816070681, 5729.0, 5737.168110624019, 5737.282839645044, 5689.77240910359, 5718.685472299064, 5732.7706891723865, 5810.622309549865, 5871.588988770745, 5620.185073164286, 5641.323041235078, 5655.321361436654, 5664.5751568631695, 5699.0311647020935, 5579.693116878449, 5583.302087239095, 5583.832485025456, 5624.74688639004, 5625.409332020079, 5494.835154945358, 5502.752376143163, 5583.4416558345465, 5597.384287728428, 5613.887722245614, 5628.521575665951, 5662.660240385425, 5692.345380281155, 5693.0, 5729.570282228933, 5538.9169162461785, 5570.972145139411, 5571.498565129158, 5580.190872323572, 5588.552915766811], "value": 5356.310916006542, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2499101186432, 594.6313285762827, 598.9880202395952, 614.9046897730852, 620.1658768955753, 42491.57508424915, 43983.900402489955, 44019.779901100446, 44052.339214911764, 47798.52201477984, 45312.0, 45509.544904551025, 45988.31017534732, 46090.30864537034, 46279.0, 9065.093490650932, 9158.450492970444, 9212.447253164799, 9222.907770922271, 9226.492542910137, 9086.22767064797, 9164.679236226733, 9165.908340916605, 9172.174504294611, 9175.678851240207, 9088.272938164931, 9186.989431162567, 9214.539273036355, 9220.07799220076, 9278.35051546392, 9093.590788414504, 9142.131497507726, 9189.632414703437, 9195.908040919572, 9202.539873006357, 9064.909350906473, 9102.226310763594, 9114.179723824887, 9115.407498512575, 9145.862812057825, 43481.347779783326, 43731.562684373064, 43834.342484862755, 43852.56147438517, 43988.0, 3699.315626609086, 3740.1584643455276, 3804.714646401531, 3857.8263978121054, 4038.2932986727205, 32317.192070198198, 32781.18047048819, 32802.17994550155, 33088.0, 33114.834425827954, 4433.512313645497, 4489.331095660509, 4542.909141817178, 4596.88507787308, 4695.178343789823, 349744.0, 350503.0, 351236.0, 351681.0, 352024.99999999744, 53576.0, 53991.0, 53997.73001134969, 54065.0, 54221.0, 63276.0, 63614.68192659007, 63820.0, 63826.36173638297, 64101.67949160224, 15525.223738813014, 15659.608509787118, 15664.451744188898, 15675.294611742498, 15746.527604171893, 12427.689307767198, 12443.0, 12469.251844889335, 12488.313142777053, 12515.436805343781, 17430.56423589395, 17929.820701792814, 18213.45359639213, 18288.81711182898, 18293.268269269345, 6704.266260829766, 6759.425448836904, 6903.275156108536, 7008.894866577006, 7128.893066604005, 1005.4269066631949, 1041.8124737547162, 1080.400377790318, 1131.8132508136066, 1132.6998345438465, 31666.05001849948, 31729.84135079356, 32223.194420139684, 32532.674673253438, 32765.672343276263, 1108.3128460354633, 1156.2195518025467, 1182.3024415594841, 1197.6407077876602, 1204.4399354300292, 17133.485995420157, 17178.656426871643, 17228.224729887184, 17303.0, 17354.392596259324, 599927.0, 604531.0, 605050.0, 605402.0, 609885.0, 59372.406275936686, 59615.40384596099, 59786.0, 59803.70098149481, 60158.398416016156, 1342.5099838558856, 1358.65354334646, 1359.5377571625497, 1384.2386687321932, 1408.5703860322494, 31181.0, 33352.0, 34457.655423445445, 34601.48097778485, 34700.30599388049, 50550.0, 51214.48785512097, 51231.2315315278, 51362.48637513727, 51477.7426112867, 424468.0, 429265.0, 429641.0, 431553.0, 433476.0, 28300.71699282981, 28502.85748571244, 28622.713772862004, 28640.28399289951, 28693.56959645565, 48155.75922120367, 49254.50745492499, 49807.0, 50879.0, 50967.74516127395, 41835.581644184385, 42949.78525107355, 43375.34936976011, 44346.334804977305, 44525.33212001887, 42004.36993445162, 42092.579074208865, 42095.0, 43091.56908431001, 43125.13749725051, 41150.0, 41823.7908810454, 42024.0, 42110.57889421067, 42260.78869605633, 35635.82182089073, 37348.81325593355, 37649.811750942165, 37881.54818341136, 38141.0, 27982.3004424891, 28549.857250713612, 28661.0, 28742.856285719274, 28947.276318092216], "value": 20524.928881706765, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.7690066807576, 385.1065527975097, 387.53882879373555, 390.0424457955719, 406.97151199416055, 17847.375341863037, 20684.896575517138, 20889.895550522226, 20906.16375344988, 20909.477263068413, 18358.816411835876, 18619.0, 18681.626367472643, 18753.0, 19694.606107877833, 5363.839084827452, 5600.215969764236, 5766.0486019806685, 5785.82642520725, 6371.362863713638, 6470.385313395243, 6471.676416179196, 6495.512836537255, 6496.740130394778, 6505.707243174045, 6264.906026409608, 6272.905906411407, 6321.399467050623, 6328.528597212626, 6356.2054743157105, 6143.9385606143815, 6286.968565157191, 6299.086632438294, 6336.714847831859, 6360.52296077796, 5459.317585301837, 5572.5263352615075, 5619.550435965154, 5637.8590535236535, 5641.633293835928, 16759.0, 19959.501012474804, 20719.0, 20762.5847483051, 23254.41863953398, 2272.5682120397073, 2604.492124035814, 2642.616820561008, 2721.6189733437336, 2948.7346138847606, 9732.805343893082, 12131.575394861226, 12143.878561214451, 13558.322083895864, 14810.629734256621, 1899.5156235159945, 1910.843939416647, 1939.3890924358923, 2013.9395818125479, 2042.7446569178853, 75652.0, 83522.58238708768, 83584.58207708923, 84841.0, 87103.56448217719, 10479.895201048044, 10613.097886679565, 10788.572571171424, 10909.18181136418, 11000.504977276041, 14733.778993315109, 14817.925910370379, 14887.627809304637, 14895.255237238094, 14912.254387280593, 7334.303241192185, 7763.534187948741, 7824.37405007598, 7847.568383738949, 8050.879236811453, 7145.749898753519, 7287.6356182190675, 7417.443691723142, 7467.813304667318, 7628.389728821681, 4377.759223242752, 4487.102579484116, 4565.333653216619, 4573.794179261941, 4574.954250457453, 1912.598354345603, 1975.1605567633699, 2003.6994450832492, 2004.3986803958753, 2032.8780273183656, 915.8259930613162, 979.9461029643295, 1025.5231317437278, 1036.1917704190744, 1073.2058276875057, 19574.412767616996, 19972.10125544354, 20090.89954550247, 20347.491312717302, 20504.69242961357, 966.7583104223944, 976.8827740671023, 982.8771403574553, 1026.373911913732, 1076.4187338836941, 9357.672481463253, 10946.288491248044, 11014.889851101547, 11043.668689939315, 11244.437778111062, 487489.0, 487877.0, 501262.0, 502635.0, 510348.0, 95441.0, 95752.52123739476, 95754.52122739481, 95778.52110739541, 95846.52076739572, 987.8666380038746, 1024.1294899335505, 1024.7028361775, 1025.261811495722, 1043.5147656339839, 17241.39655112065, 17798.555036124202, 18023.0, 18328.81671183271, 18342.1746021432, 39617.0, 40367.0, 40785.79607101945, 40887.1822563553, 42127.0, 53594.0, 55623.0, 56418.0, 57964.0, 58092.0, 7964.123946365779, 8059.637316320897, 8148.266656000988, 8234.217749313806, 8350.540720260444, 12197.268163910194, 12819.423125959167, 13178.60464186076, 13256.80114798298, 13393.263370514325, 10785.62250321235, 11194.817010683164, 11266.605668801552, 11465.31208127515, 11702.473388697359, 11324.773504530029, 11446.828297575377, 11636.010939069934, 11701.356425396685, 11826.76346473083, 11059.61291354831, 11270.661880143609, 11586.304821710724, 11796.528138874692, 11817.231879927776, 7684.193159718373, 7876.606169691607, 7887.053553573608, 7910.169432209766, 8035.236652518001, 10614.203934704774, 11106.944465277622, 11110.16673749456, 11275.210735248455, 11368.715782105513], "value": 9275.828961481016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.4117194294226, 1519.8480151984802, 1667.897710258918, 1782.6523827853566, 1795.8563314934806, 7753.92246077539, 8677.524820780467, 8692.739217823468, 9329.20701740352, 9330.160285574288, 8794.340424468159, 9033.683821066264, 9126.589303481343, 9128.634854605807, 9130.132637399436, 2302.597045517032, 2316.6177580699164, 2319.153508969231, 2320.8491448055906, 2336.0, 2257.2889539794996, 2266.977330226693, 2339.2280547419396, 2340.157543284416, 2363.196513185517, 2218.5895609312247, 2246.7416247131628, 2248.6064938635673, 2251.2570851618925, 2262.1856131792542, 2268.3988742983124, 2270.9894097126785, 2274.55646149001, 2286.0, 2291.541691661674, 2275.5335156293086, 2289.748127705951, 2306.4695120122296, 2307.2962746362355, 2308.215206829686, 9068.68259610917, 9141.451512909247, 9164.725058248263, 9166.908330916673, 9203.907960920438, 5151.459096794858, 5218.582513398919, 5260.763265653017, 5283.471652834686, 5289.44460831611, 3159.557661927332, 3250.2686895448574, 3309.0900002499266, 3360.630330663625, 3371.544841446422, 1110.4281295133012, 1130.4969288666546, 1137.7496950670923, 1140.7718456308771, 1165.626999360205, 12692.11155219135, 12724.109312348139, 12772.61682149537, 12772.872271277354, 12826.679333016657, 4272.124214536013, 4347.282698354736, 4511.323301504801, 4512.4133862597655, 4593.517680643485, 10574.841377379345, 10640.574377024986, 10661.786764264827, 10703.197260205516, 10773.622923197807, 5245.213218017328, 5269.499397557226, 5273.973630131824, 5289.629725919226, 5302.814401495929, 4152.211079894811, 4672.18236808564, 4766.308885211625, 4803.447603525569, 4812.109759694467, 3671.8714844980295, 3786.091338078841, 3805.124821291063, 3866.5360156780803, 3869.380899056194, 1228.8832560906699, 1239.6590937492263, 1243.1981372015, 1246.5512415530402, 1247.8065899785547, 984.1634610581092, 987.3088837813559, 995.7112437393075, 1000.5697550053546, 1032.99483502582, 6250.218722659668, 6344.555881088372, 6370.4903607712195, 6521.380468855451, 6776.627285499345, 1000.0199804191933, 1012.8531362952331, 1018.3584341864726, 1041.1670663469195, 1049.6378749331468, 4254.574542545783, 4339.978300108479, 4464.575865292792, 4484.753338566345, 4616.446026476844, 11074.0, 11559.248648837798, 11601.18791684574, 11614.535418583331, 11680.70798230051, 11393.373364465035, 11460.426978651201, 11488.88511114895, 11496.0, 11549.0, 7650.8087297818, 7933.563653999086, 7985.920140798634, 8217.876731849026, 8320.583970801435, 6304.905426418516, 6332.746690132343, 6447.774327898689, 6473.255575608865, 6515.381038801306, 10497.737556561147, 10569.682909512727, 10671.35971841692, 10872.184586155912, 10882.12942964545, 10667.146628269564, 10770.623028193975, 10789.0, 10814.405207713336, 10840.403777792297, 7748.380129589506, 8028.157043510348, 8079.555624440713, 8095.311898488694, 8176.509409435453, 8132.715354962548, 8191.918080819116, 8195.795105122419, 8248.876266856121, 8272.751817445485, 7558.639444899971, 7586.089669239726, 7813.257740514642, 7814.257645523666, 7838.216178382117, 7502.887456688263, 7683.078030636136, 7697.8845317319165, 7726.575038372944, 7754.534727916343, 6719.227288861844, 6804.149481314836, 6816.863662726817, 6822.044913712183, 6831.555948863308, 5372.4627537247525, 5410.594205434687, 5452.345718513804, 5506.394296627448, 5611.074172761449, 5418.810341638024, 5424.484673955968, 5446.972765136149, 5497.642653227527, 5643.633163844501], "value": 4725.1072504487165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.83313843662836, 323.26296045017364, 326.0950861359726, 329.1540740297435, 329.344604237567, 5429.239906413095, 5482.13607373493, 5605.7197140143035, 5642.7178641067985, 5651.2370829937945, 5518.641288316257, 5519.337679478458, 5533.778648854051, 5537.640053396526, 5551.861203469915, 4330.913381732364, 4332.805023773922, 4332.985161899708, 4336.284513055342, 4337.197618440583, 4101.876943691694, 4321.762303073346, 4341.934870976938, 4397.494288156871, 4398.824047038114, 4249.19265339586, 4279.379489973952, 4281.464816897887, 4303.35449682547, 4313.088665151026, 4286.721363111404, 4293.441852559147, 4309.418228539138, 4313.633341165973, 4321.36396797953, 3649.120702838014, 3749.3626083565587, 3760.172761992357, 3762.322781899257, 3784.8864534064023, 5394.379646340682, 5398.24424580559, 5420.512153906128, 5448.45515448456, 5471.37079235889, 4934.432540257881, 4935.876603084916, 4954.405471343426, 4960.677555958851, 4964.9573589546235, 5625.128105143709, 5745.48290653839, 5793.478586927196, 5798.9130163047585, 5802.823941022117, 4095.508538975342, 4114.444549985744, 4126.3397856343145, 4153.0, 4161.313383291753, 5868.589198756132, 6045.3047899491685, 6048.7882924097885, 6078.0, 6110.6944652767625, 4204.831806727697, 4224.260754367973, 4224.809883555247, 4225.471816022997, 4227.577242275809, 4276.764777937244, 4276.8289268429535, 4285.100128972888, 4288.957110428918, 4299.484061912591, 4920.6555541111775, 4935.777889994959, 4940.456549779521, 4943.629227807929, 4950.9009819803405, 5677.801276955287, 5698.857528561819, 5856.912146317809, 5882.735276912549, 5900.970495147582, 1453.4694836384856, 1476.5422718957157, 1481.9851801481848, 1484.6882154747625, 1485.6805786755683, 905.42053086025, 930.1628534319032, 944.7260294514651, 952.2049089010616, 953.620716312804, 5030.84907452777, 5060.2662613920775, 5077.949220507821, 5084.491550844886, 5085.389753229562, 5222.634415590948, 5244.059697911797, 5274.947250527523, 5308.389535203424, 5340.305760251143, 4719.183114500942, 5025.7989680412375, 5033.005361809791, 5048.469910659402, 5057.544820966131, 2861.642294713161, 2866.0398766413487, 2884.3510210202535, 2884.9134525963836, 2905.5205891027745, 5453.700046497402, 5484.0, 5484.533814625722, 5554.527865131509, 5563.443655634485, 5114.3095682083185, 5427.185922111715, 5433.864153396196, 5438.347398312228, 5458.372287187024, 5488.204210389471, 5488.231647569343, 5499.642523236057, 5586.776528938797, 5610.971945140248, 5114.8977020460125, 5347.342323879454, 5422.48486393792, 5450.781968721365, 5472.835814925558, 5200.4279529251, 5204.245384419314, 5273.020743280034, 5287.867803304948, 5307.150855863043, 5489.7648029193115, 5562.97085039269, 5581.627808181425, 5610.438956104357, 5615.354234262949, 3713.1274150574054, 3870.832333893029, 3905.3556163232747, 3907.6092390761833, 3912.237113762789, 5311.707856067954, 5345.705986170642, 5356.982173387044, 5668.26312579362, 5803.883922321445, 5789.681567513659, 5845.304861590092, 5852.882942341214, 5863.1791549184, 5900.793472228452, 5881.941180588139, 5888.676122813115, 5888.911666325094, 5892.351841297368, 5910.379410161871, 5614.354349249726, 5703.344115426779, 5720.628159169641, 5734.05388110957, 5746.13807928814, 5883.882322353444, 5900.409959004237, 5916.378780228014, 5935.762569497172, 5952.196453478899, 5594.328680558359, 5700.258966334516, 5764.616492041963, 5773.826785196451, 5810.622309549865], "value": 4270.499245964706, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.08691244838, 712.6365553567682, 762.9504082234654, 794.5113755040652, 875.5315905990296, 16474.670506589875, 17037.233324500397, 17042.318307267695, 17045.31818727251, 17084.31662733491, 14957.177355245458, 15863.0, 16555.420560280392, 16563.25465354059, 16809.075500847448, 791.0981481111537, 791.7862177212149, 792.71858490236, 796.6295672512281, 800.5516910530122, 799.7640695994667, 805.556943680976, 807.3823525003377, 807.5356669914793, 820.9343252539812, 872.7992561710811, 874.1433395272637, 875.1729615513342, 877.3639111644071, 878.6704985630389, 865.7143142762873, 870.6212797433137, 872.040755169315, 873.3929918706501, 873.7422460374205, 640.5612155673379, 642.4025656139788, 642.7139922734385, 642.810370940569, 644.2365796531079, 14200.715985680228, 14254.3585538651, 14256.572302830931, 14260.215688137148, 14261.857381426156, 14496.855031449655, 14526.564203073925, 14672.19302938338, 14676.19280939548, 14724.926375368163, 12450.688732781662, 12605.558805441766, 12609.28542431952, 12786.104972651918, 12801.29592872401, 3919.8432062717457, 3960.32674445345, 3985.362342025291, 4063.7358571692744, 4150.564190759957, 62127.37872621261, 63764.68117659382, 64330.0, 76581.0, 77624.61187694082, 11812.468438920268, 11822.0, 11917.642470725892, 11945.670594234214, 12162.756744865053, 19271.22915083409, 19403.80596194048, 19459.12433940476, 19533.413997580094, 19637.50906227356, 8465.534395608178, 8491.108433614505, 8654.023567428361, 8719.171678690514, 8809.559522023874, 4817.686850354716, 4847.587955023862, 4869.902601947942, 4898.608111351084, 4941.357623508921, 3290.4570745826672, 3324.1024923270584, 3370.915727106842, 3377.341418423433, 3403.370376480359, 572.705056895699, 576.2854060964457, 581.9476247137778, 600.0579090827447, 603.5835273661247, 681.141761380664, 691.8962155676588, 700.2192555300904, 703.8381172330393, 722.1370462297609, 9945.950270248602, 10147.34042287249, 10170.440625765506, 10231.846522302172, 10292.845607315896, 667.1926968368329, 680.6256558892688, 705.1538154214962, 730.8063363208754, 758.8140905478083, 4639.768011599406, 4763.54746299101, 4907.288443175791, 4913.385826771653, 4971.577415919687, 75987.0, 105015.47492262642, 116713.0, 121946.39026804987, 124533.0, 60554.697226514465, 81610.59194703988, 103474.0, 103876.48061759643, 105974.4701276504, 88466.55766721125, 89578.0, 90440.54779726059, 92916.53541732248, 93964.0, 9282.628694852401, 9346.719598412059, 9429.3399462037, 9449.43303401798, 9455.432674039579, 38089.238215236095, 43621.781891090344, 52659.57712748659, 53375.733121335696, 54477.45522544724, 75518.24481755112, 78332.0, 79308.0, 80853.59573202096, 82027.58986205031, 11621.709457263636, 11927.463264153308, 11963.461644225858, 12025.0, 12074.094442916992, 13554.796678049639, 13938.0, 14188.716225675635, 14224.217668027944, 14712.558623240891, 11945.761084778082, 12104.878951210376, 12406.937965310115, 12850.807237891626, 12927.224366538037, 11847.0, 12334.0, 12644.30456324911, 12751.171173873669, 12774.425150868048, 12550.937245313715, 12816.743665126458, 13249.337533123113, 13287.40206690682, 13449.0, 10350.378977261389, 10507.0, 10610.0, 10640.148788097089, 10881.020708136306, 9687.985345067877, 9918.355306905029, 10377.273590848568, 11090.467771418245, 11160.88839111631], "value": 7474.710489548208, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 106.0, 111.0, 113.0, 111.0, 111.0, 112.0, 111.0, 111.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 143.0, 172.0, 125.0, 151.0, 153.0, 119.0, 177.0, 126.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 162.0, 163.0, 165.0, 172.0, 163.0, 161.0, 164.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 182.0, 77.0, 74.0, 76.0, 75.0, 166.0, 76.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 162.0, 88.0, 84.0, 84.0, 141.0, 83.0, 83.0, 153.0], "value": 104.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 120.0, 118.0, 117.0, 118.0, 117.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 147.0, 148.0, 147.0, 149.0, 147.0, 148.0, 147.0, 147.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 100.0, 95.0, 96.0, 96.0, 97.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 55.0, 51.0, 52.0, 59.0, 56.0, 51.0, 51.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 65.0, 64.0, 63.0, 63.0, 63.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 78.0, 77.0, 77.0, 77.0, 85.0, 79.0, 80.0, 76.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 134.0, 119.0, 120.0, 128.0, 122.0, 128.0, 136.0, 163.0, 129.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 169.0, 160.0, 156.0, 155.0, 157.0, 156.0, 157.0, 158.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 63.0, 63.0, 63.0, 63.0, 63.0, 65.0], "value": 64.1, "unit": "score"}]}]} 11:45:07 INFO - Return code: 0 11:45:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:45:07 ERROR - # TBPL SUCCESS # 11:45:07 INFO - Running post-action listener: _resource_record_post_action 11:45:07 INFO - [mozharness: 2016-07-26 18:45:07.336581Z] Finished run-tests step (success) 11:45:07 INFO - Running post-run listener: _resource_record_post_run 11:45:07 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 567930880; Read time: 152; Write time: 513100 11:45:07 INFO - TinderboxPrint: CPU usage
13.4% 11:45:07 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 152 11:45:07 INFO - TinderboxPrint: I/O write bytes / time
567,930,880 / 513,100 11:45:07 INFO - TinderboxPrint: CPU idle
8,212.4 (86.6%) 11:45:07 INFO - TinderboxPrint: CPU user
1,217.2 (12.8%) 11:45:07 INFO - TinderboxPrint: Swap in / out
0 / 0 11:45:07 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 268419072; Read time: 0; Write time: 307540 11:45:07 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 299511808; Read time: 140; Write time: 205560 11:45:07 INFO - Running post-run listener: _upload_blobber_files 11:45:07 INFO - Blob upload gear active. 11:45:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:45:07 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 11:45:07 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'] 11:45:07 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 11:45:08 INFO - (blobuploader) - INFO - Open directory for files ... 11:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:45:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:09 INFO - (blobuploader) - INFO - Done attempting. 11:45:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:45:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:45:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:13 INFO - (blobuploader) - INFO - Done attempting. 11:45:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:45:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:45:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:14 INFO - (blobuploader) - INFO - Done attempting. 11:45:14 INFO - (blobuploader) - INFO - Iteration through files over. 11:45:14 INFO - Return code: 0 11:45:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:45:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:45:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a37bd1325d2d2e09a8544382cadf1f85f3aeb15ceec40050d526c6584c370f22382973984d9e2ade11984c9c1778ea503d733f3dba9ff7d43a198dd1ad9576e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29e3b9db6afe1d2107c5e1c29a1400b0bc5192efabb9f87d554c6ab87615d41b962badee5931e8986ccfaf78822b2c4dddb0c2c0ae0cb925c09ce348f24736d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25c13f31b3899dfcb27da0c3c648c580bd9b0ca06675f74eaa8e189ebdc18da782ac45f2a7f45622739f1e90ef72028c55800e6dc76a849b527a38c3846c51c4"} 11:45:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:45:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:45:14 INFO - Contents: 11:45:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a37bd1325d2d2e09a8544382cadf1f85f3aeb15ceec40050d526c6584c370f22382973984d9e2ade11984c9c1778ea503d733f3dba9ff7d43a198dd1ad9576e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29e3b9db6afe1d2107c5e1c29a1400b0bc5192efabb9f87d554c6ab87615d41b962badee5931e8986ccfaf78822b2c4dddb0c2c0ae0cb925c09ce348f24736d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25c13f31b3899dfcb27da0c3c648c580bd9b0ca06675f74eaa8e189ebdc18da782ac45f2a7f45622739f1e90ef72028c55800e6dc76a849b527a38c3846c51c4"} 11:45:14 INFO - Running post-run listener: copy_logs_to_upload_dir 11:45:14 INFO - Copying logs to upload dir... 11:45:14 INFO - mkdir: /builds/slave/test/build/upload/logs 11:45:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1224.136679 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-07-26 11:45:14.367201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:45:14.374964) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 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/a37bd1325d2d2e09a8544382cadf1f85f3aeb15ceec40050d526c6584c370f22382973984d9e2ade11984c9c1778ea503d733f3dba9ff7d43a198dd1ad9576e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29e3b9db6afe1d2107c5e1c29a1400b0bc5192efabb9f87d554c6ab87615d41b962badee5931e8986ccfaf78822b2c4dddb0c2c0ae0cb925c09ce348f24736d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25c13f31b3899dfcb27da0c3c648c580bd9b0ca06675f74eaa8e189ebdc18da782ac45f2a7f45622739f1e90ef72028c55800e6dc76a849b527a38c3846c51c4"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011200 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1469547029/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a37bd1325d2d2e09a8544382cadf1f85f3aeb15ceec40050d526c6584c370f22382973984d9e2ade11984c9c1778ea503d733f3dba9ff7d43a198dd1ad9576e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29e3b9db6afe1d2107c5e1c29a1400b0bc5192efabb9f87d554c6ab87615d41b962badee5931e8986ccfaf78822b2c4dddb0c2c0ae0cb925c09ce348f24736d1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25c13f31b3899dfcb27da0c3c648c580bd9b0ca06675f74eaa8e189ebdc18da782ac45f2a7f45622739f1e90ef72028c55800e6dc76a849b527a38c3846c51c4"}' ========= master_lag: 0.27 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:45:14.654090) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:45:14.654476) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469557488.579488-1782750698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:45:14.671450) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:45:14.671810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:45:14.672137) ========= ========= Total master_lag: 0.41 =========