builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1450354944.41 results: success (0) buildid: 20151217025833 builduid: ca393e2d7a384cceb0dececefb758959 revision: f143af51f6e35932927b8ccac2509facbbe7b539 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.405629) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.406085) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.406375) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006414 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.437749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.438030) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.463702) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.464003) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004611 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.517525) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.517836) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.518198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.518488) ========= 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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 04:22:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2015-12-17 04:22:27 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.695322) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.695652) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016928 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.746372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:24.746748) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f143af51f6e35932927b8ccac2509facbbe7b539 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f143af51f6e35932927b8ccac2509facbbe7b539 --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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 04:22:27,425 truncating revision to first 12 chars 2015-12-17 04:22:27,425 Setting DEBUG logging. 2015-12-17 04:22:27,425 attempt 1/10 2015-12-17 04:22:27,425 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f143af51f6e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 04:22:27,656 unpacking tar archive at: mozilla-central-f143af51f6e3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.503322 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:25.262597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:25.262911) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:25.272175) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:25.272484) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 04:22:25.272910) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-12-17 04:22:25.273205) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:22:28 INFO - MultiFileLogger online at 20151217 04:22:28 in /builds/slave/test 04:22:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:22:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:28 INFO - {'append_to_log': False, 04:22:28 INFO - 'base_work_dir': '/builds/slave/test', 04:22:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:22:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:28 INFO - 'branch': 'Firefox-Non-PGO', 04:22:28 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:28 INFO - 'config_files': ('talos/linux_config.py',), 04:22:28 INFO - 'default_actions': ('clobber', 04:22:28 INFO - 'read-buildbot-config', 04:22:28 INFO - 'download-and-extract', 04:22:28 INFO - 'populate-webroot', 04:22:28 INFO - 'create-virtualenv', 04:22:28 INFO - 'install', 04:22:28 INFO - 'run-tests'), 04:22:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:28 INFO - 'download_minidump_stackwalk': True, 04:22:28 INFO - 'download_symbols': 'ondemand', 04:22:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:28 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:28 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:28 INFO - 'installer_path': 'installer.exe', 04:22:28 INFO - 'log_level': 'info', 04:22:28 INFO - 'log_name': 'talos', 04:22:28 INFO - 'log_to_console': True, 04:22:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:22:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:22:28 INFO - 'opt_config_files': (), 04:22:28 INFO - 'pip_index': False, 04:22:28 INFO - 'sps_profile': False, 04:22:28 INFO - 'sps_profile_interval': 0, 04:22:28 INFO - 'suite': 'dromaeojs-e10s', 04:22:28 INFO - 'system_bits': '64', 04:22:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:28 INFO - 'title': 'talos-linux64-ix-066', 04:22:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:28 INFO - 'use_talos_json': True, 04:22:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:28 INFO - 'work_dir': 'build'} 04:22:28 INFO - ##### 04:22:28 INFO - ##### Running clobber step. 04:22:28 INFO - ##### 04:22:28 INFO - Running pre-action listener: _resource_record_pre_action 04:22:28 INFO - Running main action method: clobber 04:22:28 INFO - rmtree: /builds/slave/test/build 04:22:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:28 INFO - Running post-action listener: _resource_record_post_action 04:22:28 INFO - ##### 04:22:28 INFO - ##### Running read-buildbot-config step. 04:22:28 INFO - ##### 04:22:28 INFO - Running pre-action listener: _resource_record_pre_action 04:22:28 INFO - Running main action method: read_buildbot_config 04:22:28 INFO - Using buildbot properties: 04:22:28 INFO - { 04:22:28 INFO - "project": "", 04:22:28 INFO - "product": "firefox", 04:22:28 INFO - "script_repo_revision": "production", 04:22:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:22:28 INFO - "repository": "", 04:22:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:22:28 INFO - "buildid": "20151217025833", 04:22:28 INFO - "pgo_build": "False", 04:22:28 INFO - "basedir": "/builds/slave/test", 04:22:28 INFO - "buildnumber": 147, 04:22:28 INFO - "slavename": "talos-linux64-ix-066", 04:22:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:22:28 INFO - "platform": "ubuntu64_hw", 04:22:28 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:22:28 INFO - "branch": "mozilla-central", 04:22:28 INFO - "repo_path": "mozilla-central", 04:22:28 INFO - "revision": "f143af51f6e35932927b8ccac2509facbbe7b539", 04:22:28 INFO - "stage_platform": "linux64", 04:22:28 INFO - "builduid": "ca393e2d7a384cceb0dececefb758959", 04:22:28 INFO - "slavebuilddir": "test" 04:22:28 INFO - } 04:22:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 04:22:28 INFO - Running post-action listener: _resource_record_post_action 04:22:28 INFO - ##### 04:22:28 INFO - ##### Running download-and-extract step. 04:22:28 INFO - ##### 04:22:28 INFO - Running pre-action listener: _resource_record_pre_action 04:22:28 INFO - Running main action method: download_and_extract 04:22:28 INFO - mkdir: /builds/slave/test/build/tests 04:22:28 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')]} 04:22:28 INFO - https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:22:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/test_packages.json 04:22:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/test_packages.json 04:22:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:22:34 INFO - Downloaded 1302 bytes. 04:22:34 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:22:34 INFO - Using the following test package requirements: 04:22:34 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:22:34 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:22:34 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'jsshell-linux-x86_64.zip'], 04:22:34 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:22:34 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:22:34 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:22:34 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:22:34 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:22:34 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:22:34 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:22:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:22:34 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:34 INFO - https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:22:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:22:36 INFO - Downloaded 21869069 bytes. 04:22:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:22:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:22:37 INFO - Return code: 0 04:22:37 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:37 INFO - https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:22:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:22:39 INFO - Downloaded 21869069 bytes. 04:22:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:22:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:22:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:22:40 INFO - Return code: 0 04:22:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:40 INFO - https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:22:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:22:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:22:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:22:41 INFO - Downloaded 11132052 bytes. 04:22:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 04:22:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:22:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 04:22:41 INFO - Return code: 0 04:22:41 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')]} 04:22:41 INFO - https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:22:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:22:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:22:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:22:42 INFO - Downloaded 57770620 bytes. 04:22:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:22:42 INFO - mkdir: /builds/slave/test/properties 04:22:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:22:42 INFO - Writing to file /builds/slave/test/properties/build_url 04:22:42 INFO - Contents: 04:22:42 INFO - build_url:https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:22:42 INFO - Running post-action listener: _resource_record_post_action 04:22:42 INFO - Running post-action listener: set_extra_try_arguments 04:22:42 INFO - ##### 04:22:42 INFO - ##### Running populate-webroot step. 04:22:42 INFO - ##### 04:22:42 INFO - Running pre-action listener: _resource_record_pre_action 04:22:42 INFO - Running main action method: populate_webroot 04:22:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:22:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:22:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:22:42 INFO - '--tppagecycles', 04:22:42 INFO - '7'], 04:22:42 INFO - 'tests': ['tsvgm']}}, 04:22:42 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:22:42 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:22:42 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:22:42 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:22:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'tests': ['damp', 'tps']}, 04:22:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['damp', 'tps']}, 04:22:42 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:22:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:22:42 INFO - 'other': {'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'other_l64': {'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'other_nol64': {'tests': ['a11yr', 04:22:42 INFO - 'ts_paint', 04:22:42 INFO - 'tpaint', 04:22:42 INFO - 'sessionrestore', 04:22:42 INFO - 'sessionrestore_no_auto_restore']}, 04:22:42 INFO - 'svgr': {'tests': ['tsvgx', 04:22:42 INFO - 'tsvgr_opacity', 04:22:42 INFO - 'tart', 04:22:42 INFO - 'tscrollx', 04:22:42 INFO - 'cart']}, 04:22:42 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['tsvgx', 04:22:42 INFO - 'tsvgr_opacity', 04:22:42 INFO - 'tart', 04:22:42 INFO - 'tscrollx', 04:22:42 INFO - 'cart']}, 04:22:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'tests': ['tp5o']}, 04:22:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'talos_options': ['--e10s'], 04:22:42 INFO - 'tests': ['tp5o']}, 04:22:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'talos_options': ['--xperf_path', 04:22:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:42 INFO - 'tests': ['tp5n']}, 04:22:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:42 INFO - 'talos_options': ['--e10s', 04:22:42 INFO - '--xperf_path', 04:22:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:42 INFO - 'tests': ['tp5n']}}, 04:22:42 INFO - 'talos.zip': {'path': '', 04:22:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:22:42 INFO - Running post-action listener: _resource_record_post_action 04:22:42 INFO - ##### 04:22:42 INFO - ##### Running create-virtualenv step. 04:22:42 INFO - ##### 04:22:42 INFO - Running pre-action listener: _resource_record_pre_action 04:22:42 INFO - Running main action method: create_virtualenv 04:22:42 INFO - Creating virtualenv /builds/slave/test/build/venv 04:22:42 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 04:22:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:22:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:22:43 INFO - Using real prefix '/usr' 04:22:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:22:43 INFO - Installing distribute.............................................................................................................................................................................................done. 04:22:45 INFO - Installing pip.................done. 04:22:45 INFO - Return code: 0 04:22:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:22:45 INFO - Using env: {'DISPLAY': ':0', 04:22:45 INFO - 'HOME': '/home/cltbld', 04:22:45 INFO - 'LANG': 'en_US.UTF-8', 04:22:45 INFO - 'LANGUAGE': 'en_US:en', 04:22:45 INFO - 'LOGNAME': 'cltbld', 04:22:45 INFO - 'MAIL': '/var/mail/cltbld', 04:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:45 INFO - 'MOZ_NO_REMOTE': '1', 04:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:45 INFO - 'NO_EM_RESTART': '1', 04:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:45 INFO - 'PWD': '/builds/slave/test', 04:22:45 INFO - 'SHELL': '/bin/bash', 04:22:45 INFO - 'SHLVL': '1', 04:22:45 INFO - 'TERM': 'linux', 04:22:45 INFO - 'TMOUT': '86400', 04:22:45 INFO - 'USER': 'cltbld', 04:22:45 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:45 INFO - '_': '/tools/buildbot/bin/python'} 04:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:45 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 04:22:45 INFO - Cleaning up... 04:22:45 INFO - Return code: 0 04:22:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 04:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 04:22:45 INFO - Using env: {'DISPLAY': ':0', 04:22:45 INFO - 'HOME': '/home/cltbld', 04:22:45 INFO - 'LANG': 'en_US.UTF-8', 04:22:45 INFO - 'LANGUAGE': 'en_US:en', 04:22:45 INFO - 'LOGNAME': 'cltbld', 04:22:45 INFO - 'MAIL': '/var/mail/cltbld', 04:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:45 INFO - 'MOZ_NO_REMOTE': '1', 04:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:45 INFO - 'NO_EM_RESTART': '1', 04:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:45 INFO - 'PWD': '/builds/slave/test', 04:22:45 INFO - 'SHELL': '/bin/bash', 04:22:45 INFO - 'SHLVL': '1', 04:22:45 INFO - 'TERM': 'linux', 04:22:45 INFO - 'TMOUT': '86400', 04:22:45 INFO - 'USER': 'cltbld', 04:22:45 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:45 INFO - '_': '/tools/buildbot/bin/python'} 04:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:46 INFO - Downloading/unpacking psutil>=0.7.1 04:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:47 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:22:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:22:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:47 INFO - Installing collected packages: psutil 04:22:47 INFO - Running setup.py install for psutil 04:22:47 INFO - building 'psutil._psutil_linux' extension 04:22:47 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 04:22:48 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 04:22:48 INFO - building 'psutil._psutil_posix' extension 04:22:48 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 04:22:48 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 04:22:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:48 INFO - Successfully installed psutil 04:22:48 INFO - Cleaning up... 04:22:48 INFO - Return code: 0 04:22:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 04:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 04:22:48 INFO - Using env: {'DISPLAY': ':0', 04:22:48 INFO - 'HOME': '/home/cltbld', 04:22:48 INFO - 'LANG': 'en_US.UTF-8', 04:22:48 INFO - 'LANGUAGE': 'en_US:en', 04:22:48 INFO - 'LOGNAME': 'cltbld', 04:22:48 INFO - 'MAIL': '/var/mail/cltbld', 04:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:48 INFO - 'MOZ_NO_REMOTE': '1', 04:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:48 INFO - 'NO_EM_RESTART': '1', 04:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:48 INFO - 'PWD': '/builds/slave/test', 04:22:48 INFO - 'SHELL': '/bin/bash', 04:22:48 INFO - 'SHLVL': '1', 04:22:48 INFO - 'TERM': 'linux', 04:22:48 INFO - 'TMOUT': '86400', 04:22:48 INFO - 'USER': 'cltbld', 04:22:48 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:48 INFO - '_': '/tools/buildbot/bin/python'} 04:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:22:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:22:50 INFO - Installing collected packages: mozsystemmonitor 04:22:50 INFO - Running setup.py install for mozsystemmonitor 04:22:50 INFO - Successfully installed mozsystemmonitor 04:22:50 INFO - Cleaning up... 04:22:50 INFO - Return code: 0 04:22:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22: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')]} 04:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:22:50 INFO - Using env: {'DISPLAY': ':0', 04:22:50 INFO - 'HOME': '/home/cltbld', 04:22:50 INFO - 'LANG': 'en_US.UTF-8', 04:22:50 INFO - 'LANGUAGE': 'en_US:en', 04:22:50 INFO - 'LOGNAME': 'cltbld', 04:22:50 INFO - 'MAIL': '/var/mail/cltbld', 04:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:50 INFO - 'MOZ_NO_REMOTE': '1', 04:22:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:50 INFO - 'NO_EM_RESTART': '1', 04:22:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:50 INFO - 'PWD': '/builds/slave/test', 04:22:50 INFO - 'SHELL': '/bin/bash', 04:22:50 INFO - 'SHLVL': '1', 04:22:50 INFO - 'TERM': 'linux', 04:22:50 INFO - 'TMOUT': '86400', 04:22:50 INFO - 'USER': 'cltbld', 04:22:50 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:50 INFO - '_': '/tools/buildbot/bin/python'} 04:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:50 INFO - Downloading/unpacking blobuploader==1.2.4 04:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:22:52 INFO - Downloading blobuploader-1.2.4.tar.gz 04:22:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:22:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:22:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:52 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 04:22:53 INFO - Downloading docopt-0.6.1.tar.gz 04:22:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:22:53 INFO - Installing collected packages: blobuploader, requests, docopt 04:22:53 INFO - Running setup.py install for blobuploader 04:22:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:22:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:22:53 INFO - Running setup.py install for requests 04:22:53 INFO - Running setup.py install for docopt 04:22:53 INFO - Successfully installed blobuploader requests docopt 04:22:53 INFO - Cleaning up... 04:22:53 INFO - Return code: 0 04:22:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:22:53 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')]} 04:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:53 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')]} 04:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 04:22:53 INFO - Using env: {'DISPLAY': ':0', 04:22:53 INFO - 'HOME': '/home/cltbld', 04:22:53 INFO - 'LANG': 'en_US.UTF-8', 04:22:53 INFO - 'LANGUAGE': 'en_US:en', 04:22:53 INFO - 'LOGNAME': 'cltbld', 04:22:53 INFO - 'MAIL': '/var/mail/cltbld', 04:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:53 INFO - 'MOZ_NO_REMOTE': '1', 04:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:53 INFO - 'NO_EM_RESTART': '1', 04:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:53 INFO - 'PWD': '/builds/slave/test', 04:22:53 INFO - 'SHELL': '/bin/bash', 04:22:53 INFO - 'SHLVL': '1', 04:22:53 INFO - 'TERM': 'linux', 04:22:53 INFO - 'TMOUT': '86400', 04:22:53 INFO - 'USER': 'cltbld', 04:22:53 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:53 INFO - '_': '/tools/buildbot/bin/python'} 04:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:22:54 INFO - Running setup.py (path:/tmp/pip-XXDn1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:22:54 INFO - Running setup.py (path:/tmp/pip-LhfUsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:22:54 INFO - Running setup.py (path:/tmp/pip-uxxief-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:22:54 INFO - Running setup.py (path:/tmp/pip-BaOYWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:22:54 INFO - Running setup.py (path:/tmp/pip-eHvEBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:22:54 INFO - Running setup.py (path:/tmp/pip-hWC0An-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:22:54 INFO - Running setup.py (path:/tmp/pip-YgWQHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:22:54 INFO - Running setup.py (path:/tmp/pip-4p7wga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:22:54 INFO - Running setup.py (path:/tmp/pip-9T2oHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:22:54 INFO - Running setup.py (path:/tmp/pip-stQUWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:22:54 INFO - Running setup.py (path:/tmp/pip-TokjK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:22:54 INFO - Running setup.py (path:/tmp/pip-XRFVfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:22:54 INFO - Running setup.py (path:/tmp/pip-Kw5aD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:22:55 INFO - Running setup.py (path:/tmp/pip-prqOxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:55 INFO - Running setup.py (path:/tmp/pip-1ussZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:22:55 INFO - Running setup.py (path:/tmp/pip-rrLZLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:22:55 INFO - Running setup.py (path:/tmp/pip-FUPf9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:22:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:22:55 INFO - Running setup.py install for manifestparser 04:22:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:22:55 INFO - Running setup.py install for mozcrash 04:22:55 INFO - Running setup.py install for mozdebug 04:22:55 INFO - Running setup.py install for mozdevice 04:22:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:22:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:22:55 INFO - Running setup.py install for mozfile 04:22:55 INFO - Running setup.py install for mozhttpd 04:22:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:22:55 INFO - Running setup.py install for mozinfo 04:22:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:22:55 INFO - Running setup.py install for mozInstall 04:22:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Running setup.py install for mozleak 04:22:56 INFO - Running setup.py install for mozlog 04:22:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Running setup.py install for moznetwork 04:22:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Running setup.py install for mozprocess 04:22:56 INFO - Running setup.py install for mozprofile 04:22:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Running setup.py install for mozrunner 04:22:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:22:56 INFO - Running setup.py install for mozscreenshot 04:22:56 INFO - Running setup.py install for moztest 04:22:56 INFO - Running setup.py install for mozversion 04:22:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:22:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:22:57 INFO - Cleaning up... 04:22:57 INFO - Return code: 0 04:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:22:57 INFO - Using env: {'DISPLAY': ':0', 04:22:57 INFO - 'HOME': '/home/cltbld', 04:22:57 INFO - 'LANG': 'en_US.UTF-8', 04:22:57 INFO - 'LANGUAGE': 'en_US:en', 04:22:57 INFO - 'LOGNAME': 'cltbld', 04:22:57 INFO - 'MAIL': '/var/mail/cltbld', 04:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:57 INFO - 'MOZ_NO_REMOTE': '1', 04:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:57 INFO - 'NO_EM_RESTART': '1', 04:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:57 INFO - 'PWD': '/builds/slave/test', 04:22:57 INFO - 'SHELL': '/bin/bash', 04:22:57 INFO - 'SHLVL': '1', 04:22:57 INFO - 'TERM': 'linux', 04:22:57 INFO - 'TMOUT': '86400', 04:22:57 INFO - 'USER': 'cltbld', 04:22:57 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:57 INFO - '_': '/tools/buildbot/bin/python'} 04:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:22:57 INFO - Running setup.py (path:/tmp/pip-iehH9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:22:57 INFO - Running setup.py (path:/tmp/pip-nL46KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:22:57 INFO - Running setup.py (path:/tmp/pip-yfK53d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:22:57 INFO - Running setup.py (path:/tmp/pip-YqpwAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:22:57 INFO - Running setup.py (path:/tmp/pip-uF0sMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:22:57 INFO - Running setup.py (path:/tmp/pip-z35Gwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:22:57 INFO - Running setup.py (path:/tmp/pip-jrul_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:22:57 INFO - Running setup.py (path:/tmp/pip-kOPKbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:22:57 INFO - Running setup.py (path:/tmp/pip-qmVs3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:22:57 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)) 04:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:22:57 INFO - Running setup.py (path:/tmp/pip-R3jzR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:22:58 INFO - Running setup.py (path:/tmp/pip-CK0RiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:22:58 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)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:22:58 INFO - Running setup.py (path:/tmp/pip-7zLdBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:22:58 INFO - Running setup.py (path:/tmp/pip-yFn2Fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:22:58 INFO - Running setup.py (path:/tmp/pip-PwfFs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:58 INFO - Running setup.py (path:/tmp/pip-VcQrWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:58 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)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:22:58 INFO - Running setup.py (path:/tmp/pip-wy5nM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:22:58 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)) 04:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:22:58 INFO - Running setup.py (path:/tmp/pip-7d6qmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:22:58 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)) 04:22:58 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:22:58 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:22:58 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)) 04:22:58 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:22:58 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:22:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:22: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 04:22: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 04:22: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 04:22: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 04:22: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 04:22: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 04:23:00 INFO - Downloading blessings-1.5.1.tar.gz 04:23:00 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 04:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:00 INFO - Installing collected packages: blessings 04:23:00 INFO - Running setup.py install for blessings 04:23:00 INFO - Successfully installed blessings 04:23:00 INFO - Cleaning up... 04:23:00 INFO - Return code: 0 04:23:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:00 INFO - Reading from file tmpfile_stdout 04:23:00 INFO - Current package versions: 04:23:00 INFO - argparse == 1.2.1 04:23:00 INFO - blessings == 1.5.1 04:23:00 INFO - blobuploader == 1.2.4 04:23:00 INFO - docopt == 0.6.1 04:23:00 INFO - manifestparser == 1.1 04:23:00 INFO - mozInstall == 1.12 04:23:00 INFO - mozcrash == 0.16 04:23:00 INFO - mozdebug == 0.1 04:23:00 INFO - mozdevice == 0.47 04:23:00 INFO - mozfile == 1.2 04:23:00 INFO - mozhttpd == 0.7 04:23:00 INFO - mozinfo == 0.9 04:23:00 INFO - mozleak == 0.1 04:23:00 INFO - mozlog == 3.1 04:23:00 INFO - moznetwork == 0.27 04:23:00 INFO - mozprocess == 0.22 04:23:00 INFO - mozprofile == 0.27 04:23:00 INFO - mozrunner == 6.11 04:23:00 INFO - mozscreenshot == 0.1 04:23:00 INFO - mozsystemmonitor == 0.0 04:23:00 INFO - moztest == 0.7 04:23:00 INFO - mozversion == 1.4 04:23:00 INFO - psutil == 3.1.1 04:23:00 INFO - requests == 1.2.3 04:23:00 INFO - wsgiref == 0.1.2 04:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:00 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')]} 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:00 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')]} 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:23:00 INFO - Using env: {'DISPLAY': ':0', 04:23:00 INFO - 'HOME': '/home/cltbld', 04:23:00 INFO - 'LANG': 'en_US.UTF-8', 04:23:00 INFO - 'LANGUAGE': 'en_US:en', 04:23:00 INFO - 'LOGNAME': 'cltbld', 04:23:00 INFO - 'MAIL': '/var/mail/cltbld', 04:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:00 INFO - 'MOZ_NO_REMOTE': '1', 04:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:00 INFO - 'NO_EM_RESTART': '1', 04:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:00 INFO - 'PWD': '/builds/slave/test', 04:23:00 INFO - 'SHELL': '/bin/bash', 04:23:00 INFO - 'SHLVL': '1', 04:23:00 INFO - 'TERM': 'linux', 04:23:00 INFO - 'TMOUT': '86400', 04:23:00 INFO - 'USER': 'cltbld', 04:23:00 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:00 INFO - '_': '/tools/buildbot/bin/python'} 04:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:00 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 1)) 04:23:00 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 2)) 04:23:00 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 3)) 04:23:00 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 4)) 04:23:00 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 5)) 04:23:00 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 6)) 04:23:00 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 7)) 04:23:00 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 8)) 04:23:00 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:00 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 3)) 04:23:00 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:00 INFO - Cleaning up... 04:23:00 INFO - Return code: 0 04:23:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23:00 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')]} 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:00 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')]} 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:23:00 INFO - Using env: {'DISPLAY': ':0', 04:23:00 INFO - 'HOME': '/home/cltbld', 04:23:00 INFO - 'LANG': 'en_US.UTF-8', 04:23:00 INFO - 'LANGUAGE': 'en_US:en', 04:23:00 INFO - 'LOGNAME': 'cltbld', 04:23:00 INFO - 'MAIL': '/var/mail/cltbld', 04:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:00 INFO - 'MOZ_NO_REMOTE': '1', 04:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:00 INFO - 'NO_EM_RESTART': '1', 04:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:00 INFO - 'PWD': '/builds/slave/test', 04:23:00 INFO - 'SHELL': '/bin/bash', 04:23:00 INFO - 'SHLVL': '1', 04:23:00 INFO - 'TERM': 'linux', 04:23:00 INFO - 'TMOUT': '86400', 04:23:00 INFO - 'USER': 'cltbld', 04:23:00 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:00 INFO - '_': '/tools/buildbot/bin/python'} 04:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:01 INFO - Downloading/unpacking jsonschema 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 04:23:02 INFO - Downloading/unpacking functools32 (from jsonschema) 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:03 INFO - warning: no files found matching '*.txt' 04:23:03 INFO - no previously-included directories found matching 'build' 04:23:03 INFO - no previously-included directories found matching 'dist' 04:23:03 INFO - no previously-included directories found matching '.git*' 04:23:03 INFO - Installing collected packages: jsonschema, functools32 04:23:03 INFO - Running setup.py install for functools32 04:23:03 INFO - warning: no files found matching '*.txt' 04:23:03 INFO - no previously-included directories found matching 'build' 04:23:03 INFO - no previously-included directories found matching 'dist' 04:23:03 INFO - no previously-included directories found matching '.git*' 04:23:03 INFO - Successfully installed jsonschema functools32 04:23:03 INFO - Cleaning up... 04:23:03 INFO - Return code: 0 04:23:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:23: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')]} 04:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23: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')]} 04:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0700bc5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ca240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, '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': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', '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 04:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:23:03 INFO - Using env: {'DISPLAY': ':0', 04:23:03 INFO - 'HOME': '/home/cltbld', 04:23:03 INFO - 'LANG': 'en_US.UTF-8', 04:23:03 INFO - 'LANGUAGE': 'en_US:en', 04:23:03 INFO - 'LOGNAME': 'cltbld', 04:23:03 INFO - 'MAIL': '/var/mail/cltbld', 04:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:03 INFO - 'MOZ_NO_REMOTE': '1', 04:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:03 INFO - 'NO_EM_RESTART': '1', 04:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:03 INFO - 'PWD': '/builds/slave/test', 04:23:03 INFO - 'SHELL': '/bin/bash', 04:23:03 INFO - 'SHLVL': '1', 04:23:03 INFO - 'TERM': 'linux', 04:23:03 INFO - 'TMOUT': '86400', 04:23:03 INFO - 'USER': 'cltbld', 04:23:03 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:03 INFO - '_': '/tools/buildbot/bin/python'} 04:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:03 INFO - Downloading/unpacking flake8 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 04:23:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 04:23:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 04:23:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:23:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:05 INFO - no previously-included directories found matching 'docs/_build' 04:23:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:23:05 INFO - Running setup.py install for pep8 04:23:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:05 INFO - no previously-included directories found matching 'docs/_build' 04:23:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:23:05 INFO - Cleaning up... 04:23:06 INFO - Return code: 0 04:23:06 INFO - Running post-action listener: _resource_record_post_action 04:23:06 INFO - Running post-action listener: _start_resource_monitoring 04:23:06 INFO - Starting resource monitoring. 04:23:06 INFO - ##### 04:23:06 INFO - ##### Running install step. 04:23:06 INFO - ##### 04:23:06 INFO - Running pre-action listener: _resource_record_pre_action 04:23:06 INFO - Running main action method: install 04:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:06 INFO - Reading from file tmpfile_stdout 04:23:06 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:06 INFO - Reading from file tmpfile_stdout 04:23:06 INFO - Output received: 04:23:06 INFO - Usage: mozinstall [options] installer 04:23:06 INFO - Options: 04:23:06 INFO - -h, --help show this help message and exit 04:23:06 INFO - -d DEST, --destination=DEST 04:23:06 INFO - Directory to install application into. [default: 04:23:06 INFO - "/builds/slave/test"] 04:23:06 INFO - --app=APP Application being installed. [default: firefox] 04:23:06 INFO - mkdir: /builds/slave/test/build/application 04:23:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:23:20 INFO - Reading from file tmpfile_stdout 04:23:20 INFO - Output received: 04:23:20 INFO - /builds/slave/test/build/application/firefox/firefox 04:23:20 INFO - Running post-action listener: _resource_record_post_action 04:23:20 INFO - ##### 04:23:20 INFO - ##### Running run-tests step. 04:23:20 INFO - ##### 04:23:20 INFO - Running pre-action listener: _resource_record_pre_action 04:23:20 INFO - Running main action method: run_tests 04:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:23:20 INFO - Python 2.7.3 04:23:20 INFO - Return code: 0 04:23:20 INFO - grabbing minidump binary from tooltool 04:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:20 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29590b0>, '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 0x2959fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x295a440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:23:20 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 04:23:20 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 04:23:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:23:20 INFO - Return code: 0 04:23:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:23:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:23:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:23:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:23:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:23:21 INFO - Return code: 0 04:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] in /builds/slave/test/build 04:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost 04:23:21 INFO - Using env: {'DISPLAY': ':0', 04:23:21 INFO - 'HOME': '/home/cltbld', 04:23:21 INFO - 'LANG': 'en_US.UTF-8', 04:23:21 INFO - 'LANGUAGE': 'en_US:en', 04:23:21 INFO - 'LOGNAME': 'cltbld', 04:23:21 INFO - 'MAIL': '/var/mail/cltbld', 04:23:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:21 INFO - 'MOZ_NO_REMOTE': '1', 04:23:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:21 INFO - 'NO_EM_RESTART': '1', 04:23:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:21 INFO - 'PWD': '/builds/slave/test', 04:23:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:21 INFO - 'SHELL': '/bin/bash', 04:23:21 INFO - 'SHLVL': '1', 04:23:21 INFO - 'TERM': 'linux', 04:23:21 INFO - 'TMOUT': '86400', 04:23:21 INFO - 'USER': 'cltbld', 04:23:21 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083', 04:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:21 INFO - '_': '/tools/buildbot/bin/python'} 04:23:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost'] with output_timeout 3600 04:23:22 INFO - mozversion INFO | application_buildid: 20151217025833 04:23:22 INFO - mozversion INFO | application_changeset: f143af51f6e35932927b8ccac2509facbbe7b539 04:23:22 INFO - mozversion INFO | application_display_name: Nightly 04:23:22 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:23:22 INFO - mozversion INFO | application_name: Firefox 04:23:22 INFO - mozversion INFO | application_remotingname: firefox 04:23:22 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:23:22 INFO - mozversion INFO | application_vendor: Mozilla 04:23:22 INFO - mozversion INFO | application_version: 46.0a1 04:23:22 INFO - mozversion INFO | platform_buildid: 20151217025833 04:23:22 INFO - mozversion INFO | platform_changeset: f143af51f6e35932927b8ccac2509facbbe7b539 04:23:22 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:23:22 INFO - mozversion INFO | platform_version: 46.0a1 04:23:22 INFO - 2015-12-17 04:23:22,133 DEBUG : using testdate: 1450355002 04:23:22 INFO - 2015-12-17 04:23:22,133 DEBUG : actual date: 1450355002 04:23:22 INFO - 2015-12-17 04:23:22,141 INFO : starting webserver on 'localhost:37999' 04:23:22 INFO - 2015-12-17 04:23:22,142 INFO : Starting test suite talos-linux64-ix-066.e 04:23:22 INFO - 2015-12-17 04:23:22,142 INFO : Starting test dromaeo_css 04:23:22 INFO - 2015-12-17 04:23:22,142 DEBUG : operating with platform_type : linux_ 04:23:22 INFO - 2015-12-17 04:23:22,143 INFO : Initialising browser for dromaeo_css test... 04:23:22 INFO - 2015-12-17 04:23:22,157 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCrNFqR/profile http://localhost:37999/getInfo.html 04:23:27 INFO - 2015-12-17 04:23:27,171 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:23:27 INFO - 2015-12-17 04:23:27,172 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:23:27 INFO - 2015-12-17 04:23:27,172 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:23:27 INFO - 2015-12-17 04:23:27,172 DEBUG : BROWSER_OUTPUT: __metrics 04:23:27 INFO - 2015-12-17 04:23:27,293 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:23:28 INFO - 2015-12-17 04:23:28,224 INFO : Browser initialized. 04:23:28 INFO - 2015-12-17 04:23:28,225 INFO : Running cycle 1/1 for dromaeo_css test... 04:23:28 INFO - 2015-12-17 04:23:28,225 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCrNFqR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:23:40 INFO - 2015-12-17 04:23:40,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 143138816 04:23:40 INFO - 2015-12-17 04:23:40,259 DEBUG : BROWSER_OUTPUT: 04:24:29 INFO - 2015-12-17 04:24:29,884 DEBUG : BROWSER_OUTPUT: 1450355069883 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] 04:26:41 INFO - 2015-12-17 04:26:41,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37999/tests/dromaeo/cssquery-ext.html) 04:26:42 INFO - 2015-12-17 04:26:42,805 DEBUG : BROWSER_OUTPUT: RSS: Main: 145227776 04:26:42 INFO - 2015-12-17 04:26:42,805 DEBUG : BROWSER_OUTPUT: 04:29:44 INFO - 2015-12-17 04:29:44,800 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-ext.html (next: http://localhost:37999/tests/dromaeo/cssquery-jquery.html) 04:29:45 INFO - 2015-12-17 04:29:45,393 DEBUG : BROWSER_OUTPUT: RSS: Main: 144216064 04:29:45 INFO - 2015-12-17 04:29:45,393 DEBUG : BROWSER_OUTPUT: 04:32:47 INFO - 2015-12-17 04:32:47,212 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37999/tests/dromaeo/cssquery-mootools.html) 04:32:47 INFO - 2015-12-17 04:32:47,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 145502208 04:32:47 INFO - 2015-12-17 04:32:47,813 DEBUG : BROWSER_OUTPUT: 04:35:49 INFO - 2015-12-17 04:35:49,606 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37999/tests/dromaeo/cssquery-prototype.html) 04:35:50 INFO - 2015-12-17 04:35:50,191 DEBUG : BROWSER_OUTPUT: RSS: Main: 144035840 04:35:50 INFO - 2015-12-17 04:35:50,191 DEBUG : BROWSER_OUTPUT: 04:38:52 INFO - 2015-12-17 04:38:52,723 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37999/tests/dromaeo/cssquery-yui.html) 04:38:53 INFO - 2015-12-17 04:38:53,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 145276928 04:38:53 INFO - 2015-12-17 04:38:53,349 DEBUG : BROWSER_OUTPUT: 04:41:55 INFO - 2015-12-17 04:41:55,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/dromaeo/cssquery-yui.html (next: http://localhost:37999/tests/dromaeo/cssquery-dojo.html) 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 147558400 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:41:56 INFO - 2015-12-17 04:41:56,076 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:41:56 INFO - 2015-12-17 04:41:56,077 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2655.641488399066;2720;2748.491529067123;2770.7367800058987;2773.805833591648;4622.514635963226;4841.2012018016985;4852.830150944717;4867.488913664068;4888.120138375091;4759.7620118994;4761.642876784246;4772.928406073912;4889.682170658904;4942.431620363659;4327.199468098404;4399.604035636792;4430.335449682541;4455.1535208310315;4479.171353299634;4390.780460976938;4405.404866771624;4456.621187199092;4496.550344965493;4510.413646225986;4246.830126794925;4318.2227199104145;4361.476622805252;4450.621697155746;4500.977495112536;4419.734815911055;4423.447069116361;4427.46870375557;4442.155990361821;4455.242608756519;4424.535423780489;4428.291473364278;4428.3578881062385;4437.622802061828;4448.377227188196;4420.889477763049;4565.7260564365915;4592.7014744041535;4713.858584242478;4753.025629745894;5060.291559181717;5065.822696205615;5086.364204474441;5107.080725469414;5148.485151484859;5117;5129.307543481619;5130.538251557377;5136.512031357052;5151.072806894757;4565.315202719586;4703.365045718852;4713.293006049052;4732.100900828831;4762.833300834454;5255.290535777621;5280.445553216934;5280.815171469057;5299.31108955841;5300.787968481218;5048.368953880765;5056.317397151411;5068.265101560253;5080.364954380702;5092.8472145835685;4903.068417000758;5004.849854504298;5022.673526220784;5165.354330708661;5174.172132458789;5059.949400505948;5127.461616530285;5138.87152821175;5143.5627971622835;5159.58723302135;5099.158638824627;5108.719020453911;5111.05445492585;5111.8722031948755;5163.741812909339;4136.958630413657;4160.729552579072;4216.9578304217175;4248.4477017987465;4251.404803327537;3728.4780130781714;3732.701383889282;3734.178480734234;3740;3771.113788259757;4233;4445.688801783909;4446.599806017474;4451.5103338632725;4467.486239082482;5031.094403007493;5067.5185857343495;5123.8719032023755;5143;5165.690058596496;4430.689851710414;4465.040016396425;4465.642748580106;4497.460304763449;4530.02604440047;4391.538888416734;4477.843275485342;4571.725696458157;4573.382593349922;4576.794044268016;4792.185328494164;4832.9275060873415;4857.489963553776;4869.732164730832;4893.241547560134;5152.536271735562;5154.046501397253;5172.7413629319135;5179.92230116541;5182.844514664566;4615.607673347803;4765.046990601824;4804.711717296834;4902.558769710743;4950.529699678525;5114.232865070269;5143.4085080216255;5158.871028224325;5159.122949098587;5176.223566465061;5209.505097015777;5213.947860521346;5224.399194092728;5227.2420499028185;5273.894522109613;4746.762661866962;4858;4860.756962151808;4955.058538877602;4998.525140111684;4976.328195693535;4987.326710893983;5009;5024.547790698854;5039.395272567315;4824.8793780155775;4841.176999910094;4901.3383193268455;4905.754712264444;4919.1637421639125;4819.614430845454;4908.92636610444;4977.253411988231;4981.875453113701;4987.576056035277;4846.055019271208;4918.262260660929;4919.631027672867;4924.556789888928;4974.32846565709 04:41:56 INFO - 2015-12-17 04:41:56,077 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.4084211577842;570.3498012266015;570.5150621971322;573.1374281706034;578.9450002249785;34494.82752586237;35016.99999999997;35036.824815875916;35049.64950350496;35080.47379289313;38682.806585967104;39062.21875562487;39227.41158882619;39235.80382098086;39293.607063929354;7764.3012128908385;7903.209679032108;7939.920600794005;7949.96025019877;7953.12515623281;7650.196729343423;7768;7801;7802.843943121163;7821.100573434042;7654.3111119999185;7678.808029799244;7738.458307918419;7744.7676569703;7751.108622508399;7707.460477766558;7751.728689495841;7755.1469338372735;7756.0304961879765;7780.766577002699;7632.771016869503;7814.921850781477;7816.062072551274;7816.960915195445;7894.80262993424;35406.64593354059;35517.28965420677;36105;36229.818850905576;36292.27415451676;2840.7727381809405;2975.6875528069672;3495.790252584853;3534.2578058607724;3603.369410353204;27256.182314530597;27621.723782762117;27670.584941225898;27907.720922790715;28662;3261.2057183331854;3274.6889045540634;3303.6861498157637;3422.401079811048;3424.8972530824117;201305;202556;202827;203033;203922;44647;45582.54417455849;52416.47583524192;53286;53460;48109.27836082462;48229.51770482321;48407.515924841005;48519.27221091687;48532.27201591979;13705.451781928612;13824.308784560732;13845.792313115311;13855.653608659864;13910.165390076629;10725.195610329256;10782.892171078189;10796.082333001626;10889.455527223607;10911.290766100337;17658.91170544165;17838.5540361492;17855.46433606968;17983.370582029565;18014.63970720578;7243.239459856639;7497.775066748006;7639.236076392316;7639.388848892187;7699.26856948589;818.1981657975217;879.8812160358269;915.2632131134368;927.7402327348353;971.145392054987;26085.478290434086;26098.34754131124;26176.8691156543;26270.21189364322;26309;985.8422652375729;988.7972965542046;989.8119357322086;1032.2206733915846;1045.46158728255;13591.320433978262;13663.316834158253;13765.518206862711;13893.096948698303;13994.370253338624;253830;254038;256662;257968;258208;44263;44273.114537709065;44284.785760711835;44300.556994429644;44399.55600444019;1213.6783752305646;1227.6685294970487;1253.22926400265;1262.545483625894;1263.4819723912967;24971.625425618266;25340.239792806246;25688;25763.355916102246;26103.608445872946;38933.41599876061;38961;39110.608893911834;39396.40905386479;39426;244267;244980;245536;245668;247958;28786.856065719538;29152.854235728686;29182.56226156567;29183;29207.85396073091;33391.33217335591;33656.49515257322;33768.49347259744;34040.14899627529;34395.31209375848;27924.86037569799;28940.855295723388;29097;29201.269968250963;29725.256868578457;30303.848480757457;31222.21944451407;31628.683713163493;31687.841560792047;31747.36505269928;31668.208294792814;31739.52390714095;31808.840955795073;31883.840580796947;32187.195320117185;26647.33381665396;28948.42103157967;29377.706222937497;30033.849830750707;30204.395912082076;21559.137634495073;21784.128634855064;23165.536689265784;23715.40711482227;24040 04:41:56 INFO - 2015-12-17 04:41:56,078 DEBUG : BROWSER_OUTPUT: |2;jquery.html;340.29219224014037;341.6924767709063;342.3700391280044;343.0514627056189;343.22088858291676;12623.810642840343;13048.73902521949;15027.549173524798;15213.391464341426;15910.204489775508;15142.54572362831;15200.6959860803;15210.467633632825;15249.39002439901;15370.846291537082;4931.778069986841;4960.834203962057;5038.571721403685;5042.571381432583;5042.672226305279;4882.511748825124;5000.674956127858;5045.596352291808;5055.51972562606;5068.214426763839;4931.580815630676;5012.624053196007;5019.799208031674;5057.367829021372;5712.028955077647;5817.447342502455;5850.941490585104;5851.005329094021;5852.882942341129;5858.062709966385;5083.364579427572;5178.197379406198;5186.636935414523;5187.221916712486;5263.44733802949;11361;11522.135839812048;11572.942135289353;11596.826047609293;11724.355160466172;1748.3094177799699;1888.4901076709211;1891.101726679816;1891.9527011824678;1936.0223087340944;8984.640614375417;9047.728568142966;11051.021875015436;11684.357360345177;12616;1672.8996260224426;1787.642471505704;1800.9549761256073;1822.6719190545696;1831.8534517238588;53663.73168134134;56995.430045699424;57026.144607830916;57161.428385716026;57231.14153287704;9644.517774111266;9812.362196457207;9822.508874556244;9859.211263099078;9860.950695246478;12574.748505029847;12630.747385052431;12631.052671049709;12676.23942563449;12677.873221267853;7067;7069.8939515907305;7074.327938845904;7119.4660400470175;7133.785986420416;6676.632785196861;6692.59844409337;6697.7655782047395;6735.461163106939;6738.157730283715;4073.4704488416314;4201.012762000927;4224.218519573888;4230.048239146229;4236.216299984451;1671.2562909505154;1788.4098247578268;1802.9819701802815;1806.864485163618;1820.071763400675;384.6442041112016;388.5609261534448;409.985650502231;834.3033566971646;850.8340873529726;9558;9781.532770084545;9952.452615106095;9954.800903981879;9998.450085245382;413.53270803991296;424.34015106509673;437.4007609574839;443.3040127000643;445.56112229453964;8599.656013759504;8953.552322383855;9200.723978280663;9266.536673166314;9475;478148;482056;492478;495123;495581;60535.39464605386;69978.30238673741;70128.2987170132;70157;70876.93684594735;1063.7712891728315;1065.8827528971806;1070.796548655753;1093.049047328829;1103.426218366445;13982.44070237179;13982.860171398563;14131.293435327987;14230;14266.001379903308;29193.699718523952;29520.557191642572;30092.849535753056;30194.84902575473;31532.369352613277;41500.792496038535;42810;42812.78593607012;43245.3513197296;45514.54485455103;8581.0560838309;8825.91174088251;8901.33240006989;8919.73240802777;8950.283977281671;9618.326717129734;9790.853137202805;9831.754206144904;9887.357321774061;10522.158227341948;9221.123993220634;9412.623495060121;9457;9476.194523465583;9527.714168574677;9224.123708247706;9349.485778281987;9495.24038076966;9520.714378568655;9667.46828924416;9280.257579393767;9788.070133337322;9803;9813.607455701693;9816.754581135528;13874.098183618033;13976.371063301973;14034.438622455396;14069.030335752797;14090.22503762303;15042.398304068152;15065.548033559011;15316.157611331038;15439.6140096494;15486.5354039379 04:41:56 INFO - 2015-12-17 04:41:56,078 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1482.7182835261306;1632.9346826126955;1704.829517048295;1724.6205834716366;1757.059972914491;7031.894521582174;7129.6791644376;7338.449616278774;7501.662425190866;7525.2850979156965;7343.41252699784;7397.2602739726;7402.740904068359;7421.777346679594;7433.665485053172;2067.5348046689523;2102.926397576085;2109.5253567947243;2116.6930795034787;2118.8834614096218;2125.2774056820676;2137.294692751388;2151;2152.494163871489;2153.0418963561224;2031.8272946799539;2058.6294466995937;2075.5641315323724;2077.5325551750884;2089.874607523546;2112.345172996376;2114.4925217947657;2130.33959472564;2143.882086485243;2152.1714140056115;2142.924997625076;2157.5253444242244;2172.0334451169238;2179.1283486605325;2179.400664817188;7254.927450725478;7379.077615298088;7405.148407933103;7409.296116868889;7427.18300986891;4153.688473364479;4233.936490952638;4240.194363071007;4240.23675738367;4244.405783190356;2792.2600510864636;2802.887884484618;2836.5745138229227;2846.075025616662;2846.08925143954;872.4416373520952;886.095461619937;893.6872094766779;902.5712786426395;929.363386080537;9831.950840245825;9896.703098907044;9943.75140621483;9992.350497217658;9996.85004724937;3695.7597756145765;3717.888463346104;3726.31063253299;3796.677282431024;3850.942235866464;8033.076196237391;8060.95207623017;8081.6363263653275;8119.431639785276;8150.551719655357;4127.153933443637;4155.563665815106;4165.16696660663;4173.874783756492;4199.91600167995;3824.8661296854475;3848.3457812171996;3861.4014827701753;3874.6900247980093;3883.5533913599734;3156.1162874395204;3202.327511222623;3202.9679703203137;3204.9679503205134;3215.8070515769127;931.1293940165966;940.4874343482818;941.9434833909987;943.0899182289124;943.4716558727134;841.9579021048924;846.8348672009022;950.4012472142645;1034.6740776655329;1213.4175595714107;6706.497012723968;6760.459163266927;6763.222229443579;6764.458843292525;6769.559978601375;582.6358525921299;587.0636335045596;599.1372423709931;609.2688773471851;725.9782206533813;5220.138677118309;5297.337832770903;5326.6537675050995;5337.172738225581;5347.652402593897;8257.339412846957;8410.579471026547;8465.830683386297;8624.95687521571;8678.696245631372;8265;8293.377996650275;8293.502389856629;8363.749087527383;8370.372222083368;6581.86836263272;6823.215330237086;6940.063091482585;6941.271166527442;6960.79117626472;6889.862202755817;7001.649917504206;7006.404455621329;7032;7033.9296607033275;7737.845243095219;7866.803329916569;7879.881801773093;7955.5226686399;7966.322862556747;7925.128235893932;7946.245106714853;8100.149484304063;8164.959175204086;8264.710735124241;6366.363363663597;6379.170707808141;6442.645654489048;6448.226212854487;6453.064305675744;7081.114860642419;7188.029616001772;7191.244919283609;7200.495965282589;7201.71193152268;5654.180143879197;6486.286508483969;6488.221413430419;6559.0817285581015;6644.634545100066;5631.746571404215;6516.250631177286;6678.131842860591;6745.460363171032;6777.695003724745;6702.396784289437;6771.593704377753;6781.0167525709485;6784.7964561063245;6800.319968003358;6273.905891411541;6281.309056003927;6282.968585157228;6293.622382657055;6293.685315734104;6325.019621958604;6345.1116843641;6368.681565921593;6373.139626150411;6382 04:41:56 INFO - 2015-12-17 04:41:56,079 DEBUG : BROWSER_OUTPUT: |4;prototype.html;299.64941019007756;299.87705040933207;310.6008778719346;310.785557965004;311.28870530837025;5476.698781567012;5584.581156413265;5629.380768115504;5664.5468362531;5715.9428405715935;5395.082835917893;5501.972490137544;5535.418781027985;5562.859613779176;5591.245181900442;4324.286492728696;4331.8267269309345;4334.284843000901;4346.739195648255;4357.3899654048455;4320.762358070305;4321.027768752021;4338.717983331089;4345.478542574895;4352.825886964518;4123.195976784528;4232.915341693164;4306.698531102824;4323.027318853265;4323.697341186118;4233.491980962289;4261.190373828963;4267.039916018902;4269.380939763748;4271.9999999999845;3685.594584595692;3704.7221458390727;3714.609965953563;3717.8698745543775;3724.8510059597584;5331.973340133313;5340.91988620171;5343.251944727703;5362.088444964365;5371.838844834661;4777.904441911142;4885.022995400898;4890.413150421937;4903.41159060915;4913.778879950411;5804;5836.533077353802;5842.094475356284;5845.09401042839;5863.76544938202;4037.9192416151805;4038.6970977176534;4040.313146765057;4040.838366465338;4042.413849991764;6100.969495152496;6164.87670246597;6168.074788781674;6174.907376389403;6176.629402235881;4058.9188216236103;4143.047099167208;4143.91712165755;4152.875413737593;4159.022629682022;4225.112726327444;4233.111046680232;4235.7035007549175;4240.851570195028;4240.893977650583;4825.276208568673;4869.806897310111;4879.999600081975;4887.535684110004;4888.388951381077;5838.620489668158;5846.181534585162;5847.3275573308765;5858.970705146447;5861.41385861419;1451.586297905092;1476.4906107392933;1485.2054151029363;1496.895217334798;1497.430976229026;970.6554173268388;973.8977407372265;975.3075316525346;983.9262055345878;991.9702408927744;5060.165072763027;5091.338126043591;5098.923516147261;5105.489451054939;5117.385913690381;6028.909566356509;6037.667928263988;6038.63768173911;6044.425779550936;6057.575969682168;5073.720945348041;5078.7460626968505;5084.949150508522;5090.490950904954;5090.847274581768;3202.9359412811614;3243.8378081095852;3248.9675103249137;3268.5750852389037;3279.950800737991;5695.259616249861;5710.628809127394;5730.140478928194;5731.713414329267;5735.885282294331;6152;6185.402131104856;6208.2239720034995;6216.160818289473;6223.00431930898;5568.749406276727;5658.943410565924;5674.035413979634;5699.430056994351;5712.086066229466;6028;6046.697665116814;6051.152838602688;6053.182820319376;6054.636721796706;5888.263967004124;5902.557308201816;5906.586538942232;5915.201447804491;5915.881682366415;5678.864227154503;5690.231818704588;5708.200851880823;5724.141378793214;5733.254676891977;4047.5952404759287;4129.421880936611;4136.9793151034055;4137.25529404698;4146.79266036703;5947;6006.309274433496;6007.849803754766;6010.459058684739;6024.698765061642;5848.093545500455;5873.970630146822;5888.911666325094;5918.4673379394335;5919.378465261086;5892.1161825726485;5918.585699001029;5929.792457263975;5931.288245410578;5931.495822854933;5650;5784.768609255582;5789.986752318412;5795.623284486495;5796.449337312949;5912.526997840077;5940.079287710412;5966.910496342645;5967.970160149345;5974.9402505974385;5830.300363956183;5831.067029275296;5832.50423713989;5835.883282334415;5837.591368604157 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: |5;yui.html;663.5753118004478;671.1141293492585;728.057165970069;744.4119145874756;772.4940164192454;11798.1151413644;12061.638150855466;12150.453229604667;12162.695932601668;12192.146549741521;11295.548178072888;11328.716782080452;11419.086473082154;11428.08575313975;11946.342951137683;818.6070686070675;837.1042984007115;837.4054421360842;840.3487297344545;841.154639587217;817.0848649512554;826.7602395305354;828.0229329391316;829.5686243153557;829.6930135849714;851.825375797963;854.4702284583536;857.3612658569375;858.3133493205441;858.7509622209583;837.1712005114953;847.9618417171212;849.8937632795901;850.1838235294125;850.5959669157163;626.0796628955428;627.7081157261849;629.5844742469948;632.6393955445398;633.4710516718545;10198.694039178836;10230.18158547322;10324.483775811179;10332.793344133075;10336.17310615149;9426.293028022927;9993.950030249875;10763.677089687322;10880;10930.234883558074;9267.907320926772;9414.1527262546;9446.905530944672;9467.715968520955;9482.857757133648;3217.115293294339;3228.00320116116;3228.370467758788;3244.0592228253777;3297.670232976707;40393.192136157406;41031;41459.58540414618;41801.790991045156;41824.58175418268;9206.401583897024;9396.624135034523;9398.530073496298;9435.856652915181;9452.291078169164;15316.923415382851;15430.459933902259;15498.380064797508;15505;15564.299606517507;6285.465735412449;6293.748250070037;6394.424501794861;6459.903101453482;6503.869922601616;3392.9321413572084;3431.3137372525593;3468.6357932417236;3531.0113168312914;3555.306715190513;2551.9106831260815;2572.04834211343;2582.909598164093;2588.2623452315997;2588.352911772057;458.5071048622744;475.32266520209254;475.72645728706135;476.4187691016954;478.8300153445546;585.5432762445299;618.2025187508154;634.1977398590694;640.5580149696788;644.9451796597248;7923.683052677944;7941.205879412013;8003.359731221488;8023.719169829028;8288.336933045342;617.6942413505344;619.5013014523262;628.7956414165324;642.9260635027032;645.1741770533724;3655.3054919565197;3725.5715592707184;3747.868824591181;3811.1615444602144;3813.1801662642665;52087.73956130271;53029;53246.467535324926;53303.73348133235;53361;45925;46554.534454655695;46618.76690616525;46668;46808;37940.62059379426;38864.22271554609;41195.1760964779;44379.77810110929;45329.546704532535;7887.921120788718;8183.345332373515;8436.367272454469;8543.871841922251;8574.528400938008;32746.836265818518;32758.50862237116;32800.17999549935;32826.671733283314;33093.5107920149;35567.46648800322;36442.45336319904;38487.6151238484;42306;42656.78671606622;10280.485975701335;10311;10432.634857779942;10551.788964220827;10693.839592406275;11408.258463199865;11416.657500275005;11598.88401115978;11869.881301187223;12329.815052774395;11026.44867756593;11054.502547385226;11366.431678416211;11492.310461372344;11571.710707232387;10733.141348692243;10912.836307455553;11216.046636036028;11457.484413201259;11536.538538458368;10664.520096595827;10869.130469562258;11094.611688591183;11161.386123763272;11226.719332016764;9163.541822908961;9271.629134834531;9274.489903055397;9383.765405864635;9383.906160938302;9183.219426348835;9192.494412807093;9252.768680783034;9302.674406395743;9421.858672119786 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,323 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: __startTimestamp1450356116071__endTimestamp 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,081 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:41:56 INFO - 2015-12-17 04:41:56,082 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:41:56 INFO - 2015-12-17 04:41:56,082 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,082 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4725.78 Median:4858.00 stddev:499.44 (10.3%) stddev-sans-first:473.84 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: Values: 2655.6 2720.0 2748.5 2770.7 2773.8 4622.5 4841.2 4852.8 4867.5 4888.1 4759.8 4761.6 4772.9 4889.7 4942.4 4327.2 4399.6 4430.3 4455.2 4479.2 4390.8 4405.4 4456.6 4496.6 4510.4 4246.8 4318.2 4361.5 4450.6 4501.0 4419.7 4423.4 4427.5 4442.2 4455.2 4424.5 4428.3 4428.4 4437.6 4448.4 4420.9 4565.7 4592.7 4713.9 4753.0 5060.3 5065.8 5086.4 5107.1 5148.5 5117.0 5129.3 5130.5 5136.5 5151.1 4565.3 4703.4 4713.3 4732.1 4762.8 5255.3 5280.4 5280.8 5299.3 5300.8 5048.4 5056.3 5068.3 5080.4 5092.8 4903.1 5004.8 5022.7 5165.4 5174.2 5059.9 5127.5 5138.9 5143.6 5159.6 5099.2 5108.7 5111.1 5111.9 5163.7 4137.0 4160.7 4217.0 4248.4 4251.4 3728.5 3732.7 3734.2 3740.0 3771.1 4233.0 4445.7 4446.6 4451.5 4467.5 5031.1 5067.5 5123.9 5143.0 5165.7 4430.7 4465.0 4465.6 4497.5 4530.0 4391.5 4477.8 4571.7 4573.4 4576.8 4792.2 4832.9 4857.5 4869.7 4893.2 5152.5 5154.0 5172.7 5179.9 5182.8 4615.6 4765.0 4804.7 4902.6 4950.5 5114.2 5143.4 5158.9 5159.1 5176.2 5209.5 5213.9 5224.4 5227.2 5273.9 4746.8 4858.0 4860.8 4955.1 4998.5 4976.3 4987.3 5009.0 5024.5 5039.4 4824.9 4841.2 4901.3 4905.8 4919.2 4819.6 4908.9 4977.3 4981.9 4987.6 4846.1 4918.3 4919.6 4924.6 4974.3 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38775.56 Median:3289.19 stddev:61371.21 (1865.8%) stddev-sans-first:61476.31 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: Values: 563.4 570.3 570.5 573.1 578.9 34494.8 35017.0 35036.8 35049.6 35080.5 38682.8 39062.2 39227.4 39235.8 39293.6 7764.3 7903.2 7939.9 7950.0 7953.1 7650.2 7768.0 7801.0 7802.8 7821.1 7654.3 7678.8 7738.5 7744.8 7751.1 7707.5 7751.7 7755.1 7756.0 7780.8 7632.8 7814.9 7816.1 7817.0 7894.8 35406.6 35517.3 36105.0 36229.8 36292.3 2840.8 2975.7 3495.8 3534.3 3603.4 27256.2 27621.7 27670.6 27907.7 28662.0 3261.2 3274.7 3303.7 3422.4 3424.9 201305.0 202556.0 202827.0 203033.0 203922.0 44647.0 45582.5 52416.5 53286.0 53460.0 48109.3 48229.5 48407.5 48519.3 48532.3 13705.5 13824.3 13845.8 13855.7 13910.2 10725.2 10782.9 10796.1 10889.5 10911.3 17658.9 17838.6 17855.5 17983.4 18014.6 7243.2 7497.8 7639.2 7639.4 7699.3 818.2 879.9 915.3 927.7 971.1 26085.5 26098.3 26176.9 26270.2 26309.0 985.8 988.8 989.8 1032.2 1045.5 13591.3 13663.3 13765.5 13893.1 13994.4 253830.0 254038.0 256662.0 257968.0 258208.0 44263.0 44273.1 44284.8 44300.6 44399.6 1213.7 1227.7 1253.2 1262.5 1263.5 24971.6 25340.2 25688.0 25763.4 26103.6 38933.4 38961.0 39110.6 39396.4 39426.0 244267.0 244980.0 245536.0 245668.0 247958.0 28786.9 29152.9 29182.6 29183.0 29207.9 33391.3 33656.5 33768.5 34040.1 34395.3 27924.9 28940.9 29097.0 29201.3 29725.3 30303.8 31222.2 31628.7 31687.8 31747.4 31668.2 31739.5 31808.8 31883.8 32187.2 26647.3 28948.4 29377.7 30033.8 30204.4 21559.1 21784.1 23165.5 23715.4 24040.0 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25630.66 Median:248704.79 stddev:79859.47 (32.1%) stddev-sans-first:80060.92 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: Values: 340.3 341.7 342.4 343.1 343.2 12623.8 13048.7 15027.5 15213.4 15910.2 15142.5 15200.7 15210.5 15249.4 15370.8 4931.8 4960.8 5038.6 5042.6 5042.7 4882.5 5000.7 5045.6 5055.5 5068.2 4931.6 5012.6 5019.8 5057.4 5712.0 5817.4 5850.9 5851.0 5852.9 5858.1 5083.4 5178.2 5186.6 5187.2 5263.4 11361.0 11522.1 11572.9 11596.8 11724.4 1748.3 1888.5 1891.1 1892.0 1936.0 8984.6 9047.7 11051.0 11684.4 12616.0 1672.9 1787.6 1801.0 1822.7 1831.9 53663.7 56995.4 57026.1 57161.4 57231.1 9644.5 9812.4 9822.5 9859.2 9861.0 12574.7 12630.7 12631.1 12676.2 12677.9 7067.0 7069.9 7074.3 7119.5 7133.8 6676.6 6692.6 6697.8 6735.5 6738.2 4073.5 4201.0 4224.2 4230.0 4236.2 1671.3 1788.4 1803.0 1806.9 1820.1 384.6 388.6 410.0 834.3 850.8 9558.0 9781.5 9952.5 9954.8 9998.5 413.5 424.3 437.4 443.3 445.6 8599.7 8953.6 9200.7 9266.5 9475.0 478148.0 482056.0 492478.0 495123.0 495581.0 60535.4 69978.3 70128.3 70157.0 70876.9 1063.8 1065.9 1070.8 1093.0 1103.4 13982.4 13982.9 14131.3 14230.0 14266.0 29193.7 29520.6 30092.8 30194.8 31532.4 41500.8 42810.0 42812.8 43245.4 45514.5 8581.1 8825.9 8901.3 8919.7 8950.3 9618.3 9790.9 9831.8 9887.4 10522.2 9221.1 9412.6 9457.0 9476.2 9527.7 9224.1 9349.5 9495.2 9520.7 9667.5 9280.3 9788.1 9803.0 9813.6 9816.8 13874.1 13976.4 14034.4 14069.0 14090.2 15042.4 15065.5 15316.2 15439.6 15486.5 04:41:56 INFO - 2015-12-17 04:41:56,083 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,084 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5064.93 Median:6537.67 stddev:2659.40 (40.7%) stddev-sans-first:2653.23 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: Values: 1482.7 1632.9 1704.8 1724.6 1757.1 7031.9 7129.7 7338.4 7501.7 7525.3 7343.4 7397.3 7402.7 7421.8 7433.7 2067.5 2102.9 2109.5 2116.7 2118.9 2125.3 2137.3 2151.0 2152.5 2153.0 2031.8 2058.6 2075.6 2077.5 2089.9 2112.3 2114.5 2130.3 2143.9 2152.2 2142.9 2157.5 2172.0 2179.1 2179.4 7254.9 7379.1 7405.1 7409.3 7427.2 4153.7 4233.9 4240.2 4240.2 4244.4 2792.3 2802.9 2836.6 2846.1 2846.1 872.4 886.1 893.7 902.6 929.4 9832.0 9896.7 9943.8 9992.4 9996.9 3695.8 3717.9 3726.3 3796.7 3850.9 8033.1 8061.0 8081.6 8119.4 8150.6 4127.2 4155.6 4165.2 4173.9 4199.9 3824.9 3848.3 3861.4 3874.7 3883.6 3156.1 3202.3 3203.0 3205.0 3215.8 931.1 940.5 941.9 943.1 943.5 842.0 846.8 950.4 1034.7 1213.4 6706.5 6760.5 6763.2 6764.5 6769.6 582.6 587.1 599.1 609.3 726.0 5220.1 5297.3 5326.7 5337.2 5347.7 8257.3 8410.6 8465.8 8625.0 8678.7 8265.0 8293.4 8293.5 8363.7 8370.4 6581.9 6823.2 6940.1 6941.3 6960.8 6889.9 7001.6 7006.4 7032.0 7033.9 7737.8 7866.8 7879.9 7955.5 7966.3 7925.1 7946.2 8100.1 8165.0 8264.7 6366.4 6379.2 6442.6 6448.2 6453.1 7081.1 7188.0 7191.2 7200.5 7201.7 5654.2 6486.3 6488.2 6559.1 6644.6 5631.7 6516.3 6678.1 6745.5 6777.7 6702.4 6771.6 6781.0 6784.8 6800.3 6273.9 6281.3 6283.0 6293.6 6293.7 6325.0 6345.1 6368.7 6373.1 6382.0 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4842.76 Median:5576.67 stddev:1435.34 (25.7%) stddev-sans-first:1398.27 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: Values: 299.6 299.9 310.6 310.8 311.3 5476.7 5584.6 5629.4 5664.5 5715.9 5395.1 5502.0 5535.4 5562.9 5591.2 4324.3 4331.8 4334.3 4346.7 4357.4 4320.8 4321.0 4338.7 4345.5 4352.8 4123.2 4232.9 4306.7 4323.0 4323.7 4233.5 4261.2 4267.0 4269.4 4272.0 3685.6 3704.7 3714.6 3717.9 3724.9 5332.0 5340.9 5343.3 5362.1 5371.8 4777.9 4885.0 4890.4 4903.4 4913.8 5804.0 5836.5 5842.1 5845.1 5863.8 4037.9 4038.7 4040.3 4040.8 4042.4 6101.0 6164.9 6168.1 6174.9 6176.6 4058.9 4143.0 4143.9 4152.9 4159.0 4225.1 4233.1 4235.7 4240.9 4240.9 4825.3 4869.8 4880.0 4887.5 4888.4 5838.6 5846.2 5847.3 5859.0 5861.4 1451.6 1476.5 1485.2 1496.9 1497.4 970.7 973.9 975.3 983.9 992.0 5060.2 5091.3 5098.9 5105.5 5117.4 6028.9 6037.7 6038.6 6044.4 6057.6 5073.7 5078.7 5084.9 5090.5 5090.8 3202.9 3243.8 3249.0 3268.6 3280.0 5695.3 5710.6 5730.1 5731.7 5735.9 6152.0 6185.4 6208.2 6216.2 6223.0 5568.7 5658.9 5674.0 5699.4 5712.1 6028.0 6046.7 6051.2 6053.2 6054.6 5888.3 5902.6 5906.6 5915.2 5915.9 5678.9 5690.2 5708.2 5724.1 5733.3 4047.6 4129.4 4137.0 4137.3 4146.8 5947.0 6006.3 6007.8 6010.5 6024.7 5848.1 5874.0 5888.9 5918.5 5919.4 5892.1 5918.6 5929.8 5931.3 5931.5 5650.0 5784.8 5790.0 5795.6 5796.4 5912.5 5940.1 5966.9 5968.0 5974.9 5830.3 5831.1 5832.5 5835.9 5837.6 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,085 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12479.26 Median:46586.65 stddev:14325.41 (30.8%) stddev-sans-first:14338.11 04:41:56 INFO - 2015-12-17 04:41:56,086 DEBUG : BROWSER_OUTPUT: Values: 663.6 671.1 728.1 744.4 772.5 11798.1 12061.6 12150.5 12162.7 12192.1 11295.5 11328.7 11419.1 11428.1 11946.3 818.6 837.1 837.4 840.3 841.2 817.1 826.8 828.0 829.6 829.7 851.8 854.5 857.4 858.3 858.8 837.2 848.0 849.9 850.2 850.6 626.1 627.7 629.6 632.6 633.5 10198.7 10230.2 10324.5 10332.8 10336.2 9426.3 9994.0 10763.7 10880.0 10930.2 9267.9 9414.2 9446.9 9467.7 9482.9 3217.1 3228.0 3228.4 3244.1 3297.7 40393.2 41031.0 41459.6 41801.8 41824.6 9206.4 9396.6 9398.5 9435.9 9452.3 15316.9 15430.5 15498.4 15505.0 15564.3 6285.5 6293.7 6394.4 6459.9 6503.9 3392.9 3431.3 3468.6 3531.0 3555.3 2551.9 2572.0 2582.9 2588.3 2588.4 458.5 475.3 475.7 476.4 478.8 585.5 618.2 634.2 640.6 644.9 7923.7 7941.2 8003.4 8023.7 8288.3 617.7 619.5 628.8 642.9 645.2 3655.3 3725.6 3747.9 3811.2 3813.2 52087.7 53029.0 53246.5 53303.7 53361.0 45925.0 46554.5 46618.8 46668.0 46808.0 37940.6 38864.2 41195.2 44379.8 45329.5 7887.9 8183.3 8436.4 8543.9 8574.5 32746.8 32758.5 32800.2 32826.7 33093.5 35567.5 36442.5 38487.6 42306.0 42656.8 10280.5 10311.0 10432.6 10551.8 10693.8 11408.3 11416.7 11598.9 11869.9 12329.8 11026.4 11054.5 11366.4 11492.3 11571.7 10733.1 10912.8 11216.0 11457.5 11536.5 10664.5 10869.1 11094.6 11161.4 11226.7 9163.5 9271.6 9274.5 9383.8 9383.9 9183.2 9192.5 9252.8 9302.7 9421.9 04:41:56 INFO - 2015-12-17 04:41:56,086 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:41:56 INFO - 2015-12-17 04:41:56,087 DEBUG : BROWSER_OUTPUT: 04:41:56 INFO - 2015-12-17 04:41:56,828 INFO : Browser exited with error code: 0 04:41:56 INFO - 2015-12-17 04:41:56,844 INFO : Completed test dromaeo_css (00:18:34) 04:41:56 INFO - 2015-12-17 04:41:56,844 INFO : Starting test kraken 04:41:56 INFO - 2015-12-17 04:41:56,844 DEBUG : operating with platform_type : linux_ 04:41:56 INFO - 2015-12-17 04:41:56,845 INFO : Initialising browser for kraken test... 04:41:56 INFO - 2015-12-17 04:41:56,851 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS8fprE/profile http://localhost:37999/getInfo.html 04:42:01 INFO - 2015-12-17 04:42:01,858 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:42:01 INFO - 2015-12-17 04:42:01,859 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:42:01 INFO - 2015-12-17 04:42:01,859 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:42:01 INFO - 2015-12-17 04:42:01,859 DEBUG : BROWSER_OUTPUT: __metrics 04:42:01 INFO - 2015-12-17 04:42:01,960 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:42:02 INFO - 2015-12-17 04:42:02,900 INFO : Browser initialized. 04:42:02 INFO - 2015-12-17 04:42:02,900 INFO : Running cycle 1/1 for kraken test... 04:42:02 INFO - 2015-12-17 04:42:02,901 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS8fprE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:42:14 INFO - 2015-12-17 04:42:14,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 143347712 04:42:14 INFO - 2015-12-17 04:42:14,948 DEBUG : BROWSER_OUTPUT: 04:42:17 INFO - 2015-12-17 04:42:17,510 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37999/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:18 INFO - 2015-12-17 04:42:18,124 DEBUG : BROWSER_OUTPUT: RSS: Main: 143704064 04:42:18 INFO - 2015-12-17 04:42:18,124 DEBUG : BROWSER_OUTPUT: 04:42:20 INFO - 2015-12-17 04:42:20,914 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37999/tests/kraken/driver.html?testName=audio-dft) 04:42:21 INFO - 2015-12-17 04:42:21,514 DEBUG : BROWSER_OUTPUT: RSS: Main: 143380480 04:42:21 INFO - 2015-12-17 04:42:21,514 DEBUG : BROWSER_OUTPUT: 04:42:24 INFO - 2015-12-17 04:42:24,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37999/tests/kraken/driver.html?testName=audio-fft) 04:42:25 INFO - 2015-12-17 04:42:25,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 138358784 04:42:25 INFO - 2015-12-17 04:42:25,003 DEBUG : BROWSER_OUTPUT: 04:42:27 INFO - 2015-12-17 04:42:27,076 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37999/tests/kraken/driver.html?testName=audio-oscillator) 04:42:27 INFO - 2015-12-17 04:42:27,702 DEBUG : BROWSER_OUTPUT: RSS: Main: 138678272 04:42:27 INFO - 2015-12-17 04:42:27,702 DEBUG : BROWSER_OUTPUT: 04:42:29 INFO - 2015-12-17 04:42:29,997 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:42:30 INFO - 2015-12-17 04:42:30,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 139460608 04:42:30 INFO - 2015-12-17 04:42:30,586 DEBUG : BROWSER_OUTPUT: 04:42:33 INFO - 2015-12-17 04:42:33,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37999/tests/kraken/driver.html?testName=imaging-darkroom) 04:42:34 INFO - 2015-12-17 04:42:34,274 DEBUG : BROWSER_OUTPUT: RSS: Main: 140365824 04:42:34 INFO - 2015-12-17 04:42:34,274 DEBUG : BROWSER_OUTPUT: 04:42:37 INFO - 2015-12-17 04:42:37,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37999/tests/kraken/driver.html?testName=imaging-desaturate) 04:42:38 INFO - 2015-12-17 04:42:38,343 DEBUG : BROWSER_OUTPUT: RSS: Main: 139685888 04:42:38 INFO - 2015-12-17 04:42:38,343 DEBUG : BROWSER_OUTPUT: 04:42:41 INFO - 2015-12-17 04:42:41,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37999/tests/kraken/driver.html?testName=json-parse-financial) 04:42:41 INFO - 2015-12-17 04:42:41,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 138067968 04:42:41 INFO - 2015-12-17 04:42:41,848 DEBUG : BROWSER_OUTPUT: 04:42:43 INFO - 2015-12-17 04:42:43,639 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:42:44 INFO - 2015-12-17 04:42:44,225 DEBUG : BROWSER_OUTPUT: RSS: Main: 140517376 04:42:44 INFO - 2015-12-17 04:42:44,225 DEBUG : BROWSER_OUTPUT: 04:42:46 INFO - 2015-12-17 04:42:46,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:42:46 INFO - 2015-12-17 04:42:46,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 140312576 04:42:46 INFO - 2015-12-17 04:42:46,742 DEBUG : BROWSER_OUTPUT: 04:42:48 INFO - 2015-12-17 04:42:48,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:42:49 INFO - 2015-12-17 04:42:49,526 DEBUG : BROWSER_OUTPUT: RSS: Main: 141053952 04:42:49 INFO - 2015-12-17 04:42:49,526 DEBUG : BROWSER_OUTPUT: 04:42:52 INFO - 2015-12-17 04:42:52,143 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:42:52 INFO - 2015-12-17 04:42:52,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 140742656 04:42:52 INFO - 2015-12-17 04:42:52,745 DEBUG : BROWSER_OUTPUT: 04:42:55 INFO - 2015-12-17 04:42:55,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:42:55 INFO - 2015-12-17 04:42:55,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 140681216 04:42:55 INFO - 2015-12-17 04:42:55,981 DEBUG : BROWSER_OUTPUT: 04:42:57 INFO - 2015-12-17 04:42:57,796 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37999/tests/kraken/driver.html?testName=ai-astar) 04:42:58 INFO - 2015-12-17 04:42:58,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 141352960 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:42:58 INFO - 2015-12-17 04:42:58,047 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;109;116;115;116;125;114;113;114;115 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;136;163;180;135;161;159;134;174;132 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;162;163;165;164;167;164;165;162 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;75;74;72;72;135;75;74;72 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;97;95;138;95;95;95;139;95;96;135 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;111;113;111;111;111;110;111;110 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;148;148;149;148;148;147;147 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;99;96;96;96;96;96;97;97;95 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;78;76;73;74;77;74;73;75;74;75 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;67;68;69;69;76;68;68;69 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;86;86;86;84;86;86;86;88 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;134;124;129;130;122;130;128;131;126 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;142;152;154;150;152;140;165;150;152;146 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;72;72;71;71;70;68;70;70;71;76 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:42:58 INFO - 2015-12-17 04:42:58,048 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:42:58 INFO - 2015-12-17 04:42:58,049 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,787 04:42:58 INFO - 2015-12-17 04:42:58,049 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:42:58 INFO - 2015-12-17 04:42:58,049 DEBUG : BROWSER_OUTPUT: __startTimestamp1450356178045__endTimestamp 04:42:58 INFO - 2015-12-17 04:42:58,049 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:4.06 (3.5%) stddev-sans-first:4.24 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: Values: 113.0 109.0 116.0 115.0 116.0 125.0 114.0 113.0 114.0 115.0 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:153.60 Median:161.50 stddev:17.83 (11.0%) stddev-sans-first:18.65 04:42:58 INFO - 2015-12-17 04:42:58,052 DEBUG : BROWSER_OUTPUT: Values: 162.0 136.0 163.0 180.0 135.0 161.0 159.0 134.0 174.0 132.0 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:1.55 (0.9%) stddev-sans-first:1.62 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 162.0 163.0 165.0 164.0 167.0 164.0 165.0 162.0 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.40 Median:73.50 stddev:19.57 (26.6%) stddev-sans-first:20.58 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 75.0 74.0 72.0 72.0 135.0 75.0 74.0 72.0 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.00 Median:95.00 stddev:20.28 (21.3%) stddev-sans-first:21.11 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: Values: 97.0 95.0 138.0 95.0 95.0 95.0 139.0 95.0 96.0 135.0 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.90 Median:111.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 04:42:58 INFO - 2015-12-17 04:42:58,053 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 111.0 113.0 111.0 111.0 111.0 110.0 111.0 110.0 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 148.0 148.0 149.0 148.0 148.0 147.0 147.0 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.07 (1.1%) stddev-sans-first:1.13 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: Values: 96.0 99.0 96.0 96.0 96.0 96.0 96.0 97.0 97.0 95.0 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.90 Median:75.00 stddev:1.66 (2.2%) stddev-sans-first:1.33 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: Values: 78.0 76.0 73.0 74.0 77.0 74.0 73.0 75.0 74.0 75.0 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:2.51 (3.6%) stddev-sans-first:2.64 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 67.0 68.0 69.0 69.0 76.0 68.0 68.0 69.0 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.20 04:42:58 INFO - 2015-12-17 04:42:58,054 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 86.0 86.0 86.0 84.0 86.0 86.0 86.0 88.0 04:42:58 INFO - 2015-12-17 04:42:58,055 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,055 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:129.50 stddev:3.50 (2.7%) stddev-sans-first:3.70 04:42:58 INFO - 2015-12-17 04:42:58,055 DEBUG : BROWSER_OUTPUT: Values: 129.0 134.0 124.0 129.0 130.0 122.0 130.0 128.0 131.0 126.0 04:42:58 INFO - 2015-12-17 04:42:58,055 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,055 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.30 Median:152.00 stddev:6.93 (4.6%) stddev-sans-first:6.67 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: Values: 142.0 152.0 154.0 150.0 152.0 140.0 165.0 150.0 152.0 146.0 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:71.00 stddev:2.08 (2.9%) stddev-sans-first:2.18 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 71.0 71.0 70.0 68.0 70.0 70.0 71.0 76.0 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:42:58 INFO - 2015-12-17 04:42:58,056 DEBUG : BROWSER_OUTPUT: 04:42:58 INFO - 2015-12-17 04:42:58,938 INFO : Browser exited with error code: 0 04:42:58 INFO - 2015-12-17 04:42:58,988 INFO : Completed test kraken (00:01:02) 04:42:58 INFO - 2015-12-17 04:42:58,988 INFO : Starting test v8_7 04:42:58 INFO - 2015-12-17 04:42:58,988 DEBUG : operating with platform_type : linux_ 04:42:58 INFO - 2015-12-17 04:42:58,988 INFO : Initialising browser for v8_7 test... 04:42:58 INFO - 2015-12-17 04:42:58,994 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprItycg/profile http://localhost:37999/getInfo.html 04:43:03 INFO - 2015-12-17 04:43:03,979 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:43:03 INFO - 2015-12-17 04:43:03,980 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:43:03 INFO - 2015-12-17 04:43:03,980 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:43:03 INFO - 2015-12-17 04:43:03,980 DEBUG : BROWSER_OUTPUT: __metrics 04:43:04 INFO - 2015-12-17 04:43:04,121 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:43:05 INFO - 2015-12-17 04:43:05,032 INFO : Browser initialized. 04:43:05 INFO - 2015-12-17 04:43:05,032 INFO : Running cycle 1/1 for v8_7 test... 04:43:05 INFO - 2015-12-17 04:43:05,033 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprItycg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:43:17 INFO - 2015-12-17 04:43:17,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 143343616 04:43:17 INFO - 2015-12-17 04:43:17,086 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,483 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37999/tests/v8_7/run.html (next: http://localhost:37999/tests/v8_7/run.html) 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 137752576 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: |0;Richards;164.63615409944023 04:43:38 INFO - 2015-12-17 04:43:38,742 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.64430816404752 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.17364600781684 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5256.544502617801 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1063.8297872340424 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |5;Earley;702.7406886858749 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |6;Boyer;12192.77108433735 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |7;RegExp;26894.736842105263 04:43:38 INFO - 2015-12-17 04:43:38,743 DEBUG : BROWSER_OUTPUT: |8;Splay;596.3029218843172 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: __startTimestamp1450356218735__endTimestamp 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,744 DEBUG : BROWSER_OUTPUT: Values: 164.6 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.64 Median:191.64 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: Values: 191.6 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.17 Median:279.17 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: Values: 279.2 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5256.54 Median:5256.54 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,745 DEBUG : BROWSER_OUTPUT: Values: 5256.5 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1063.83 Median:1063.83 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: Values: 1063.8 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:702.74 Median:702.74 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: Values: 702.7 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,746 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12192.77 Median:12192.77 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: Values: 12192.8 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26894.74 Median:26894.74 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: Values: 26894.7 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:596.30 Median:596.30 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: Values: 596.3 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: 04:43:38 INFO - 2015-12-17 04:43:38,747 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 04:43:38 INFO - 2015-12-17 04:43:38,748 DEBUG : BROWSER_OUTPUT: Values: 4835.7 04:43:38 INFO - 2015-12-17 04:43:38,748 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:43:38 INFO - 2015-12-17 04:43:38,748 DEBUG : BROWSER_OUTPUT: 04:43:39 INFO - 2015-12-17 04:43:39,660 INFO : Browser exited with error code: 0 04:43:39 INFO - 2015-12-17 04:43:39,679 INFO : Completed test v8_7 (00:00:40) 04:43:39 INFO - 2015-12-17 04:43:39,747 INFO : Completed test suite (00:20:17) 04:43:39 INFO - 2015-12-17 04:43:39,747 DEBUG : Working with test: dromaeo_css 04:43:39 INFO - 2015-12-17 04:43:39,747 DEBUG : Generating results file: dromaeo_css 04:43:39 INFO - 2015-12-17 04:43:39,749 DEBUG : Working with test: kraken 04:43:39 INFO - 2015-12-17 04:43:39,749 DEBUG : Generating results file: kraken 04:43:39 INFO - 2015-12-17 04:43:39,749 INFO : javascript benchmark 04:43:39 INFO - 2015-12-17 04:43:39,749 DEBUG : Working with test: v8_7 04:43:39 INFO - 2015-12-17 04:43:39,749 DEBUG : Generating results file: v8_7 04:43:39 INFO - 2015-12-17 04:43:39,750 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:43:39 INFO - 2015-12-17 04:43:39,804 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:43:39 INFO - 2015-12-17 04:43:39,805 DEBUG : process_Request line: dromaeo_css 6280.07 graph.html#tests=[[72,94,43]] 04:43:39 INFO - 2015-12-17 04:43:39,805 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:43:39 INFO - 2015-12-17 04:43:39,833 DEBUG : process_Request line: kraken 1554.20 graph.html#tests=[[232,94,43]] 04:43:39 INFO - 2015-12-17 04:43:39,833 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:43:39 INFO - 2015-12-17 04:43:39,865 DEBUG : process_Request line: v8_7 21283.25 graph.html#tests=[[230,94,43]] 04:43:39 INFO - 2015-12-17 04:43:39,867 INFO : javascript benchmark 04:43:39 INFO - 2015-12-17 04:43:39,870 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2655.641488399066, 2720.0, 2748.491529067123, 2770.7367800058987, 2773.805833591648, 4622.514635963226, 4841.2012018016985, 4852.830150944717, 4867.488913664068, 4888.120138375091, 4759.7620118994, 4761.642876784246, 4772.928406073912, 4889.682170658904, 4942.431620363659, 4327.199468098404, 4399.604035636792, 4430.335449682541, 4455.1535208310315, 4479.171353299634, 4390.780460976938, 4405.404866771624, 4456.621187199092, 4496.550344965493, 4510.413646225986, 4246.830126794925, 4318.2227199104145, 4361.476622805252, 4450.621697155746, 4500.977495112536, 4419.734815911055, 4423.447069116361, 4427.46870375557, 4442.155990361821, 4455.242608756519, 4424.535423780489, 4428.291473364278, 4428.3578881062385, 4437.622802061828, 4448.377227188196, 4420.889477763049, 4565.7260564365915, 4592.7014744041535, 4713.858584242478, 4753.025629745894, 5060.291559181717, 5065.822696205615, 5086.364204474441, 5107.080725469414, 5148.485151484859, 5117.0, 5129.307543481619, 5130.538251557377, 5136.512031357052, 5151.072806894757, 4565.315202719586, 4703.365045718852, 4713.293006049052, 4732.100900828831, 4762.833300834454, 5255.290535777621, 5280.445553216934, 5280.815171469057, 5299.31108955841, 5300.787968481218, 5048.368953880765, 5056.317397151411, 5068.265101560253, 5080.364954380702, 5092.8472145835685, 4903.068417000758, 5004.849854504298, 5022.673526220784, 5165.354330708661, 5174.172132458789, 5059.949400505948, 5127.461616530285, 5138.87152821175, 5143.5627971622835, 5159.58723302135, 5099.158638824627, 5108.719020453911, 5111.05445492585, 5111.8722031948755, 5163.741812909339, 4136.958630413657, 4160.729552579072, 4216.9578304217175, 4248.4477017987465, 4251.404803327537, 3728.4780130781714, 3732.701383889282, 3734.178480734234, 3740.0, 3771.113788259757, 4233.0, 4445.688801783909, 4446.599806017474, 4451.5103338632725, 4467.486239082482, 5031.094403007493, 5067.5185857343495, 5123.8719032023755, 5143.0, 5165.690058596496, 4430.689851710414, 4465.040016396425, 4465.642748580106, 4497.460304763449, 4530.02604440047, 4391.538888416734, 4477.843275485342, 4571.725696458157, 4573.382593349922, 4576.794044268016, 4792.185328494164, 4832.9275060873415, 4857.489963553776, 4869.732164730832, 4893.241547560134, 5152.536271735562, 5154.046501397253, 5172.7413629319135, 5179.92230116541, 5182.844514664566, 4615.607673347803, 4765.046990601824, 4804.711717296834, 4902.558769710743, 4950.529699678525, 5114.232865070269, 5143.4085080216255, 5158.871028224325, 5159.122949098587, 5176.223566465061, 5209.505097015777, 5213.947860521346, 5224.399194092728, 5227.2420499028185, 5273.894522109613, 4746.762661866962, 4858.0, 4860.756962151808, 4955.058538877602, 4998.525140111684, 4976.328195693535, 4987.326710893983, 5009.0, 5024.547790698854, 5039.395272567315, 4824.8793780155775, 4841.176999910094, 4901.3383193268455, 4905.754712264444, 4919.1637421639125, 4819.614430845454, 4908.92636610444, 4977.253411988231, 4981.875453113701, 4987.576056035277, 4846.055019271208, 4918.262260660929, 4919.631027672867, 4924.556789888928, 4974.32846565709], "unit": "score", "name": "dojo.html", "value": 4694.4274707942095}, {"lowerIsBetter": false, "replicates": [563.4084211577842, 570.3498012266015, 570.5150621971322, 573.1374281706034, 578.9450002249785, 34494.82752586237, 35016.99999999997, 35036.824815875916, 35049.64950350496, 35080.47379289313, 38682.806585967104, 39062.21875562487, 39227.41158882619, 39235.80382098086, 39293.607063929354, 7764.3012128908385, 7903.209679032108, 7939.920600794005, 7949.96025019877, 7953.12515623281, 7650.196729343423, 7768.0, 7801.0, 7802.843943121163, 7821.100573434042, 7654.3111119999185, 7678.808029799244, 7738.458307918419, 7744.7676569703, 7751.108622508399, 7707.460477766558, 7751.728689495841, 7755.1469338372735, 7756.0304961879765, 7780.766577002699, 7632.771016869503, 7814.921850781477, 7816.062072551274, 7816.960915195445, 7894.80262993424, 35406.64593354059, 35517.28965420677, 36105.0, 36229.818850905576, 36292.27415451676, 2840.7727381809405, 2975.6875528069672, 3495.790252584853, 3534.2578058607724, 3603.369410353204, 27256.182314530597, 27621.723782762117, 27670.584941225898, 27907.720922790715, 28662.0, 3261.2057183331854, 3274.6889045540634, 3303.6861498157637, 3422.401079811048, 3424.8972530824117, 201305.0, 202556.0, 202827.0, 203033.0, 203922.0, 44647.0, 45582.54417455849, 52416.47583524192, 53286.0, 53460.0, 48109.27836082462, 48229.51770482321, 48407.515924841005, 48519.27221091687, 48532.27201591979, 13705.451781928612, 13824.308784560732, 13845.792313115311, 13855.653608659864, 13910.165390076629, 10725.195610329256, 10782.892171078189, 10796.082333001626, 10889.455527223607, 10911.290766100337, 17658.91170544165, 17838.5540361492, 17855.46433606968, 17983.370582029565, 18014.63970720578, 7243.239459856639, 7497.775066748006, 7639.236076392316, 7639.388848892187, 7699.26856948589, 818.1981657975217, 879.8812160358269, 915.2632131134368, 927.7402327348353, 971.145392054987, 26085.478290434086, 26098.34754131124, 26176.8691156543, 26270.21189364322, 26309.0, 985.8422652375729, 988.7972965542046, 989.8119357322086, 1032.2206733915846, 1045.46158728255, 13591.320433978262, 13663.316834158253, 13765.518206862711, 13893.096948698303, 13994.370253338624, 253830.0, 254038.0, 256662.0, 257968.0, 258208.0, 44263.0, 44273.114537709065, 44284.785760711835, 44300.556994429644, 44399.55600444019, 1213.6783752305646, 1227.6685294970487, 1253.22926400265, 1262.545483625894, 1263.4819723912967, 24971.625425618266, 25340.239792806246, 25688.0, 25763.355916102246, 26103.608445872946, 38933.41599876061, 38961.0, 39110.608893911834, 39396.40905386479, 39426.0, 244267.0, 244980.0, 245536.0, 245668.0, 247958.0, 28786.856065719538, 29152.854235728686, 29182.56226156567, 29183.0, 29207.85396073091, 33391.33217335591, 33656.49515257322, 33768.49347259744, 34040.14899627529, 34395.31209375848, 27924.86037569799, 28940.855295723388, 29097.0, 29201.269968250963, 29725.256868578457, 30303.848480757457, 31222.21944451407, 31628.683713163493, 31687.841560792047, 31747.36505269928, 31668.208294792814, 31739.52390714095, 31808.840955795073, 31883.840580796947, 32187.195320117185, 26647.33381665396, 28948.42103157967, 29377.706222937497, 30033.849830750707, 30204.395912082076, 21559.137634495073, 21784.128634855064, 23165.536689265784, 23715.40711482227, 24040.0], "unit": "score", "name": "ext.html", "value": 16383.81394694214}, {"lowerIsBetter": false, "replicates": [340.29219224014037, 341.6924767709063, 342.3700391280044, 343.0514627056189, 343.22088858291676, 12623.810642840343, 13048.73902521949, 15027.549173524798, 15213.391464341426, 15910.204489775508, 15142.54572362831, 15200.6959860803, 15210.467633632825, 15249.39002439901, 15370.846291537082, 4931.778069986841, 4960.834203962057, 5038.571721403685, 5042.571381432583, 5042.672226305279, 4882.511748825124, 5000.674956127858, 5045.596352291808, 5055.51972562606, 5068.214426763839, 4931.580815630676, 5012.624053196007, 5019.799208031674, 5057.367829021372, 5712.028955077647, 5817.447342502455, 5850.941490585104, 5851.005329094021, 5852.882942341129, 5858.062709966385, 5083.364579427572, 5178.197379406198, 5186.636935414523, 5187.221916712486, 5263.44733802949, 11361.0, 11522.135839812048, 11572.942135289353, 11596.826047609293, 11724.355160466172, 1748.3094177799699, 1888.4901076709211, 1891.101726679816, 1891.9527011824678, 1936.0223087340944, 8984.640614375417, 9047.728568142966, 11051.021875015436, 11684.357360345177, 12616.0, 1672.8996260224426, 1787.642471505704, 1800.9549761256073, 1822.6719190545696, 1831.8534517238588, 53663.73168134134, 56995.430045699424, 57026.144607830916, 57161.428385716026, 57231.14153287704, 9644.517774111266, 9812.362196457207, 9822.508874556244, 9859.211263099078, 9860.950695246478, 12574.748505029847, 12630.747385052431, 12631.052671049709, 12676.23942563449, 12677.873221267853, 7067.0, 7069.8939515907305, 7074.327938845904, 7119.4660400470175, 7133.785986420416, 6676.632785196861, 6692.59844409337, 6697.7655782047395, 6735.461163106939, 6738.157730283715, 4073.4704488416314, 4201.012762000927, 4224.218519573888, 4230.048239146229, 4236.216299984451, 1671.2562909505154, 1788.4098247578268, 1802.9819701802815, 1806.864485163618, 1820.071763400675, 384.6442041112016, 388.5609261534448, 409.985650502231, 834.3033566971646, 850.8340873529726, 9558.0, 9781.532770084545, 9952.452615106095, 9954.800903981879, 9998.450085245382, 413.53270803991296, 424.34015106509673, 437.4007609574839, 443.3040127000643, 445.56112229453964, 8599.656013759504, 8953.552322383855, 9200.723978280663, 9266.536673166314, 9475.0, 478148.0, 482056.0, 492478.0, 495123.0, 495581.0, 60535.39464605386, 69978.30238673741, 70128.2987170132, 70157.0, 70876.93684594735, 1063.7712891728315, 1065.8827528971806, 1070.796548655753, 1093.049047328829, 1103.426218366445, 13982.44070237179, 13982.860171398563, 14131.293435327987, 14230.0, 14266.001379903308, 29193.699718523952, 29520.557191642572, 30092.849535753056, 30194.84902575473, 31532.369352613277, 41500.792496038535, 42810.0, 42812.78593607012, 43245.3513197296, 45514.54485455103, 8581.0560838309, 8825.91174088251, 8901.33240006989, 8919.73240802777, 8950.283977281671, 9618.326717129734, 9790.853137202805, 9831.754206144904, 9887.357321774061, 10522.158227341948, 9221.123993220634, 9412.623495060121, 9457.0, 9476.194523465583, 9527.714168574677, 9224.123708247706, 9349.485778281987, 9495.24038076966, 9520.714378568655, 9667.46828924416, 9280.257579393767, 9788.070133337322, 9803.0, 9813.607455701693, 9816.754581135528, 13874.098183618033, 13976.371063301973, 14034.438622455396, 14069.030335752797, 14090.22503762303, 15042.398304068152, 15065.548033559011, 15316.157611331038, 15439.6140096494, 15486.5354039379], "unit": "score", "name": "jquery.html", "value": 7671.612291417067}, {"lowerIsBetter": false, "replicates": [1482.7182835261306, 1632.9346826126955, 1704.829517048295, 1724.6205834716366, 1757.059972914491, 7031.894521582174, 7129.6791644376, 7338.449616278774, 7501.662425190866, 7525.2850979156965, 7343.41252699784, 7397.2602739726, 7402.740904068359, 7421.777346679594, 7433.665485053172, 2067.5348046689523, 2102.926397576085, 2109.5253567947243, 2116.6930795034787, 2118.8834614096218, 2125.2774056820676, 2137.294692751388, 2151.0, 2152.494163871489, 2153.0418963561224, 2031.8272946799539, 2058.6294466995937, 2075.5641315323724, 2077.5325551750884, 2089.874607523546, 2112.345172996376, 2114.4925217947657, 2130.33959472564, 2143.882086485243, 2152.1714140056115, 2142.924997625076, 2157.5253444242244, 2172.0334451169238, 2179.1283486605325, 2179.400664817188, 7254.927450725478, 7379.077615298088, 7405.148407933103, 7409.296116868889, 7427.18300986891, 4153.688473364479, 4233.936490952638, 4240.194363071007, 4240.23675738367, 4244.405783190356, 2792.2600510864636, 2802.887884484618, 2836.5745138229227, 2846.075025616662, 2846.08925143954, 872.4416373520952, 886.095461619937, 893.6872094766779, 902.5712786426395, 929.363386080537, 9831.950840245825, 9896.703098907044, 9943.75140621483, 9992.350497217658, 9996.85004724937, 3695.7597756145765, 3717.888463346104, 3726.31063253299, 3796.677282431024, 3850.942235866464, 8033.076196237391, 8060.95207623017, 8081.6363263653275, 8119.431639785276, 8150.551719655357, 4127.153933443637, 4155.563665815106, 4165.16696660663, 4173.874783756492, 4199.91600167995, 3824.8661296854475, 3848.3457812171996, 3861.4014827701753, 3874.6900247980093, 3883.5533913599734, 3156.1162874395204, 3202.327511222623, 3202.9679703203137, 3204.9679503205134, 3215.8070515769127, 931.1293940165966, 940.4874343482818, 941.9434833909987, 943.0899182289124, 943.4716558727134, 841.9579021048924, 846.8348672009022, 950.4012472142645, 1034.6740776655329, 1213.4175595714107, 6706.497012723968, 6760.459163266927, 6763.222229443579, 6764.458843292525, 6769.559978601375, 582.6358525921299, 587.0636335045596, 599.1372423709931, 609.2688773471851, 725.9782206533813, 5220.138677118309, 5297.337832770903, 5326.6537675050995, 5337.172738225581, 5347.652402593897, 8257.339412846957, 8410.579471026547, 8465.830683386297, 8624.95687521571, 8678.696245631372, 8265.0, 8293.377996650275, 8293.502389856629, 8363.749087527383, 8370.372222083368, 6581.86836263272, 6823.215330237086, 6940.063091482585, 6941.271166527442, 6960.79117626472, 6889.862202755817, 7001.649917504206, 7006.404455621329, 7032.0, 7033.9296607033275, 7737.845243095219, 7866.803329916569, 7879.881801773093, 7955.5226686399, 7966.322862556747, 7925.128235893932, 7946.245106714853, 8100.149484304063, 8164.959175204086, 8264.710735124241, 6366.363363663597, 6379.170707808141, 6442.645654489048, 6448.226212854487, 6453.064305675744, 7081.114860642419, 7188.029616001772, 7191.244919283609, 7200.495965282589, 7201.71193152268, 5654.180143879197, 6486.286508483969, 6488.221413430419, 6559.0817285581015, 6644.634545100066, 5631.746571404215, 6516.250631177286, 6678.131842860591, 6745.460363171032, 6777.695003724745, 6702.396784289437, 6771.593704377753, 6781.0167525709485, 6784.7964561063245, 6800.319968003358, 6273.905891411541, 6281.309056003927, 6282.968585157228, 6293.622382657055, 6293.685315734104, 6325.019621958604, 6345.1116843641, 6368.681565921593, 6373.139626150411, 6382.0], "unit": "score", "name": "mootools.html", "value": 4091.8660269395587}, {"lowerIsBetter": false, "replicates": [299.64941019007756, 299.87705040933207, 310.6008778719346, 310.785557965004, 311.28870530837025, 5476.698781567012, 5584.581156413265, 5629.380768115504, 5664.5468362531, 5715.9428405715935, 5395.082835917893, 5501.972490137544, 5535.418781027985, 5562.859613779176, 5591.245181900442, 4324.286492728696, 4331.8267269309345, 4334.284843000901, 4346.739195648255, 4357.3899654048455, 4320.762358070305, 4321.027768752021, 4338.717983331089, 4345.478542574895, 4352.825886964518, 4123.195976784528, 4232.915341693164, 4306.698531102824, 4323.027318853265, 4323.697341186118, 4233.491980962289, 4261.190373828963, 4267.039916018902, 4269.380939763748, 4271.9999999999845, 3685.594584595692, 3704.7221458390727, 3714.609965953563, 3717.8698745543775, 3724.8510059597584, 5331.973340133313, 5340.91988620171, 5343.251944727703, 5362.088444964365, 5371.838844834661, 4777.904441911142, 4885.022995400898, 4890.413150421937, 4903.41159060915, 4913.778879950411, 5804.0, 5836.533077353802, 5842.094475356284, 5845.09401042839, 5863.76544938202, 4037.9192416151805, 4038.6970977176534, 4040.313146765057, 4040.838366465338, 4042.413849991764, 6100.969495152496, 6164.87670246597, 6168.074788781674, 6174.907376389403, 6176.629402235881, 4058.9188216236103, 4143.047099167208, 4143.91712165755, 4152.875413737593, 4159.022629682022, 4225.112726327444, 4233.111046680232, 4235.7035007549175, 4240.851570195028, 4240.893977650583, 4825.276208568673, 4869.806897310111, 4879.999600081975, 4887.535684110004, 4888.388951381077, 5838.620489668158, 5846.181534585162, 5847.3275573308765, 5858.970705146447, 5861.41385861419, 1451.586297905092, 1476.4906107392933, 1485.2054151029363, 1496.895217334798, 1497.430976229026, 970.6554173268388, 973.8977407372265, 975.3075316525346, 983.9262055345878, 991.9702408927744, 5060.165072763027, 5091.338126043591, 5098.923516147261, 5105.489451054939, 5117.385913690381, 6028.909566356509, 6037.667928263988, 6038.63768173911, 6044.425779550936, 6057.575969682168, 5073.720945348041, 5078.7460626968505, 5084.949150508522, 5090.490950904954, 5090.847274581768, 3202.9359412811614, 3243.8378081095852, 3248.9675103249137, 3268.5750852389037, 3279.950800737991, 5695.259616249861, 5710.628809127394, 5730.140478928194, 5731.713414329267, 5735.885282294331, 6152.0, 6185.402131104856, 6208.2239720034995, 6216.160818289473, 6223.00431930898, 5568.749406276727, 5658.943410565924, 5674.035413979634, 5699.430056994351, 5712.086066229466, 6028.0, 6046.697665116814, 6051.152838602688, 6053.182820319376, 6054.636721796706, 5888.263967004124, 5902.557308201816, 5906.586538942232, 5915.201447804491, 5915.881682366415, 5678.864227154503, 5690.231818704588, 5708.200851880823, 5724.141378793214, 5733.254676891977, 4047.5952404759287, 4129.421880936611, 4136.9793151034055, 4137.25529404698, 4146.79266036703, 5947.0, 6006.309274433496, 6007.849803754766, 6010.459058684739, 6024.698765061642, 5848.093545500455, 5873.970630146822, 5888.911666325094, 5918.4673379394335, 5919.378465261086, 5892.1161825726485, 5918.585699001029, 5929.792457263975, 5931.288245410578, 5931.495822854933, 5650.0, 5784.768609255582, 5789.986752318412, 5795.623284486495, 5796.449337312949, 5912.526997840077, 5940.079287710412, 5966.910496342645, 5967.970160149345, 5974.9402505974385, 5830.300363956183, 5831.067029275296, 5832.50423713989, 5835.883282334415, 5837.591368604157], "unit": "score", "name": "prototype.html", "value": 4374.6434292027925}, {"lowerIsBetter": false, "replicates": [663.5753118004478, 671.1141293492585, 728.057165970069, 744.4119145874756, 772.4940164192454, 11798.1151413644, 12061.638150855466, 12150.453229604667, 12162.695932601668, 12192.146549741521, 11295.548178072888, 11328.716782080452, 11419.086473082154, 11428.08575313975, 11946.342951137683, 818.6070686070675, 837.1042984007115, 837.4054421360842, 840.3487297344545, 841.154639587217, 817.0848649512554, 826.7602395305354, 828.0229329391316, 829.5686243153557, 829.6930135849714, 851.825375797963, 854.4702284583536, 857.3612658569375, 858.3133493205441, 858.7509622209583, 837.1712005114953, 847.9618417171212, 849.8937632795901, 850.1838235294125, 850.5959669157163, 626.0796628955428, 627.7081157261849, 629.5844742469948, 632.6393955445398, 633.4710516718545, 10198.694039178836, 10230.18158547322, 10324.483775811179, 10332.793344133075, 10336.17310615149, 9426.293028022927, 9993.950030249875, 10763.677089687322, 10880.0, 10930.234883558074, 9267.907320926772, 9414.1527262546, 9446.905530944672, 9467.715968520955, 9482.857757133648, 3217.115293294339, 3228.00320116116, 3228.370467758788, 3244.0592228253777, 3297.670232976707, 40393.192136157406, 41031.0, 41459.58540414618, 41801.790991045156, 41824.58175418268, 9206.401583897024, 9396.624135034523, 9398.530073496298, 9435.856652915181, 9452.291078169164, 15316.923415382851, 15430.459933902259, 15498.380064797508, 15505.0, 15564.299606517507, 6285.465735412449, 6293.748250070037, 6394.424501794861, 6459.903101453482, 6503.869922601616, 3392.9321413572084, 3431.3137372525593, 3468.6357932417236, 3531.0113168312914, 3555.306715190513, 2551.9106831260815, 2572.04834211343, 2582.909598164093, 2588.2623452315997, 2588.352911772057, 458.5071048622744, 475.32266520209254, 475.72645728706135, 476.4187691016954, 478.8300153445546, 585.5432762445299, 618.2025187508154, 634.1977398590694, 640.5580149696788, 644.9451796597248, 7923.683052677944, 7941.205879412013, 8003.359731221488, 8023.719169829028, 8288.336933045342, 617.6942413505344, 619.5013014523262, 628.7956414165324, 642.9260635027032, 645.1741770533724, 3655.3054919565197, 3725.5715592707184, 3747.868824591181, 3811.1615444602144, 3813.1801662642665, 52087.73956130271, 53029.0, 53246.467535324926, 53303.73348133235, 53361.0, 45925.0, 46554.534454655695, 46618.76690616525, 46668.0, 46808.0, 37940.62059379426, 38864.22271554609, 41195.1760964779, 44379.77810110929, 45329.546704532535, 7887.921120788718, 8183.345332373515, 8436.367272454469, 8543.871841922251, 8574.528400938008, 32746.836265818518, 32758.50862237116, 32800.17999549935, 32826.671733283314, 33093.5107920149, 35567.46648800322, 36442.45336319904, 38487.6151238484, 42306.0, 42656.78671606622, 10280.485975701335, 10311.0, 10432.634857779942, 10551.788964220827, 10693.839592406275, 11408.258463199865, 11416.657500275005, 11598.88401115978, 11869.881301187223, 12329.815052774395, 11026.44867756593, 11054.502547385226, 11366.431678416211, 11492.310461372344, 11571.710707232387, 10733.141348692243, 10912.836307455553, 11216.046636036028, 11457.484413201259, 11536.538538458368, 10664.520096595827, 10869.130469562258, 11094.611688591183, 11161.386123763272, 11226.719332016764, 9163.541822908961, 9271.629134834531, 9274.489903055397, 9383.765405864635, 9383.906160938302, 9183.219426348835, 9192.494412807093, 9252.768680783034, 9302.674406395743, 9421.858672119786], "unit": "score", "name": "yui.html", "value": 5807.590761207902}], "name": "dromaeo_css", "value": 6280.065989001506}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 116.0, 115.0, 116.0, 125.0, 114.0, 113.0, 114.0, 115.0], "unit": "score", "name": "ai-astar", "value": 115.0}, {"lowerIsBetter": true, "replicates": [162.0, 136.0, 163.0, 180.0, 135.0, 161.0, 159.0, 134.0, 174.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 153.6}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 162.0, 163.0, 165.0, 164.0, 167.0, 164.0, 165.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 75.0, 74.0, 72.0, 72.0, 135.0, 75.0, 74.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.4}, {"lowerIsBetter": true, "replicates": [97.0, 95.0, 138.0, 95.0, 95.0, 95.0, 139.0, 95.0, 96.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 108.0}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 111.0, 113.0, 111.0, 111.0, 111.0, 110.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.9}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.9}, {"lowerIsBetter": true, "replicates": [96.0, 99.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [78.0, 76.0, 73.0, 74.0, 77.0, 74.0, 73.0, 75.0, 74.0, 75.0], "unit": "score", "name": "json-parse-financial", "value": 74.9}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 67.0, 68.0, 69.0, 69.0, 76.0, 68.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.1}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 86.0, 86.0, 86.0, 84.0, 86.0, 86.0, 86.0, 88.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.5}, {"lowerIsBetter": true, "replicates": [129.0, 134.0, 124.0, 129.0, 130.0, 122.0, 130.0, 128.0, 131.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 128.3}, {"lowerIsBetter": true, "replicates": [142.0, 152.0, 154.0, 150.0, 152.0, 140.0, 165.0, 150.0, 152.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 71.0, 71.0, 70.0, 68.0, 70.0, 70.0, 71.0, 76.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.1}], "name": "kraken", "value": 1554.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "Richards", "value": 214.42434799999998}, {"lowerIsBetter": false, "replicates": [191.64430816404752], "unit": "score", "name": "DeltaBlue", "value": 345.00372400000015}, {"lowerIsBetter": false, "replicates": [279.17364600781684], "unit": "score", "name": "Encrypt", "value": 953.4603419999996}, {"lowerIsBetter": false, "replicates": [5256.544502617801], "unit": "score", "name": "Decrypt", "value": 50.6380189243028}, {"lowerIsBetter": false, "replicates": [1063.8297872340424], "unit": "score", "name": "RayTrace", "value": 695.5896600000003}, {"lowerIsBetter": false, "replicates": [702.7406886858749], "unit": "score", "name": "Earley", "value": 948.3768489999995}, {"lowerIsBetter": false, "replicates": [12192.77108433735], "unit": "score", "name": "Boyer", "value": 54.66050296442688}, {"lowerIsBetter": false, "replicates": [26894.736842105263], "unit": "score", "name": "RegExp", "value": 33.87224070450098}, {"lowerIsBetter": false, "replicates": [596.3029218843172], "unit": "score", "name": "Splay", "value": 136.66040700000002}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21283.253111757054}]} 04:43:39 INFO - RETURN: dromaeo_css: 6280.07 04:43:39 INFO - RETURN: kraken: 1554.20 04:43:39 INFO - RETURN: v8_7: 21283.25 04:43:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21283.25"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1554.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6280.07"}}} 04:43:39 INFO - Return code: 0 04:43:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:43:39 INFO - # TBPL SUCCESS # 04:43:39 INFO - Running post-action listener: _resource_record_post_action 04:43:39 INFO - Running post-run listener: _resource_record_post_run 04:43:41 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517242880; Read time: 12; Write time: 660168 04:43:41 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 233394176; Read time: 0; Write time: 452256 04:43:41 INFO - run-tests - Wall time: 1219s; CPU: 13.0%; Read bytes: 4096; Write bytes: 273129472; Read time: 12; Write time: 202896 04:43:41 INFO - Running post-run listener: _upload_blobber_files 04:43:41 INFO - Blob upload gear active. 04:43:41 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:43:41 INFO - Copying logs to upload dir... 04:43:41 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1273.179979 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-12-17 04:43:38.447984) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 04:43:38.451588) ========= 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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010043 build_url: 'https://queue.taskcluster.net/v1/task/meu_lbZyT5WOSTbBVt3kzg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 04:43:38.502757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:43:38.503109) ========= 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=004b26eee9c3b360c8654f40000001e1-1450343839.263939-2135177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004674 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 04:43:38.552274) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 04:43:38.552617) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 04:43:38.552946) ========= ========= Total master_lag: 0.22 =========