builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1450704400.53 results: success (0) buildid: 20151221040829 builduid: 7a22ab2e35534224b6ceab48bf17b6a6 revision: a8acaa9868df4aa8d801725cfa9ef744640fc402 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.531370) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.531865) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.532213) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006382 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.558064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.558353) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.571876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.572247) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004703 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.628353) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.628615) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.628947) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.629237) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 05:26:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.9M=0s 2015-12-21 05:26:40 (41.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187602 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.830234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.830554) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016355 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.879878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:40.880235) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8acaa9868df4aa8d801725cfa9ef744640fc402 --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 a8acaa9868df4aa8d801725cfa9ef744640fc402 --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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 05:26:40,940 truncating revision to first 12 chars 2015-12-21 05:26:40,940 Setting DEBUG logging. 2015-12-21 05:26:40,940 attempt 1/10 2015-12-21 05:26:40,940 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8acaa9868df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 05:26:41,301 unpacking tar archive at: mozilla-central-a8acaa9868df/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645168 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:41.538462) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:41.538779) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:41.701313) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:41.701622) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 05:26:41.702175) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-21 05:26:41.702557) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:26:41 INFO - MultiFileLogger online at 20151221 05:26:41 in /builds/slave/test 05:26:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 05:26:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:26:41 INFO - {'append_to_log': False, 05:26:41 INFO - 'base_work_dir': '/builds/slave/test', 05:26:41 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:26:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:26:41 INFO - 'branch': 'Firefox-Non-PGO', 05:26:41 INFO - 'buildbot_json_path': 'buildprops.json', 05:26:41 INFO - 'config_files': ('talos/linux_config.py',), 05:26:41 INFO - 'default_actions': ('clobber', 05:26:41 INFO - 'read-buildbot-config', 05:26:41 INFO - 'download-and-extract', 05:26:41 INFO - 'populate-webroot', 05:26:41 INFO - 'create-virtualenv', 05:26:41 INFO - 'install', 05:26:41 INFO - 'run-tests'), 05:26:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:26:41 INFO - 'download_minidump_stackwalk': True, 05:26:41 INFO - 'download_symbols': 'ondemand', 05:26:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:26:41 INFO - 'tooltool.py': '/tools/tooltool.py', 05:26:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:26:41 INFO - '/tools/misc-python/virtualenv.py')}, 05:26:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:26:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:26:41 INFO - 'installer_path': 'installer.exe', 05:26:41 INFO - 'log_level': 'info', 05:26:41 INFO - 'log_name': 'talos', 05:26:41 INFO - 'log_to_console': True, 05:26:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:26:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:26:41 INFO - 'opt_config_files': (), 05:26:41 INFO - 'pip_index': False, 05:26:41 INFO - 'sps_profile': False, 05:26:41 INFO - 'sps_profile_interval': 0, 05:26:41 INFO - 'suite': 'dromaeojs', 05:26:41 INFO - 'system_bits': '64', 05:26:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:26:41 INFO - 'title': 'talos-linux64-ix-038', 05:26:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:26:41 INFO - 'use_talos_json': True, 05:26:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:26:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:26:41 INFO - 'work_dir': 'build'} 05:26:41 INFO - ##### 05:26:41 INFO - ##### Running clobber step. 05:26:41 INFO - ##### 05:26:41 INFO - Running pre-action listener: _resource_record_pre_action 05:26:41 INFO - Running main action method: clobber 05:26:41 INFO - rmtree: /builds/slave/test/build 05:26:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:26:42 INFO - Running post-action listener: _resource_record_post_action 05:26:42 INFO - ##### 05:26:42 INFO - ##### Running read-buildbot-config step. 05:26:42 INFO - ##### 05:26:42 INFO - Running pre-action listener: _resource_record_pre_action 05:26:42 INFO - Running main action method: read_buildbot_config 05:26:42 INFO - Using buildbot properties: 05:26:42 INFO - { 05:26:42 INFO - "project": "", 05:26:42 INFO - "product": "firefox", 05:26:42 INFO - "script_repo_revision": "production", 05:26:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:26:42 INFO - "repository": "", 05:26:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:26:42 INFO - "buildid": "20151221040829", 05:26:42 INFO - "pgo_build": "False", 05:26:42 INFO - "basedir": "/builds/slave/test", 05:26:42 INFO - "buildnumber": 414, 05:26:42 INFO - "slavename": "talos-linux64-ix-038", 05:26:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:26:42 INFO - "platform": "ubuntu64_hw", 05:26:42 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 05:26:42 INFO - "branch": "mozilla-central", 05:26:42 INFO - "repo_path": "mozilla-central", 05:26:42 INFO - "revision": "a8acaa9868df4aa8d801725cfa9ef744640fc402", 05:26:42 INFO - "stage_platform": "linux64", 05:26:42 INFO - "builduid": "7a22ab2e35534224b6ceab48bf17b6a6", 05:26:42 INFO - "slavebuilddir": "test" 05:26:42 INFO - } 05:26:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:26:42 INFO - Running post-action listener: _resource_record_post_action 05:26:42 INFO - ##### 05:26:42 INFO - ##### Running download-and-extract step. 05:26:42 INFO - ##### 05:26:42 INFO - Running pre-action listener: _resource_record_pre_action 05:26:42 INFO - Running main action method: download_and_extract 05:26:42 INFO - mkdir: /builds/slave/test/build/tests 05:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:42 INFO - https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:26:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/test_packages.json 05:26:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/test_packages.json 05:26:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:26:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:26:43 INFO - Downloaded 1302 bytes. 05:26:43 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:26:43 INFO - Using the following test package requirements: 05:26:43 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:26:43 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:26:43 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'jsshell-linux-x86_64.zip'], 05:26:43 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:26:43 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:26:43 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:26:43 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:26:43 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:26:43 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:26:43 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:26:43 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:26:43 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:43 INFO - https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:44 INFO - Downloaded 21926134 bytes. 05:26:44 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 05:26:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:44 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 05:26:46 INFO - Return code: 0 05:26:46 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 05:26:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:46 INFO - https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:26:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:48 INFO - Downloaded 21926134 bytes. 05:26:48 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 05:26:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:26:48 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 05:26:49 INFO - Return code: 0 05:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:49 INFO - https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:26:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:26:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:26:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:50 INFO - Downloaded 11132277 bytes. 05:26:50 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 05:26:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:26:50 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 05:26:51 INFO - Return code: 0 05:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:51 INFO - https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:26:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:26:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:26:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aTqGZp0MSVy9yq1-knaNGQ/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 05:26:52 INFO - Downloaded 53381964 bytes. 05:26:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:26:52 INFO - mkdir: /builds/slave/test/properties 05:26:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:26:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:26:52 INFO - Contents: 05:26:52 INFO - build_url:https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:26:52 INFO - Running post-action listener: _resource_record_post_action 05:26:52 INFO - Running post-action listener: set_extra_try_arguments 05:26:52 INFO - ##### 05:26:52 INFO - ##### Running populate-webroot step. 05:26:52 INFO - ##### 05:26:52 INFO - Running pre-action listener: _resource_record_pre_action 05:26:52 INFO - Running main action method: populate_webroot 05:26:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:26:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:26:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:26:52 INFO - '--tppagecycles', 05:26:52 INFO - '7'], 05:26:52 INFO - 'tests': ['tsvgm']}}, 05:26:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:26:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:26:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:26:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:26:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:26:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['damp', 'tps']}, 05:26:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['damp', 'tps']}, 05:26:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:26:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:26:52 INFO - 'other': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'other_l64': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'other_nol64': {'tests': ['a11yr', 05:26:52 INFO - 'ts_paint', 05:26:52 INFO - 'tpaint', 05:26:52 INFO - 'sessionrestore', 05:26:52 INFO - 'sessionrestore_no_auto_restore']}, 05:26:52 INFO - 'svgr': {'tests': ['tsvgx', 05:26:52 INFO - 'tsvgr_opacity', 05:26:52 INFO - 'tart', 05:26:52 INFO - 'tscrollx', 05:26:52 INFO - 'cart']}, 05:26:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['tsvgx', 05:26:52 INFO - 'tsvgr_opacity', 05:26:52 INFO - 'tart', 05:26:52 INFO - 'tscrollx', 05:26:52 INFO - 'cart']}, 05:26:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'tests': ['tp5o']}, 05:26:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--e10s'], 05:26:52 INFO - 'tests': ['tp5o']}, 05:26:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--xperf_path', 05:26:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:52 INFO - 'tests': ['tp5n']}, 05:26:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:26:52 INFO - 'talos_options': ['--e10s', 05:26:52 INFO - '--xperf_path', 05:26:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:26:52 INFO - 'tests': ['tp5n']}}, 05:26:52 INFO - 'talos.zip': {'path': '', 05:26:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:26:52 INFO - Running post-action listener: _resource_record_post_action 05:26:52 INFO - ##### 05:26:52 INFO - ##### Running create-virtualenv step. 05:26:52 INFO - ##### 05:26:52 INFO - Running pre-action listener: _resource_record_pre_action 05:26:52 INFO - Running main action method: create_virtualenv 05:26:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:26:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:26:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:26:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:26:52 INFO - Using real prefix '/usr' 05:26:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:26:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:26:54 INFO - Installing pip.................done. 05:26:54 INFO - Return code: 0 05:26:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:54 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:26:54 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 05:26:54 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 05:26:54 INFO - Using env: {'DISPLAY': ':0', 05:26:54 INFO - 'HOME': '/home/cltbld', 05:26:54 INFO - 'LANG': 'en_US.UTF-8', 05:26:54 INFO - 'LANGUAGE': 'en_US:en', 05:26:54 INFO - 'LOGNAME': 'cltbld', 05:26:54 INFO - 'MAIL': '/var/mail/cltbld', 05:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:54 INFO - 'MOZ_NO_REMOTE': '1', 05:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:54 INFO - 'NO_EM_RESTART': '1', 05:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:54 INFO - 'PWD': '/builds/slave/test', 05:26:54 INFO - 'SHELL': '/bin/bash', 05:26:54 INFO - 'SHLVL': '1', 05:26:54 INFO - 'TERM': 'linux', 05:26:54 INFO - 'TMOUT': '86400', 05:26:54 INFO - 'USER': 'cltbld', 05:26:54 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:54 INFO - '_': '/tools/buildbot/bin/python'} 05:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:26:55 INFO - Cleaning up... 05:26:55 INFO - Return code: 0 05:26:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:55 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:26:55 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 05:26:55 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 05:26:55 INFO - Using env: {'DISPLAY': ':0', 05:26:55 INFO - 'HOME': '/home/cltbld', 05:26:55 INFO - 'LANG': 'en_US.UTF-8', 05:26:55 INFO - 'LANGUAGE': 'en_US:en', 05:26:55 INFO - 'LOGNAME': 'cltbld', 05:26:55 INFO - 'MAIL': '/var/mail/cltbld', 05:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:55 INFO - 'MOZ_NO_REMOTE': '1', 05:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:55 INFO - 'NO_EM_RESTART': '1', 05:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:55 INFO - 'PWD': '/builds/slave/test', 05:26:55 INFO - 'SHELL': '/bin/bash', 05:26:55 INFO - 'SHLVL': '1', 05:26:55 INFO - 'TERM': 'linux', 05:26:55 INFO - 'TMOUT': '86400', 05:26:55 INFO - 'USER': 'cltbld', 05:26:55 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:55 INFO - '_': '/tools/buildbot/bin/python'} 05:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:55 INFO - Downloading/unpacking psutil>=0.7.1 05:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:57 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:26:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:26:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:26:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:26:57 INFO - Installing collected packages: psutil 05:26:57 INFO - Running setup.py install for psutil 05:26:57 INFO - building 'psutil._psutil_linux' extension 05:26:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:26:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:26:57 INFO - building 'psutil._psutil_posix' extension 05:26:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:26:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:26:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:26:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:26:57 INFO - Successfully installed psutil 05:26:57 INFO - Cleaning up... 05:26:57 INFO - Return code: 0 05:26:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26: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', '--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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:26:57 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 05:26:57 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 05:26:57 INFO - Using env: {'DISPLAY': ':0', 05:26:57 INFO - 'HOME': '/home/cltbld', 05:26:57 INFO - 'LANG': 'en_US.UTF-8', 05:26:57 INFO - 'LANGUAGE': 'en_US:en', 05:26:57 INFO - 'LOGNAME': 'cltbld', 05:26:57 INFO - 'MAIL': '/var/mail/cltbld', 05:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:57 INFO - 'MOZ_NO_REMOTE': '1', 05:26:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:57 INFO - 'NO_EM_RESTART': '1', 05:26:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:57 INFO - 'PWD': '/builds/slave/test', 05:26:57 INFO - 'SHELL': '/bin/bash', 05:26:57 INFO - 'SHLVL': '1', 05:26:57 INFO - 'TERM': 'linux', 05:26:57 INFO - 'TMOUT': '86400', 05:26:57 INFO - 'USER': 'cltbld', 05:26:57 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:57 INFO - '_': '/tools/buildbot/bin/python'} 05:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:26:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:26:59 INFO - Installing collected packages: mozsystemmonitor 05:26:59 INFO - Running setup.py install for mozsystemmonitor 05:26:59 INFO - Successfully installed mozsystemmonitor 05:26:59 INFO - Cleaning up... 05:26:59 INFO - Return code: 0 05:26:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:59 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:26:59 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 05:26:59 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 05:26:59 INFO - Using env: {'DISPLAY': ':0', 05:26:59 INFO - 'HOME': '/home/cltbld', 05:26:59 INFO - 'LANG': 'en_US.UTF-8', 05:26:59 INFO - 'LANGUAGE': 'en_US:en', 05:26:59 INFO - 'LOGNAME': 'cltbld', 05:26:59 INFO - 'MAIL': '/var/mail/cltbld', 05:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:59 INFO - 'MOZ_NO_REMOTE': '1', 05:26:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:26:59 INFO - 'NO_EM_RESTART': '1', 05:26:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:59 INFO - 'PWD': '/builds/slave/test', 05:26:59 INFO - 'SHELL': '/bin/bash', 05:26:59 INFO - 'SHLVL': '1', 05:26:59 INFO - 'TERM': 'linux', 05:26:59 INFO - 'TMOUT': '86400', 05:26:59 INFO - 'USER': 'cltbld', 05:26:59 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:59 INFO - '_': '/tools/buildbot/bin/python'} 05:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:01 INFO - Downloading blobuploader-1.2.4.tar.gz 05:27:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:27:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:27:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:02 INFO - Downloading docopt-0.6.1.tar.gz 05:27:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:27:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:27:02 INFO - Running setup.py install for blobuploader 05:27:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:27:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:27:02 INFO - Running setup.py install for requests 05:27:03 INFO - Running setup.py install for docopt 05:27:03 INFO - Successfully installed blobuploader requests docopt 05:27:03 INFO - Cleaning up... 05:27:04 INFO - Return code: 0 05:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:04 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:27:04 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 05:27:04 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 05:27:04 INFO - Using env: {'DISPLAY': ':0', 05:27:04 INFO - 'HOME': '/home/cltbld', 05:27:04 INFO - 'LANG': 'en_US.UTF-8', 05:27:04 INFO - 'LANGUAGE': 'en_US:en', 05:27:04 INFO - 'LOGNAME': 'cltbld', 05:27:04 INFO - 'MAIL': '/var/mail/cltbld', 05:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:04 INFO - 'MOZ_NO_REMOTE': '1', 05:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:04 INFO - 'NO_EM_RESTART': '1', 05:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:04 INFO - 'PWD': '/builds/slave/test', 05:27:04 INFO - 'SHELL': '/bin/bash', 05:27:04 INFO - 'SHLVL': '1', 05:27:04 INFO - 'TERM': 'linux', 05:27:04 INFO - 'TMOUT': '86400', 05:27:04 INFO - 'USER': 'cltbld', 05:27:04 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:04 INFO - '_': '/tools/buildbot/bin/python'} 05:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:04 INFO - Running setup.py (path:/tmp/pip-92X6Fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:04 INFO - Running setup.py (path:/tmp/pip-TmtX56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:04 INFO - Running setup.py (path:/tmp/pip-n3BT7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:05 INFO - Running setup.py (path:/tmp/pip-IOYxTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:05 INFO - Running setup.py (path:/tmp/pip-cwFgpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:05 INFO - Running setup.py (path:/tmp/pip-_vhfFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:05 INFO - Running setup.py (path:/tmp/pip-m0hBdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:05 INFO - Running setup.py (path:/tmp/pip-T5zAPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:05 INFO - Running setup.py (path:/tmp/pip-Gl7MsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:05 INFO - Running setup.py (path:/tmp/pip-JesrXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:05 INFO - Running setup.py (path:/tmp/pip-Z1GfD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:05 INFO - Running setup.py (path:/tmp/pip-VuG5uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:05 INFO - Running setup.py (path:/tmp/pip-5hosJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:05 INFO - Running setup.py (path:/tmp/pip-df6Wbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:05 INFO - Running setup.py (path:/tmp/pip-pOJ_Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:05 INFO - Running setup.py (path:/tmp/pip-72oT5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:05 INFO - Running setup.py (path:/tmp/pip-OWdBi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:27:06 INFO - Running setup.py install for manifestparser 05:27:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Running setup.py install for mozcrash 05:27:06 INFO - Running setup.py install for mozdebug 05:27:06 INFO - Running setup.py install for mozdevice 05:27:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Running setup.py install for mozfile 05:27:06 INFO - Running setup.py install for mozhttpd 05:27:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Running setup.py install for mozinfo 05:27:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Running setup.py install for mozInstall 05:27:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:27:06 INFO - Running setup.py install for mozleak 05:27:07 INFO - Running setup.py install for mozlog 05:27:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Running setup.py install for moznetwork 05:27:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Running setup.py install for mozprocess 05:27:07 INFO - Running setup.py install for mozprofile 05:27:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Running setup.py install for mozrunner 05:27:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Running setup.py install for mozscreenshot 05:27:07 INFO - Running setup.py install for moztest 05:27:07 INFO - Running setup.py install for mozversion 05:27:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:27:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:27:07 INFO - Cleaning up... 05:27:07 INFO - Return code: 0 05:27:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:07 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:27:07 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 05:27:07 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 05:27:07 INFO - Using env: {'DISPLAY': ':0', 05:27:07 INFO - 'HOME': '/home/cltbld', 05:27:07 INFO - 'LANG': 'en_US.UTF-8', 05:27:07 INFO - 'LANGUAGE': 'en_US:en', 05:27:07 INFO - 'LOGNAME': 'cltbld', 05:27:07 INFO - 'MAIL': '/var/mail/cltbld', 05:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:07 INFO - 'MOZ_NO_REMOTE': '1', 05:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:07 INFO - 'NO_EM_RESTART': '1', 05:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:07 INFO - 'PWD': '/builds/slave/test', 05:27:07 INFO - 'SHELL': '/bin/bash', 05:27:07 INFO - 'SHLVL': '1', 05:27:07 INFO - 'TERM': 'linux', 05:27:07 INFO - 'TMOUT': '86400', 05:27:07 INFO - 'USER': 'cltbld', 05:27:07 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:07 INFO - '_': '/tools/buildbot/bin/python'} 05:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:27:08 INFO - Running setup.py (path:/tmp/pip-JUuY6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:27:08 INFO - Running setup.py (path:/tmp/pip-DsUg5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:27:08 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)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:27:08 INFO - Running setup.py (path:/tmp/pip-P0Aexp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:27:08 INFO - Running setup.py (path:/tmp/pip-91XRLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:27:08 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)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:27:08 INFO - Running setup.py (path:/tmp/pip-z8QXyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:27:08 INFO - Running setup.py (path:/tmp/pip-smjpw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:27:08 INFO - Running setup.py (path:/tmp/pip-tthmMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:27:08 INFO - Running setup.py (path:/tmp/pip-bt8OY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:27:08 INFO - Running setup.py (path:/tmp/pip-N0J5LB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:27:08 INFO - Running setup.py (path:/tmp/pip-XniPT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:27:08 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)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:27:08 INFO - Running setup.py (path:/tmp/pip-LD7WRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:27:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:27:08 INFO - Running setup.py (path:/tmp/pip-KJkMWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:27:09 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)) 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:27:09 INFO - Running setup.py (path:/tmp/pip-UkD4QZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:27:09 INFO - Running setup.py (path:/tmp/pip-U23Vln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:27:09 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)) 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:09 INFO - Running setup.py (path:/tmp/pip-4vcrFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:27:09 INFO - Running setup.py (path:/tmp/pip-F5ndCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:27:09 INFO - Running setup.py (path:/tmp/pip-c8kYkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:27:09 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)) 05:27:09 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)) 05:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:27:09 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)) 05:27:09 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)) 05:27:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:11 INFO - Downloading blessings-1.5.1.tar.gz 05:27:11 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 05:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:27:11 INFO - Installing collected packages: blessings 05:27:11 INFO - Running setup.py install for blessings 05:27:11 INFO - Successfully installed blessings 05:27:11 INFO - Cleaning up... 05:27:11 INFO - Return code: 0 05:27:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:27:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:11 INFO - Reading from file tmpfile_stdout 05:27:11 INFO - Current package versions: 05:27:11 INFO - argparse == 1.2.1 05:27:11 INFO - blessings == 1.5.1 05:27:11 INFO - blobuploader == 1.2.4 05:27:11 INFO - docopt == 0.6.1 05:27:11 INFO - manifestparser == 1.1 05:27:11 INFO - mozInstall == 1.12 05:27:11 INFO - mozcrash == 0.16 05:27:11 INFO - mozdebug == 0.1 05:27:11 INFO - mozdevice == 0.47 05:27:11 INFO - mozfile == 1.2 05:27:11 INFO - mozhttpd == 0.7 05:27:11 INFO - mozinfo == 0.9 05:27:11 INFO - mozleak == 0.1 05:27:11 INFO - mozlog == 3.1 05:27:11 INFO - moznetwork == 0.27 05:27:11 INFO - mozprocess == 0.22 05:27:11 INFO - mozprofile == 0.28 05:27:11 INFO - mozrunner == 6.11 05:27:11 INFO - mozscreenshot == 0.1 05:27:11 INFO - mozsystemmonitor == 0.0 05:27:11 INFO - moztest == 0.7 05:27:11 INFO - mozversion == 1.4 05:27:11 INFO - psutil == 3.1.1 05:27:11 INFO - requests == 1.2.3 05:27:11 INFO - wsgiref == 0.1.2 05:27:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:11 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:27:11 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 05:27:11 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 05:27:11 INFO - Using env: {'DISPLAY': ':0', 05:27:11 INFO - 'HOME': '/home/cltbld', 05:27:11 INFO - 'LANG': 'en_US.UTF-8', 05:27:11 INFO - 'LANGUAGE': 'en_US:en', 05:27:11 INFO - 'LOGNAME': 'cltbld', 05:27:11 INFO - 'MAIL': '/var/mail/cltbld', 05:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:11 INFO - 'MOZ_NO_REMOTE': '1', 05:27:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:11 INFO - 'NO_EM_RESTART': '1', 05:27:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:11 INFO - 'PWD': '/builds/slave/test', 05:27:11 INFO - 'SHELL': '/bin/bash', 05:27:11 INFO - 'SHLVL': '1', 05:27:11 INFO - 'TERM': 'linux', 05:27:11 INFO - 'TMOUT': '86400', 05:27:11 INFO - 'USER': 'cltbld', 05:27:11 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:11 INFO - '_': '/tools/buildbot/bin/python'} 05:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:27:11 INFO - Cleaning up... 05:27:11 INFO - Return code: 0 05:27:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:11 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:27:11 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 05:27:11 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 05:27:11 INFO - Using env: {'DISPLAY': ':0', 05:27:11 INFO - 'HOME': '/home/cltbld', 05:27:11 INFO - 'LANG': 'en_US.UTF-8', 05:27:11 INFO - 'LANGUAGE': 'en_US:en', 05:27:11 INFO - 'LOGNAME': 'cltbld', 05:27:11 INFO - 'MAIL': '/var/mail/cltbld', 05:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:11 INFO - 'MOZ_NO_REMOTE': '1', 05:27:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:11 INFO - 'NO_EM_RESTART': '1', 05:27:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:11 INFO - 'PWD': '/builds/slave/test', 05:27:11 INFO - 'SHELL': '/bin/bash', 05:27:11 INFO - 'SHLVL': '1', 05:27:11 INFO - 'TERM': 'linux', 05:27:11 INFO - 'TMOUT': '86400', 05:27:11 INFO - 'USER': 'cltbld', 05:27:11 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:11 INFO - '_': '/tools/buildbot/bin/python'} 05:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:12 INFO - Downloading/unpacking jsonschema 05:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:27:13 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 05:27:13 INFO - Downloading/unpacking functools32 (from jsonschema) 05:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:13 INFO - Downloading functools32-3.2.3-2.tar.gz 05:27:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:27:13 INFO - warning: no files found matching '*.txt' 05:27:13 INFO - no previously-included directories found matching 'build' 05:27:13 INFO - no previously-included directories found matching 'dist' 05:27:13 INFO - no previously-included directories found matching '.git*' 05:27:13 INFO - Installing collected packages: jsonschema, functools32 05:27:14 INFO - Running setup.py install for functools32 05:27:14 INFO - warning: no files found matching '*.txt' 05:27:14 INFO - no previously-included directories found matching 'build' 05:27:14 INFO - no previously-included directories found matching 'dist' 05:27:14 INFO - no previously-included directories found matching '.git*' 05:27:14 INFO - Successfully installed jsonschema functools32 05:27:14 INFO - Cleaning up... 05:27:14 INFO - Return code: 0 05:27:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:14 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 0x7f43d09f5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26fbdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, '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': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:27:14 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 05:27:14 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 05:27:14 INFO - Using env: {'DISPLAY': ':0', 05:27:14 INFO - 'HOME': '/home/cltbld', 05:27:14 INFO - 'LANG': 'en_US.UTF-8', 05:27:14 INFO - 'LANGUAGE': 'en_US:en', 05:27:14 INFO - 'LOGNAME': 'cltbld', 05:27:14 INFO - 'MAIL': '/var/mail/cltbld', 05:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:14 INFO - 'MOZ_NO_REMOTE': '1', 05:27:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:14 INFO - 'NO_EM_RESTART': '1', 05:27:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:14 INFO - 'PWD': '/builds/slave/test', 05:27:14 INFO - 'SHELL': '/bin/bash', 05:27:14 INFO - 'SHLVL': '1', 05:27:14 INFO - 'TERM': 'linux', 05:27:14 INFO - 'TMOUT': '86400', 05:27:14 INFO - 'USER': 'cltbld', 05:27:14 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:14 INFO - '_': '/tools/buildbot/bin/python'} 05:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:14 INFO - Downloading/unpacking flake8 05:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:27:16 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 05:27:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:27:16 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 05:27:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 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 05:27:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:27:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:27:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:27:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:27:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:27:16 INFO - no previously-included directories found matching 'docs/_build' 05:27:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:27:16 INFO - Running setup.py install for pep8 05:27:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:27:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:27:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:27:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:27:16 INFO - no previously-included directories found matching 'docs/_build' 05:27:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:27:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:27:16 INFO - Cleaning up... 05:27:16 INFO - Return code: 0 05:27:16 INFO - Running post-action listener: _resource_record_post_action 05:27:16 INFO - Running post-action listener: _start_resource_monitoring 05:27:16 INFO - Starting resource monitoring. 05:27:16 INFO - ##### 05:27:16 INFO - ##### Running install step. 05:27:16 INFO - ##### 05:27:16 INFO - Running pre-action listener: _resource_record_pre_action 05:27:16 INFO - Running main action method: install 05:27:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:27:17 INFO - Reading from file tmpfile_stdout 05:27:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:27:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:27:17 INFO - Reading from file tmpfile_stdout 05:27:17 INFO - Output received: 05:27:17 INFO - Usage: mozinstall [options] installer 05:27:17 INFO - Options: 05:27:17 INFO - -h, --help show this help message and exit 05:27:17 INFO - -d DEST, --destination=DEST 05:27:17 INFO - Directory to install application into. [default: 05:27:17 INFO - "/builds/slave/test"] 05:27:17 INFO - --app=APP Application being installed. [default: firefox] 05:27:17 INFO - mkdir: /builds/slave/test/build/application 05:27:17 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'] 05:27:17 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 05:27:32 INFO - Reading from file tmpfile_stdout 05:27:32 INFO - Output received: 05:27:32 INFO - /builds/slave/test/build/application/firefox/firefox 05:27:32 INFO - Running post-action listener: _resource_record_post_action 05:27:32 INFO - ##### 05:27:32 INFO - ##### Running run-tests step. 05:27:32 INFO - ##### 05:27:32 INFO - Running pre-action listener: _resource_record_pre_action 05:27:32 INFO - Running main action method: run_tests 05:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:27:32 INFO - Python 2.7.3 05:27:32 INFO - Return code: 0 05:27:32 INFO - grabbing minidump binary from tooltool 05:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:27:32 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 0x278e3d0>, '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 0x26f9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26fa360>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:27:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:27:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:27:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:27:32 INFO - Return code: 0 05:27:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:27:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:27:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:27:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:27:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:27:33 INFO - Return code: 0 05:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:27:33 INFO - Using env: {'DISPLAY': ':0', 05:27:33 INFO - 'HOME': '/home/cltbld', 05:27:33 INFO - 'LANG': 'en_US.UTF-8', 05:27:33 INFO - 'LANGUAGE': 'en_US:en', 05:27:33 INFO - 'LOGNAME': 'cltbld', 05:27:33 INFO - 'MAIL': '/var/mail/cltbld', 05:27:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:27:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:33 INFO - 'MOZ_NO_REMOTE': '1', 05:27:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:27:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:27:33 INFO - 'NO_EM_RESTART': '1', 05:27:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:33 INFO - 'PWD': '/builds/slave/test', 05:27:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:27:33 INFO - 'SHELL': '/bin/bash', 05:27:33 INFO - 'SHLVL': '1', 05:27:33 INFO - 'TERM': 'linux', 05:27:33 INFO - 'TMOUT': '86400', 05:27:33 INFO - 'USER': 'cltbld', 05:27:33 INFO - 'XDG_SESSION_COOKIE': 'e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533', 05:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:33 INFO - '_': '/tools/buildbot/bin/python'} 05:27:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:27:33 INFO - mozversion application_buildid: 20151221040829 05:27:33 INFO - mozversion application_changeset: a8acaa9868df4aa8d801725cfa9ef744640fc402 05:27:33 INFO - mozversion application_display_name: Nightly 05:27:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:27:33 INFO - mozversion application_name: Firefox 05:27:33 INFO - mozversion application_remotingname: firefox 05:27:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:27:33 INFO - mozversion application_vendor: Mozilla 05:27:33 INFO - mozversion application_version: 46.0a1 05:27:33 INFO - mozversion platform_buildid: 20151221040829 05:27:33 INFO - mozversion platform_changeset: a8acaa9868df4aa8d801725cfa9ef744640fc402 05:27:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:27:33 INFO - mozversion platform_version: 46.0a1 05:27:33 INFO - using testdate: 1450704453 05:27:33 INFO - actual date: 1450704453 05:27:33 INFO - starting webserver on 'localhost:49647' 05:27:33 INFO - SUITE-START | Running 3 tests 05:27:33 INFO - TEST-START | dromaeo_css 05:27:33 INFO - operating with platform_type : linux_ 05:27:33 INFO - Initialising browser for dromaeo_css test... 05:27:33 INFO - TEST-INFO | started process 5540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmparLA4y/profile http://localhost:49647/getInfo.html) 05:27:39 INFO - PROCESS | 5540 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:27:39 INFO - PROCESS | 5540 | __metrics Screen width/height:1600/1200 05:27:39 INFO - PROCESS | 5540 | colorDepth:24 05:27:39 INFO - PROCESS | 5540 | Browser inner width/height: 1024/697 05:27:39 INFO - PROCESS | 5540 | __metrics 05:27:39 INFO - PROCESS | 5540 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:27:39 INFO - PROCESS | 5540 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:27:40 INFO - TEST-INFO | 5540: exit 0 05:27:40 INFO - Browser initialized. 05:27:40 INFO - Running cycle 1/1 for dromaeo_css test... 05:27:40 INFO - TEST-INFO | started process 5635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmparLA4y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:27:52 INFO - PROCESS | 5635 | RSS: Main: 150188032 05:27:52 INFO - PROCESS | 5635 | 05:28:44 INFO - PROCESS | 5635 | 1450704524108 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:30:56 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49647/tests/dromaeo/cssquery-ext.html) 05:30:57 INFO - PROCESS | 5635 | RSS: Main: 245714944 05:30:57 INFO - PROCESS | 5635 | 05:33:59 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-ext.html (next: http://localhost:49647/tests/dromaeo/cssquery-jquery.html) 05:34:00 INFO - PROCESS | 5635 | RSS: Main: 224165888 05:34:00 INFO - PROCESS | 5635 | 05:37:02 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49647/tests/dromaeo/cssquery-mootools.html) 05:37:03 INFO - PROCESS | 5635 | RSS: Main: 220139520 05:37:03 INFO - PROCESS | 5635 | 05:40:05 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49647/tests/dromaeo/cssquery-prototype.html) 05:40:05 INFO - PROCESS | 5635 | RSS: Main: 207319040 05:40:05 INFO - PROCESS | 5635 | 05:43:08 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49647/tests/dromaeo/cssquery-yui.html) 05:43:08 INFO - PROCESS | 5635 | RSS: Main: 214765568 05:43:08 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | Cycle 1(1): loaded http://localhost:49647/tests/dromaeo/cssquery-yui.html (next: http://localhost:49647/tests/dromaeo/cssquery-dojo.html) 05:46:11 INFO - PROCESS | 5635 | RSS: Main: 258596864 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | __start_tp_report 05:46:11 INFO - PROCESS | 5635 | _x_x_mozilla_page_load 05:46:11 INFO - PROCESS | 5635 | _x_x_mozilla_page_load_details 05:46:11 INFO - PROCESS | 5635 | |i|pagename|runs| 05:46:11 INFO - PROCESS | 5635 | |0;dojo.html;2615.6861176658804;2710.2953232159643;2713.5658294672858;2719.021152385142;2735.193118030181;4482.663800214988;4571.108633816407;4615.7922893469795;4677.766111694419;4688;4537.90924181517;4598.264277715566;4627.305904114384;4635.58279754822;4731.432228132629;4202.915941681164;4298.849540266091;4323.437953066097;4338.783060846953;4435.62297204738;4412.073464572443;4466.441694788152;4475.820967161309;4480.820767169309;4508.97745511272;4102.056526998807;4191.413202151671;4206.221848957922;4210.347396153601;4343.630791382736;4302.182585308781;4321.503027151886;4325.783710814479;4327.891802704926;4363.040131171138;4299.742015479082;4301.139772045603;4308.849190278356;4334.631556317717;4339.349097635349;4371.890702732426;4478.708883922534;4479.4176757021705;4514.503404625489;4646.094011667727;5008.649394542347;5050.898982020339;5055.949440505585;5061.215511595708;5101.4133374662015;5074.2895994560795;5079.4412614612365;5091.770870310845;5093.337866077423;5094.847154585368;4466.1067786442845;4562.8631141065825;4567.063751930847;4569.908601827978;4686.109639168547;5103.234514822806;5147.382314122329;5153.974230128825;5155.922661160085;5199.766010529535;4931.284963680247;4947.777350019258;4950.133726597831;4950.628702847229;5007.223880298559;4830.710157390497;4949.480304567968;4964.05682920244;4968.701877887338;4975.10448119342;4917.655764096479;4940.283658869444;4946.678465899705;4970.552650261494;4973.825916092919;4923.507649235119;4924.113659541317;4928.285398617252;4932.482089380635;4967.453580106186;3950.771735946645;4115.5678653741525;4119.567445418188;4122.91754164915;4163.084121493267;3510.104923244556;3623.9094022649647;3632.854685812538;3647.854085836537;3663.157473781045;4316.266234740102;4366.323219900921;4367.803448844809;4371.912561748811;4409.603135717736;4791.880202994953;5056.9494305057215;5084.262781896604;5087.16061849798;5089.567386772092;4228.4080228768;4244.617984381421;4250;4324.308110702273;4354.368616550582;4433.357163211316;4473.150101480708;4479.193745125844;4483.955160448354;4520.547945205519;4626.791794369329;4825.469198388175;4840.7337596432535;4842.055799119138;4879.048585525788;4927.433345165352;4938;4940.431850337257;5050.292958985819;5059.215821547666;4748.62011039123;4749.192637251606;4766.118268120409;4787.234042553175;4801.447833499053;4897.608191344755;4900.215965445655;4902.264660300983;4924.655274130777;4946.1591529439365;4851.58761505276;4932.432770231326;4991.80032798684;4996.325496057985;5000.974995125001;4868.707877527359;4870.926936095891;4879.219324907997;4887.291342755351;4915.680480768739;4818.734969576707;4860.17377045896;4867.902641947212;4869.829555965524;4905.583025442877;4602.562756538124;4756.762161891961;4759.23852183649;4759.833405830779;4766.45185803637;4731.550502702238;4828.468868424541;4831.830885918976;4833.540813622701;4847.75761211931;4758.095961767253;4794.2808578713475;4795.496472870438;4801.51984801517;4844.15227335222 05:46:11 INFO - PROCESS | 5635 | |1;ext.html;509.44979422224003;518.5410911343461;521.0776924843028;524.4964833759591;536.0271107939088;27837.860810695944;28362.432751344986;28398.71601283989;37004.999999999935;37525.81237093811;41018.38472422916;41518;41523;41590.7920460398;41592;7889.684412623488;7983.640736166886;8058;8058.5567793771315;8062.113167551594;7076.7169313227405;7247.818804529903;10084.798304033915;10365.792684146312;10419.687409377693;9849.950750246238;10062.49687515625;10067.949660251725;10092.949535252314;10101;7109.289071092901;7280.453965952574;10041.447720375376;10129.898701013006;10147.847782283272;10144.644937427229;10443.895561044445;10445.373277603368;10448.32085914413;10448.79102417955;37567.436488452695;37786.81106594477;38132.42801357982;38138.80930595357;38200.61799382026;2728.7134850840575;2761.7376349246993;2773.015430006784;2785.1922942346696;3432.2484840985026;28755.315480979258;29289.56065659017;29308.120756377342;29325;29420.558691619644;3408.352413041514;3459.7924124552355;3486.686198242145;3510.350585141757;3607.4769158472136;210858;211639.53441862308;213378;213922;214288;49006.26490602644;49824.02111746614;49844.50155498471;55923.720381397834;56310.43689563052;42809;43056.78471607622;66570.33429665738;68073.65963170152;68142.65928670325;14412.927935360256;14951.551453456414;14980.700385992219;14981.176035318162;14998;10960;10986.505607247693;11006.779864402828;11012.944935275433;11014.724631884106;18962.14670339838;19048;19076.80923190778;19214;19224.134913928905;7097.89353159703;7275.854482910312;7286.453515986216;7321.231270716498;7435.851282974419;827.2720006394454;848.8302339532119;851.8637018077078;860.8364410761935;879.8196369744187;26475.205743827715;26661.86669066681;26673.33316667099;26857.597136042976;26922.5961610576;970.1414248390225;977.8728765260471;993.4635296939572;1032.2155162076876;1062.7024433158729;13913.304334783423;13943.860561394256;14627.414903403745;15114.622134446727;15137.848621513644;194304;197551;198119;198403;198482;42802.78598606987;42878.57121428808;42908;42951;42971.78514107472;1222.9143959922899;1226.3439060102803;1231.1751126745103;1236.208826351118;1243.1484433163312;26552.601710973966;27221.1833644991;27340.72659273382;27350.863245683646;27790.166295011182;36722.816385918966;37655.2468950614;40444.595554044085;41964.79017605015;42949.78525107355;188341;189735;191210;191507;192660;24573.754262457147;27942.580861287504;28276.85861570679;28372.574411383433;28414.573781392883;27718.72281277242;33327;33405.66594334025;39231;40064.7996760026;32348.514772278904;33367.49948750819;33690.32619347648;33976.32047359089;34337.82831085929;29187.41625167442;29823;29836.70163298426;30178;30220.5466917992;30209.848950755986;30320.84839575788;30428;33256.334873301916;33764.493532597524;26798.65282177831;29355.85322073376;29482.557761634023;29499.55750663699;29661.85169074141;23069.88465057664;23089.538209235387;23232.3030309091;23589.882050589636;23627.40931476727 05:46:11 INFO - PROCESS | 5635 | |2;jquery.html;298.0492229786979;299.71527049303165;307.6170168140665;309.1806712212637;311.3912301450664;12535;12934.93532532336;15577.766333504993;16062.277197526095;16221.675566488679;9963.252756043308;10173.949130254357;10177;14752.704945901103;15217.695646087099;4444.666650001254;4498.5559207250335;4547.15877562652;4548.931766023512;4550.317452382136;3439.2949445363765;3532.5760908690995;4972;5071.974640126794;5211.5830733541225;5228.607854410915;5234.607404444663;5264.92102618461;5270.947290527103;5274.841754747345;5193.792248310063;5215.843524694265;5762.567807414419;5782.190493330916;5791.8552036199435;5107.7956881724685;5112.3865136183895;5114.974425127888;5115.565176959963;5119.513646203642;11582.884171158265;15268.007579507297;18959.146838392167;19847.404577862686;19849.106790194477;1847.3072597775833;1862.9720554191697;1864.5618279704258;1871.373090014833;1884.8125879620673;9090.13643703847;11516.827247591293;12263.325517096557;12301.3234272115;12843.678908027281;1761.4187318184968;1774.5652315182733;1781.8129096444816;1783.1530023238859;1813.519417354402;49024.50975490235;50179;51914.740426298005;52629.73685131588;53607.19589206126;9510.191633711212;9626.47054412014;9644.75888102803;9646.276529260334;9694.127528522326;12175.025997920146;12189.817152742717;12195.329256890778;12199;12202.328871911952;6961.199462061826;7069.82325441868;7079.079719636517;7113.57318560888;7209;6860.931390686029;6977.092977912839;6977.476689248327;7042.190148132928;7045.506814522934;3990.1421194443333;4026.6778657707314;4029.899252518652;4032.2540330039037;4088.468499095158;1562.4531414005007;1604.1979010494751;1616.9110698911672;1620.8622267107191;1671.6238846259737;815.8939337886155;845.4969293270494;849.0829903703985;852.0286872964825;946.1626460582435;14504.709905801825;14936.850631493764;15114;15234.466793662168;15981.121038343003;768.6617888129206;772.795209269544;796.9760907172794;831.4553967151526;856.8414843254018;8326.542040187727;8501.234888860032;8750.518721470253;8774.517401542977;8836.55817209137;321528;323366;325731;327532;486750;61922;67889;73633.6318318405;81177;83390;417.571988711567;418.9078402751451;429.0239704671834;430.9806058727365;432.0279371414318;8899.911000889908;8900.376973611785;9044.186023257938;9058.909410905806;9340.112689294507;33844.492332614536;33994.490082648284;35156.296874062886;35205.47191792074;35303.470447943815;44115.55884441243;44523.55476445194;44600;44800.77599611981;45493.31760023536;8860.734177974671;8866.379353445454;8966.327525435488;8969.282457403522;9007.684731034644;11421.828672569753;16236.756448653043;16323.836761632232;16459.423920163215;16530.09084500364;10242.743931401774;13304.46782128704;13563.254021028939;13564.593062208149;13569;12957.352132393155;12979.545715899898;12995.155314904501;13180.472781088649;13709.725805484033;13118.868811311764;13167.802482962572;13842.515511957034;13942.093763905314;13992.650183745487;8384.119667435076;8582.485050896967;8619.482831030158;8623.65505379803;8636.654533818577;8810.823783524422;8856.557172141496;8875.467471951704;11661;13595.796063058864 05:46:11 INFO - PROCESS | 5635 | |3;mootools.html;1455.519678506093;1681.9747703784444;1733.0554847608053;1737.7306517489785;1744.2499725118194;6125.203723515942;7122.501424900259;7290.963545182267;7304.3426091651745;7311.341979221862;7092.680829362678;7273.054502914613;7279.454040946924;7284.7814565562985;7286.963565182166;2031.106313222185;2057.043474784225;2057.249104077016;2063.535704466498;2064.741907261592;2103.905324260404;2106.378618307603;2128.6806978953127;2175.5322605639785;2179.3353027326657;2020.959580808383;2078.1998930411823;2094.853360264782;2097.685347197925;2098.27609474731;2087.812096911278;2113.7569179544244;2121.3635909227246;2121.734783152106;2123.235635171337;2087.279888438486;2127.2129312154448;2128.840336974733;2129.7337832770904;2131.637621604331;7078.044463997346;7138.286171382872;7142.142942846892;7145.535540189923;7184.209736928934;4269.6370808481315;4327.675424343187;4339.934900976488;4343.174796788631;4345.304751239818;2792.3158826087533;2821.7742580593504;2849;2860.8712607932694;2861.899833505838;896.5786080542135;905.1943770044666;928.5542939389063;948.2745699539857;948.9762755931088;9903.405795652285;9910.157636600898;9917.355371900876;9934.9503252484;9935.254855885765;3996.660283875845;4035.6973227008093;4041.3533834586324;4090.284200264942;4153.833846646161;8505.31957443403;8509;8529.914700852913;8533.402661813738;8542.957285213533;4288.571142885687;4301.795497260772;4312.137572485452;4313.676474264443;4322.438083049184;3389.6440873707907;3442.1566716154202;3725.962740372616;3897.707671924617;3904.531456225271;3498.073010652179;3527.2416430467574;3534.6288639692975;3558.2883423314925;3560.074380660995;1114.8272017837248;1116.207492680206;1121.7644294698205;1133.665568657248;1133.9092872570175;771.1247733822153;775.5850619918317;777.0209535984587;784.8116452051466;802.2699343597299;6498.935010649928;6524.086627872101;6537.5096867734155;6538.640374779433;6540.215174179034;431.9827206911688;435.8583460375391;437.7482947305312;438.96049355558154;455.4011474910482;5234.42421333653;5260.842174734764;5265.736713164327;5269.762860671279;5279.788808447619;8087.514749115072;8135.796605084802;8286.254237118688;8502.957485212535;8778.648854045894;8312.085670576233;8335.791605209919;8360.832783344298;8364.832703346021;8385.413021088465;6887.621180835102;6950.895736563955;6995.475339349671;7051.858962820715;7052.788416347517;6710.060591517094;6781.152355955505;6789.932100679128;6793.490488213502;6799.898001529882;7827.060752709711;7954.363650908029;7991;8097.757067287991;8100.311473524816;8076.111627720828;8167.714130005421;8273.793155171168;8325.458845175044;8327.37544684139;5349.170878513836;5439.6464229826515;5599.188117722826;6316.463100636313;6403.103565500926;6335.778247761306;6625.635590042594;6659.700313485808;6662.600243985376;6672.065910772399;6249.968750156402;6343.492520598434;6352.745890164341;6374.394432528902;6450.483961282992;6111.388861113853;6165.075238714228;6210.937890621036;6268.717907694074;6277.466415354564;6405.135306733531;6405.647689377129;6425.839354016187;6441.774537891151;6448.193975753031;5800.796972105956;5839.211706419579;5843;5843.94156058434;5850.2102216200265;5856.385079566584;5910.172575839472;5925.4370834770625;5932.228810254639;5945.91081133792 05:46:11 INFO - PROCESS | 5635 | |4;prototype.html;283.72762148337597;289.5772172627963;290.39307846600616;296.04525405567034;296.12790332470877;4972.279019542164;5291.417944026154;5295.205719142131;5302.469753024696;5302.893942121155;5231.973840130803;5284.4187139414635;5287.021900948327;5293.285406470125;5296.841094767154;4092.283850326181;4113.053997580559;4121.196366708486;4123.649489793371;4128.545859955402;4123.711340206187;4126.8761937141935;4128.256913755523;4128.587141285878;4133.3386658134705;4001.8199181036925;4087.8773636790797;4091.9795401022952;4092.611201935826;4101.343784994401;4074.2869997750277;4075.3886916962397;4076;4085.8774236772942;4093.0995181060266;3551.3785087609567;3556.5554305711785;3559.1280136366754;3566.4472006838982;3574.571051473814;5094.006668699605;5109.897802043938;5114.105031619451;5117.974410127926;5122.538971492546;4365.192439398721;4672.042231342567;4676.883077923045;4679.064187162581;4681.88295292617;5216.165413533817;5318.989392015505;5338.973305133488;5372.462753724635;5506.559475242011;3831.4635950966886;3846.865359712425;3847.7306588538813;3848.5766565677754;3848.826802793881;5521.420250873641;5779.855503612402;5785.074388097884;5788.739506722165;5792.82621521355;3854.6145385461227;3878.2243551289853;3967.861124860616;3972.3445631471063;3990.501187351581;4017;4031;4033.596640335943;4033.6369726724733;4034.798260086984;4615.169269531517;4616.584507394284;4618.607418369409;4620.237660785932;4626.236670949323;5422.447030175874;5514.393416724157;5518.172274158828;5518.255035570147;5519.31008623922;1396.2041636267434;1428.707115041414;1431.2342896550297;1432.3769160415252;1432.6633241188522;949.1457688080783;965.4689920543673;966.8308046091905;970.6166064404521;978.3982850546862;4867.707937523689;4870.95129048705;4875.19559272716;4876.244182151772;4880.877978050577;5704.087346024699;5734.598578099576;5741.942580574141;5742.052561327418;5742.397048309951;4863.5379638934955;4875.975620121877;4879.902401951941;4881.658283920163;4882.5605695487575;3017.7585793136936;3070.0482850316475;3089.1350421881907;3095.7987730797427;3102.4570700127388;5432.402435732066;5432.483914028161;5438.184272359099;5440.619156658996;5454.536364409069;5885.176075349455;5892.086726557391;5895.469407753323;5902.6458412495385;5902.704864756745;5445.564354851603;5445.754941027584;5446.482584154499;5455.754491047833;5456.945430545803;5639.2668953035845;5721.342045664635;5814.680192589448;5821.79623713168;5831.650100993954;5682.37493875665;5683.5737319700365;5689.089745640677;5696.11710184922;5698.1452782083015;5519.613627046068;5522.861928451821;5524.392316845224;5526.253955715927;5526.834194973996;3687.8156092194745;3787.7348585598743;3792.9620703792607;3910.3743401055694;3913.4521167037205;5717.656940583578;5724;5726.713664316684;5736.311642602915;5761.366249712612;5522.558195344443;5528.944710552843;5533.861653458695;5534.142207957773;5535.280413546213;5435.402105768441;5533;5538.280023596906;5540.307461567304;5612.971935140298;5498.642588231752;5498.752556135064;5499.50504454601;5500.862478438071;5501.642393244426;5591;5617.662940223599;5634.070378387521;5640.492355688008;5646.153077038477;5348.812791552277;5479.369872464717;5491.093969494988;5492.780288788404;5498.037843377473 05:46:11 INFO - PROCESS | 5635 | |5;yui.html;664.4817042706691;666.300384596174;722.4725950056459;727.7234650832683;736.6611358774963;11383;11538.2500137491;11550.942245288772;11565.479553420095;11566.248193867414;11180.66458006259;11267.943660281688;11802.527898884035;11830.171887967846;11868.050555955524;637.0666972884723;645.4287955159671;788.1409263902331;799.4244144216156;808.1796976069294;802.6909639788665;806.7862016565585;806.8587997100514;813.8209593889335;814.6374863185885;794.8688466403037;799.4084377560592;800.4756884240832;800.9519428834288;801.3428988229625;670.3497607320916;677.1975209376905;834.4742812028384;847.2882778466118;849.4605925237457;624.1012941364467;626.3329554024949;626.3767551286454;627.0468887291337;627.1126356205963;11401.37292448915;11433.08535317181;11437.256578322382;11447.713807154805;11511.654650360502;10512.106470949979;10520.263581549294;10756;11002.889971100267;11011.119110471143;9097;9258.72223833286;9382.530873456368;9429.245660347156;9550.092741189575;3275.3285576457006;3275.950860737091;3280.7867488613165;3289.7203737682325;3304.2400247943106;39166.60833391658;39341.213175736324;39974.20051598981;40111.19777604461;40117.398239026435;8748.34387420946;8983.416077954913;9000.09999000108;9180.44917304964;9260.166585007382;15710.27187009428;15715.449959251371;15769.05385676863;15785.289661965238;15786.921065394828;6226.937730622726;6227.221597300338;6318.115463835067;6424.903626445607;6513.381228783258;3588.928221435609;3666.0834791302173;3684.742068055264;3740.1584643455003;3828.368319227352;2602.700689420703;2609.647697560843;2615.6599642046795;2617.607358896181;2624.238970698514;440.25816499198953;451.4807970833568;458.2873631503045;458.4132310642383;462.5212904643745;544.383181949609;574.715515819672;578.1010528628024;590.6356316907957;598.8892054969845;7242.855142897112;7246.067477079877;7332.413406927433;7366;7367.410607151415;382.1249339013683;470.74814973989436;565.6238601330069;567.1521075991419;596.3261514488601;3303.1576947878616;3333.516640087174;3356.5972083350152;3396.677315655009;3432.708219801345;48005.75997119992;48604;48635.27047094297;48684.513154868;48763.75618121958;47483.33808316692;47495.76252118717;48332.7583362088;48732;49055.50944490581;43099.35350969675;43521.34717979233;44100.55899441029;45112.42106526322;45220.3216951746;7286.271372862671;7290.270972902879;7297.525660832029;7385.667644955883;7518.135414427411;30762;30836.074917752503;31002.22494437567;31276.218094547818;32019.83990080128;35685;36181;36224.45663315105;38471.80764096162;39022.60977390303;9420.340576159877;9462.526873656427;9745.976672449575;9780.608775648894;9792.559334830092;10233.795324093626;10734.677959661223;10778.89221107779;10804.567817287221;10832.295900766425;9560.61757529691;9644.565994530123;9736.853947190939;9779.657711980331;9835.557399917165;9739.805203896025;9765.609375624896;10092.798144037224;10230.539625716712;10284.382937023802;7595.202503737028;9443.952780236055;9632;9654.823944359714;9721.173700235277;8153.714619988272;8367.163283671583;8482.872756908775;8535.8719619207;8770.166834150745;8310.875336870073;8359.916400835913;8373.49759014461;8659.177378149066;8792.956035219782 05:46:11 INFO - PROCESS | 5635 | __end_tp_report 05:46:11 INFO - PROCESS | 5635 | __start_cc_report 05:46:11 INFO - PROCESS | 5635 | _x_x_mozilla_cycle_collect,818 05:46:11 INFO - PROCESS | 5635 | __end_cc_report 05:46:11 INFO - PROCESS | 5635 | __startTimestamp1450705571605__endTimestamp 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | ------- Summary: start ------- 05:46:11 INFO - PROCESS | 5635 | Number of tests: 6 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#0] dojo.html Cycles:165 Average:4608.85 Median:4759.24 stddev:481.76 (10.1%) stddev-sans-first:457.16 05:46:11 INFO - PROCESS | 5635 | Values: 2615.7 2710.3 2713.6 2719.0 2735.2 4482.7 4571.1 4615.8 4677.8 4688.0 4537.9 4598.3 4627.3 4635.6 4731.4 4202.9 4298.8 4323.4 4338.8 4435.6 4412.1 4466.4 4475.8 4480.8 4509.0 4102.1 4191.4 4206.2 4210.3 4343.6 4302.2 4321.5 4325.8 4327.9 4363.0 4299.7 4301.1 4308.8 4334.6 4339.3 4371.9 4478.7 4479.4 4514.5 4646.1 5008.6 5050.9 5055.9 5061.2 5101.4 5074.3 5079.4 5091.8 5093.3 5094.8 4466.1 4562.9 4567.1 4569.9 4686.1 5103.2 5147.4 5154.0 5155.9 5199.8 4931.3 4947.8 4950.1 4950.6 5007.2 4830.7 4949.5 4964.1 4968.7 4975.1 4917.7 4940.3 4946.7 4970.6 4973.8 4923.5 4924.1 4928.3 4932.5 4967.5 3950.8 4115.6 4119.6 4122.9 4163.1 3510.1 3623.9 3632.9 3647.9 3663.2 4316.3 4366.3 4367.8 4371.9 4409.6 4791.9 5056.9 5084.3 5087.2 5089.6 4228.4 4244.6 4250.0 4324.3 4354.4 4433.4 4473.2 4479.2 4484.0 4520.5 4626.8 4825.5 4840.7 4842.1 4879.0 4927.4 4938.0 4940.4 5050.3 5059.2 4748.6 4749.2 4766.1 4787.2 4801.4 4897.6 4900.2 4902.3 4924.7 4946.2 4851.6 4932.4 4991.8 4996.3 5001.0 4868.7 4870.9 4879.2 4887.3 4915.7 4818.7 4860.2 4867.9 4869.8 4905.6 4602.6 4756.8 4759.2 4759.8 4766.5 4731.6 4828.5 4831.8 4833.5 4847.8 4758.1 4794.3 4795.5 4801.5 4844.2 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#1] ext.html Cycles:180 Average:36689.48 Median:29340.43 stddev:51820.69 (176.6%) stddev-sans-first:51894.84 05:46:11 INFO - PROCESS | 5635 | Values: 509.4 518.5 521.1 524.5 536.0 27837.9 28362.4 28398.7 37005.0 37525.8 41018.4 41518.0 41523.0 41590.8 41592.0 7889.7 7983.6 8058.0 8058.6 8062.1 7076.7 7247.8 10084.8 10365.8 10419.7 9850.0 10062.5 10067.9 10092.9 10101.0 7109.3 7280.5 10041.4 10129.9 10147.8 10144.6 10443.9 10445.4 10448.3 10448.8 37567.4 37786.8 38132.4 38138.8 38200.6 2728.7 2761.7 2773.0 2785.2 3432.2 28755.3 29289.6 29308.1 29325.0 29420.6 3408.4 3459.8 3486.7 3510.4 3607.5 210858.0 211639.5 213378.0 213922.0 214288.0 49006.3 49824.0 49844.5 55923.7 56310.4 42809.0 43056.8 66570.3 68073.7 68142.7 14412.9 14951.6 14980.7 14981.2 14998.0 10960.0 10986.5 11006.8 11012.9 11014.7 18962.1 19048.0 19076.8 19214.0 19224.1 7097.9 7275.9 7286.5 7321.2 7435.9 827.3 848.8 851.9 860.8 879.8 26475.2 26661.9 26673.3 26857.6 26922.6 970.1 977.9 993.5 1032.2 1062.7 13913.3 13943.9 14627.4 15114.6 15137.8 194304.0 197551.0 198119.0 198403.0 198482.0 42802.8 42878.6 42908.0 42951.0 42971.8 1222.9 1226.3 1231.2 1236.2 1243.1 26552.6 27221.2 27340.7 27350.9 27790.2 36722.8 37655.2 40444.6 41964.8 42949.8 188341.0 189735.0 191210.0 191507.0 192660.0 24573.8 27942.6 28276.9 28372.6 28414.6 27718.7 33327.0 33405.7 39231.0 40064.8 32348.5 33367.5 33690.3 33976.3 34337.8 29187.4 29823.0 29836.7 30178.0 30220.5 30209.8 30320.8 30428.0 33256.3 33764.5 26798.7 29355.9 29482.6 29499.6 29661.9 23069.9 23089.5 23232.3 23589.9 23627.4 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#2] jquery.html Cycles:180 Average:22363.21 Median:22273.79 stddev:59725.02 (268.1%) stddev-sans-first:59869.58 05:46:11 INFO - PROCESS | 5635 | Values: 298.0 299.7 307.6 309.2 311.4 12535.0 12934.9 15577.8 16062.3 16221.7 9963.3 10173.9 10177.0 14752.7 15217.7 4444.7 4498.6 4547.2 4548.9 4550.3 3439.3 3532.6 4972.0 5072.0 5211.6 5228.6 5234.6 5264.9 5270.9 5274.8 5193.8 5215.8 5762.6 5782.2 5791.9 5107.8 5112.4 5115.0 5115.6 5119.5 11582.9 15268.0 18959.1 19847.4 19849.1 1847.3 1863.0 1864.6 1871.4 1884.8 9090.1 11516.8 12263.3 12301.3 12843.7 1761.4 1774.6 1781.8 1783.2 1813.5 49024.5 50179.0 51914.7 52629.7 53607.2 9510.2 9626.5 9644.8 9646.3 9694.1 12175.0 12189.8 12195.3 12199.0 12202.3 6961.2 7069.8 7079.1 7113.6 7209.0 6860.9 6977.1 6977.5 7042.2 7045.5 3990.1 4026.7 4029.9 4032.3 4088.5 1562.5 1604.2 1616.9 1620.9 1671.6 815.9 845.5 849.1 852.0 946.2 14504.7 14936.9 15114.0 15234.5 15981.1 768.7 772.8 797.0 831.5 856.8 8326.5 8501.2 8750.5 8774.5 8836.6 321528.0 323366.0 325731.0 327532.0 486750.0 61922.0 67889.0 73633.6 81177.0 83390.0 417.6 418.9 429.0 431.0 432.0 8899.9 8900.4 9044.2 9058.9 9340.1 33844.5 33994.5 35156.3 35205.5 35303.5 44115.6 44523.6 44600.0 44800.8 45493.3 8860.7 8866.4 8966.3 8969.3 9007.7 11421.8 16236.8 16323.8 16459.4 16530.1 10242.7 13304.5 13563.3 13564.6 13569.0 12957.4 12979.5 12995.2 13180.5 13709.7 13118.9 13167.8 13842.5 13942.1 13992.7 8384.1 8582.5 8619.5 8623.7 8636.7 8810.8 8856.6 8875.5 11661.0 13595.8 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#3] mootools.html Cycles:180 Average:4991.25 Median:6188.01 stddev:2622.25 (42.4%) stddev-sans-first:2616.15 05:46:11 INFO - PROCESS | 5635 | Values: 1455.5 1682.0 1733.1 1737.7 1744.2 6125.2 7122.5 7291.0 7304.3 7311.3 7092.7 7273.1 7279.5 7284.8 7287.0 2031.1 2057.0 2057.2 2063.5 2064.7 2103.9 2106.4 2128.7 2175.5 2179.3 2021.0 2078.2 2094.9 2097.7 2098.3 2087.8 2113.8 2121.4 2121.7 2123.2 2087.3 2127.2 2128.8 2129.7 2131.6 7078.0 7138.3 7142.1 7145.5 7184.2 4269.6 4327.7 4339.9 4343.2 4345.3 2792.3 2821.8 2849.0 2860.9 2861.9 896.6 905.2 928.6 948.3 949.0 9903.4 9910.2 9917.4 9935.0 9935.3 3996.7 4035.7 4041.4 4090.3 4153.8 8505.3 8509.0 8529.9 8533.4 8543.0 4288.6 4301.8 4312.1 4313.7 4322.4 3389.6 3442.2 3726.0 3897.7 3904.5 3498.1 3527.2 3534.6 3558.3 3560.1 1114.8 1116.2 1121.8 1133.7 1133.9 771.1 775.6 777.0 784.8 802.3 6498.9 6524.1 6537.5 6538.6 6540.2 432.0 435.9 437.7 439.0 455.4 5234.4 5260.8 5265.7 5269.8 5279.8 8087.5 8135.8 8286.3 8503.0 8778.6 8312.1 8335.8 8360.8 8364.8 8385.4 6887.6 6950.9 6995.5 7051.9 7052.8 6710.1 6781.2 6789.9 6793.5 6799.9 7827.1 7954.4 7991.0 8097.8 8100.3 8076.1 8167.7 8273.8 8325.5 8327.4 5349.2 5439.6 5599.2 6316.5 6403.1 6335.8 6625.6 6659.7 6662.6 6672.1 6250.0 6343.5 6352.7 6374.4 6450.5 6111.4 6165.1 6210.9 6268.7 6277.5 6405.1 6405.6 6425.8 6441.8 6448.2 5800.8 5839.2 5843.0 5843.9 5850.2 5856.4 5910.2 5925.4 5932.2 5945.9 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#4] prototype.html Cycles:180 Average:4594.92 Median:5292.35 stddev:1354.08 (25.6%) stddev-sans-first:1318.65 05:46:11 INFO - PROCESS | 5635 | Values: 283.7 289.6 290.4 296.0 296.1 4972.3 5291.4 5295.2 5302.5 5302.9 5232.0 5284.4 5287.0 5293.3 5296.8 4092.3 4113.1 4121.2 4123.6 4128.5 4123.7 4126.9 4128.3 4128.6 4133.3 4001.8 4087.9 4092.0 4092.6 4101.3 4074.3 4075.4 4076.0 4085.9 4093.1 3551.4 3556.6 3559.1 3566.4 3574.6 5094.0 5109.9 5114.1 5118.0 5122.5 4365.2 4672.0 4676.9 4679.1 4681.9 5216.2 5319.0 5339.0 5372.5 5506.6 3831.5 3846.9 3847.7 3848.6 3848.8 5521.4 5779.9 5785.1 5788.7 5792.8 3854.6 3878.2 3967.9 3972.3 3990.5 4017.0 4031.0 4033.6 4033.6 4034.8 4615.2 4616.6 4618.6 4620.2 4626.2 5422.4 5514.4 5518.2 5518.3 5519.3 1396.2 1428.7 1431.2 1432.4 1432.7 949.1 965.5 966.8 970.6 978.4 4867.7 4871.0 4875.2 4876.2 4880.9 5704.1 5734.6 5741.9 5742.1 5742.4 4863.5 4876.0 4879.9 4881.7 4882.6 3017.8 3070.0 3089.1 3095.8 3102.5 5432.4 5432.5 5438.2 5440.6 5454.5 5885.2 5892.1 5895.5 5902.6 5902.7 5445.6 5445.8 5446.5 5455.8 5456.9 5639.3 5721.3 5814.7 5821.8 5831.7 5682.4 5683.6 5689.1 5696.1 5698.1 5519.6 5522.9 5524.4 5526.3 5526.8 3687.8 3787.7 3793.0 3910.4 3913.5 5717.7 5724.0 5726.7 5736.3 5761.4 5522.6 5528.9 5533.9 5534.1 5535.3 5435.4 5533.0 5538.3 5540.3 5613.0 5498.6 5498.8 5499.5 5500.9 5501.6 5591.0 5617.7 5634.1 5640.5 5646.2 5348.8 5479.4 5491.1 5492.8 5498.0 05:46:11 INFO - PROCESS | 5635 | 05:46:11 INFO - PROCESS | 5635 | [#5] yui.html Cycles:180 Average:12073.61 Median:555.00 stddev:14060.53 (2533.4%) stddev-sans-first:14073.87 05:46:11 INFO - PROCESS | 5635 | Values: 664.5 666.3 722.5 727.7 736.7 11383.0 11538.3 11550.9 11565.5 11566.2 11180.7 11267.9 11802.5 11830.2 11868.1 637.1 645.4 788.1 799.4 808.2 802.7 806.8 806.9 813.8 814.6 794.9 799.4 800.5 801.0 801.3 670.3 677.2 834.5 847.3 849.5 624.1 626.3 626.4 627.0 627.1 11401.4 11433.1 11437.3 11447.7 11511.7 10512.1 10520.3 10756.0 11002.9 11011.1 9097.0 9258.7 9382.5 9429.2 9550.1 3275.3 3276.0 3280.8 3289.7 3304.2 39166.6 39341.2 39974.2 40111.2 40117.4 8748.3 8983.4 9000.1 9180.4 9260.2 15710.3 15715.4 15769.1 15785.3 15786.9 6226.9 6227.2 6318.1 6424.9 6513.4 3588.9 3666.1 3684.7 3740.2 3828.4 2602.7 2609.6 2615.7 2617.6 2624.2 440.3 451.5 458.3 458.4 462.5 544.4 574.7 578.1 590.6 598.9 7242.9 7246.1 7332.4 7366.0 7367.4 382.1 470.7 565.6 567.2 596.3 3303.2 3333.5 3356.6 3396.7 3432.7 48005.8 48604.0 48635.3 48684.5 48763.8 47483.3 47495.8 48332.8 48732.0 49055.5 43099.4 43521.3 44100.6 45112.4 45220.3 7286.3 7290.3 7297.5 7385.7 7518.1 30762.0 30836.1 31002.2 31276.2 32019.8 35685.0 36181.0 36224.5 38471.8 39022.6 9420.3 9462.5 9746.0 9780.6 9792.6 10233.8 10734.7 10778.9 10804.6 10832.3 9560.6 9644.6 9736.9 9779.7 9835.6 9739.8 9765.6 10092.8 10230.5 10284.4 7595.2 9444.0 9632.0 9654.8 9721.2 8153.7 8367.2 8482.9 8535.9 8770.2 8310.9 8359.9 8373.5 8659.2 8793.0 05:46:11 INFO - PROCESS | 5635 | -------- Summary: end -------- 05:46:11 INFO - PROCESS | 5635 | 05:46:12 INFO - TEST-INFO | 5635: exit 0 05:46:12 INFO - TEST-OK | dromaeo_css | took 1118466ms 05:46:12 INFO - TEST-START | kraken 05:46:12 INFO - operating with platform_type : linux_ 05:46:12 INFO - Initialising browser for kraken test... 05:46:12 INFO - TEST-INFO | started process 5826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Ru3X1/profile http://localhost:49647/getInfo.html) 05:46:17 INFO - PROCESS | 5826 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:46:17 INFO - PROCESS | 5826 | __metrics Screen width/height:1600/1200 05:46:17 INFO - PROCESS | 5826 | colorDepth:24 05:46:17 INFO - PROCESS | 5826 | Browser inner width/height: 1024/697 05:46:17 INFO - PROCESS | 5826 | __metrics 05:46:17 INFO - PROCESS | 5826 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:46:17 INFO - PROCESS | 5826 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:46:17 ERROR - PROCESS | 5826 | JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 05:46:18 INFO - TEST-INFO | 5826: exit 0 05:46:18 INFO - Browser initialized. 05:46:18 INFO - Running cycle 1/1 for kraken test... 05:46:18 INFO - TEST-INFO | started process 5909 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Ru3X1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:30 INFO - PROCESS | 5909 | RSS: Main: 149975040 05:46:30 INFO - PROCESS | 5909 | 05:46:33 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49647/tests/kraken/driver.html?testName=audio-beat-detection) 05:46:33 INFO - PROCESS | 5909 | RSS: Main: 186634240 05:46:33 INFO - PROCESS | 5909 | 05:46:36 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49647/tests/kraken/driver.html?testName=audio-dft) 05:46:37 INFO - PROCESS | 5909 | RSS: Main: 175534080 05:46:37 INFO - PROCESS | 5909 | 05:46:40 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49647/tests/kraken/driver.html?testName=audio-fft) 05:46:40 INFO - PROCESS | 5909 | RSS: Main: 160579584 05:46:40 INFO - PROCESS | 5909 | 05:46:42 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49647/tests/kraken/driver.html?testName=audio-oscillator) 05:46:43 INFO - PROCESS | 5909 | RSS: Main: 163577856 05:46:43 INFO - PROCESS | 5909 | 05:46:45 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49647/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:46:46 INFO - PROCESS | 5909 | RSS: Main: 165351424 05:46:46 INFO - PROCESS | 5909 | 05:46:49 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49647/tests/kraken/driver.html?testName=imaging-darkroom) 05:46:49 INFO - PROCESS | 5909 | RSS: Main: 164061184 05:46:49 INFO - PROCESS | 5909 | 05:46:53 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49647/tests/kraken/driver.html?testName=imaging-desaturate) 05:46:53 INFO - PROCESS | 5909 | RSS: Main: 167378944 05:46:53 INFO - PROCESS | 5909 | 05:46:56 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49647/tests/kraken/driver.html?testName=json-parse-financial) 05:46:57 INFO - PROCESS | 5909 | RSS: Main: 167112704 05:46:57 INFO - PROCESS | 5909 | 05:46:58 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49647/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:46:59 INFO - PROCESS | 5909 | RSS: Main: 174481408 05:46:59 INFO - PROCESS | 5909 | 05:47:01 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:47:01 INFO - PROCESS | 5909 | RSS: Main: 173383680 05:47:01 INFO - PROCESS | 5909 | 05:47:04 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:47:04 INFO - PROCESS | 5909 | RSS: Main: 182145024 05:47:04 INFO - PROCESS | 5909 | 05:47:07 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:47:07 INFO - PROCESS | 5909 | RSS: Main: 188895232 05:47:07 INFO - PROCESS | 5909 | 05:47:10 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:47:10 INFO - PROCESS | 5909 | RSS: Main: 186818560 05:47:10 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | Cycle 1(1): loaded http://localhost:49647/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49647/tests/kraken/driver.html?testName=ai-astar) 05:47:12 INFO - PROCESS | 5909 | RSS: Main: 244174848 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | __start_tp_report 05:47:12 INFO - PROCESS | 5909 | _x_x_mozilla_page_load 05:47:12 INFO - PROCESS | 5909 | _x_x_mozilla_page_load_details 05:47:12 INFO - PROCESS | 5909 | |i|pagename|runs| 05:47:12 INFO - PROCESS | 5909 | |0;ai-astar;110;112;106;112;113;112;100;113;111;118 05:47:12 INFO - PROCESS | 5909 | |1;audio-beat-detection;148;128;154;170;133;150;159;129;170;130 05:47:12 INFO - PROCESS | 5909 | |2;audio-dft;161;160;160;159;161;161;162;160;163;161 05:47:12 INFO - PROCESS | 5909 | |3;audio-fft;72;71;87;90;72;75;72;101;86;71 05:47:12 INFO - PROCESS | 5909 | |4;audio-oscillator;95;95;127;95;94;94;137;95;95;129 05:47:12 INFO - PROCESS | 5909 | |5;imaging-gaussian-blur;109;110;110;110;110;110;109;110;109;110 05:47:12 INFO - PROCESS | 5909 | |6;imaging-darkroom;146;147;147;146;147;147;147;145;146;146 05:47:12 INFO - PROCESS | 5909 | |7;imaging-desaturate;96;95;96;95;95;95;95;96;95;95 05:47:12 INFO - PROCESS | 5909 | |8;json-parse-financial;70;72;71;70;70;70;71;80;69;70 05:47:12 INFO - PROCESS | 5909 | |9;json-stringify-tinderbox;67;68;68;67;68;69;69;68;68;67 05:47:12 INFO - PROCESS | 5909 | |10;stanford-crypto-aes;84;84;83;82;82;82;83;82;84;82 05:47:12 INFO - PROCESS | 5909 | |11;stanford-crypto-ccm;119;123;121;124;130;123;136;124;124;126 05:47:12 INFO - PROCESS | 5909 | |12;stanford-crypto-pbkdf2;140;140;146;148;146;134;144;135;144;135 05:47:12 INFO - PROCESS | 5909 | |13;stanford-crypto-sha256-iterative;72;68;70;73;70;69;70;68;68;68 05:47:12 INFO - PROCESS | 5909 | __end_tp_report 05:47:12 INFO - PROCESS | 5909 | __start_cc_report 05:47:12 INFO - PROCESS | 5909 | _x_x_mozilla_cycle_collect,989 05:47:12 INFO - PROCESS | 5909 | __end_cc_report 05:47:12 INFO - PROCESS | 5909 | __startTimestamp1450705632934__endTimestamp 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | ------- Summary: start ------- 05:47:12 INFO - PROCESS | 5909 | Number of tests: 14 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:4.79 (4.3%) stddev-sans-first:5.07 05:47:12 INFO - PROCESS | 5909 | Values: 110.0 112.0 106.0 112.0 113.0 112.0 100.0 113.0 111.0 118.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:152.00 stddev:16.43 (10.8%) stddev-sans-first:17.43 05:47:12 INFO - PROCESS | 5909 | Values: 148.0 128.0 154.0 170.0 133.0 150.0 159.0 129.0 170.0 130.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 05:47:12 INFO - PROCESS | 5909 | Values: 161.0 160.0 160.0 159.0 161.0 161.0 162.0 160.0 163.0 161.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#3] audio-fft Cycles:10 Average:79.70 Median:73.50 stddev:10.56 (14.4%) stddev-sans-first:10.83 05:47:12 INFO - PROCESS | 5909 | Values: 72.0 71.0 87.0 90.0 72.0 75.0 72.0 101.0 86.0 71.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:95.00 stddev:17.71 (18.6%) stddev-sans-first:18.36 05:47:12 INFO - PROCESS | 5909 | Values: 95.0 95.0 127.0 95.0 94.0 94.0 137.0 95.0 95.0 129.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 05:47:12 INFO - PROCESS | 5909 | Values: 109.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 109.0 110.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 05:47:12 INFO - PROCESS | 5909 | Values: 146.0 147.0 147.0 146.0 147.0 147.0 147.0 145.0 146.0 146.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:47:12 INFO - PROCESS | 5909 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#8] json-parse-financial Cycles:10 Average:71.30 Median:70.50 stddev:3.16 (4.5%) stddev-sans-first:3.32 05:47:12 INFO - PROCESS | 5909 | Values: 70.0 72.0 71.0 70.0 70.0 70.0 71.0 80.0 69.0 70.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 05:47:12 INFO - PROCESS | 5909 | Values: 67.0 68.0 68.0 67.0 68.0 69.0 69.0 68.0 68.0 67.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:0.92 (1.1%) stddev-sans-first:0.87 05:47:12 INFO - PROCESS | 5909 | Values: 84.0 84.0 83.0 82.0 82.0 82.0 83.0 82.0 84.0 82.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:124.00 stddev:4.83 (3.9%) stddev-sans-first:4.61 05:47:12 INFO - PROCESS | 5909 | Values: 119.0 123.0 121.0 124.0 130.0 123.0 136.0 124.0 124.0 126.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.20 Median:144.00 stddev:5.16 (3.6%) stddev-sans-first:5.45 05:47:12 INFO - PROCESS | 5909 | Values: 140.0 140.0 146.0 148.0 146.0 134.0 144.0 135.0 144.0 135.0 05:47:12 INFO - PROCESS | 5909 | 05:47:12 INFO - PROCESS | 5909 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.78 (2.5%) stddev-sans-first:1.66 05:47:12 INFO - PROCESS | 5909 | Values: 72.0 68.0 70.0 73.0 70.0 69.0 70.0 68.0 68.0 68.0 05:47:12 INFO - PROCESS | 5909 | -------- Summary: end -------- 05:47:12 INFO - PROCESS | 5909 | 05:47:13 INFO - TEST-INFO | 5909: exit 0 05:47:13 INFO - TEST-OK | kraken | took 61495ms 05:47:13 INFO - TEST-START | v8_7 05:47:13 INFO - operating with platform_type : linux_ 05:47:13 INFO - Initialising browser for v8_7 test... 05:47:13 INFO - TEST-INFO | started process 6159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVUt877/profile http://localhost:49647/getInfo.html) 05:47:18 INFO - PROCESS | 6159 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 05:47:18 INFO - PROCESS | 6159 | __metrics Screen width/height:1600/1200 05:47:18 INFO - PROCESS | 6159 | colorDepth:24 05:47:18 INFO - PROCESS | 6159 | Browser inner width/height: 1024/697 05:47:18 INFO - PROCESS | 6159 | __metrics 05:47:19 INFO - PROCESS | 6159 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:47:19 INFO - PROCESS | 6159 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:47:19 ERROR - PROCESS | 6159 | JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 05:47:20 INFO - TEST-INFO | 6159: exit 0 05:47:20 INFO - Browser initialized. 05:47:20 INFO - Running cycle 1/1 for v8_7 test... 05:47:20 INFO - TEST-INFO | started process 6238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVUt877/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:32 INFO - PROCESS | 6238 | RSS: Main: 149786624 05:47:32 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | Cycle 1(1): loaded http://localhost:49647/tests/v8_7/run.html (next: http://localhost:49647/tests/v8_7/run.html) 05:47:53 INFO - PROCESS | 6238 | RSS: Main: 450404352 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | __start_tp_report 05:47:53 INFO - PROCESS | 6238 | _x_x_mozilla_page_load 05:47:53 INFO - PROCESS | 6238 | _x_x_mozilla_page_load_details 05:47:53 INFO - PROCESS | 6238 | |i|pagename|runs| 05:47:53 INFO - PROCESS | 6238 | |0;Richards;165.80998176090202 05:47:53 INFO - PROCESS | 6238 | |1;DeltaBlue;189.28639030853682 05:47:53 INFO - PROCESS | 6238 | |2;Encrypt;277.623542476402 05:47:53 INFO - PROCESS | 6238 | |3;Decrypt;5181.347150259067 05:47:53 INFO - PROCESS | 6238 | |4;RayTrace;1960.7843137254902 05:47:53 INFO - PROCESS | 6238 | |5;Earley;794.2811755361398 05:47:53 INFO - PROCESS | 6238 | |6;Boyer;12268.292682926829 05:47:53 INFO - PROCESS | 6238 | |7;RegExp;27324.324324324323 05:47:53 INFO - PROCESS | 6238 | |8;Splay;607.5334143377886 05:47:53 INFO - PROCESS | 6238 | |9;NavierStokes;4830.917874396136 05:47:53 INFO - PROCESS | 6238 | __end_tp_report 05:47:53 INFO - PROCESS | 6238 | __startTimestamp1450705673838__endTimestamp 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | ------- Summary: start ------- 05:47:53 INFO - PROCESS | 6238 | Number of tests: 10 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#0] Richards Cycles:1 Average:165.81 Median:165.81 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 165.8 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#1] DeltaBlue Cycles:1 Average:189.29 Median:189.29 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 189.3 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#2] Encrypt Cycles:1 Average:277.62 Median:277.62 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 277.6 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 5181.3 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#4] RayTrace Cycles:1 Average:1960.78 Median:1960.78 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 1960.8 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#5] Earley Cycles:1 Average:794.28 Median:794.28 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 794.3 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 12268.3 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#7] RegExp Cycles:1 Average:27324.32 Median:27324.32 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 27324.3 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#8] Splay Cycles:1 Average:607.53 Median:607.53 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 607.5 05:47:53 INFO - PROCESS | 6238 | 05:47:53 INFO - PROCESS | 6238 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:47:53 INFO - PROCESS | 6238 | Values: 4830.9 05:47:53 INFO - PROCESS | 6238 | -------- Summary: end -------- 05:47:53 INFO - PROCESS | 6238 | 05:47:54 INFO - TEST-INFO | 6238: exit 0 05:47:54 INFO - TEST-OK | v8_7 | took 40860ms 05:47:54 INFO - SUITE-END | took 1220s 05:47:54 INFO - Completed test suite (00:20:20) 05:47:54 INFO - Working with test: dromaeo_css 05:47:54 INFO - Generating results file: dromaeo_css 05:47:54 INFO - Working with test: kraken 05:47:54 INFO - Generating results file: kraken 05:47:54 INFO - javascript benchmark 05:47:54 INFO - Working with test: v8_7 05:47:54 INFO - Generating results file: v8_7 05:47:54 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:47:54 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 05:47:54 INFO - process_Request line: dromaeo_css 6157.58 graph.html#tests=[[72,94,35]] 05:47:54 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:47:54 INFO - process_Request line: kraken 1513.10 graph.html#tests=[[232,94,35]] 05:47:54 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:47:55 INFO - process_Request line: v8_7 19744.95 graph.html#tests=[[230,94,35]] 05:47:55 INFO - RETURN: dromaeo_css: 6157.58 05:47:55 INFO - RETURN: kraken: 1513.10 05:47:55 INFO - RETURN: v8_7: 19744.95 05:47:55 INFO - javascript benchmark 05:47:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2615.6861176658804, 2710.2953232159643, 2713.5658294672858, 2719.021152385142, 2735.193118030181, 4482.663800214988, 4571.108633816407, 4615.7922893469795, 4677.766111694419, 4688.0, 4537.90924181517, 4598.264277715566, 4627.305904114384, 4635.58279754822, 4731.432228132629, 4202.915941681164, 4298.849540266091, 4323.437953066097, 4338.783060846953, 4435.62297204738, 4412.073464572443, 4466.441694788152, 4475.820967161309, 4480.820767169309, 4508.97745511272, 4102.056526998807, 4191.413202151671, 4206.221848957922, 4210.347396153601, 4343.630791382736, 4302.182585308781, 4321.503027151886, 4325.783710814479, 4327.891802704926, 4363.040131171138, 4299.742015479082, 4301.139772045603, 4308.849190278356, 4334.631556317717, 4339.349097635349, 4371.890702732426, 4478.708883922534, 4479.4176757021705, 4514.503404625489, 4646.094011667727, 5008.649394542347, 5050.898982020339, 5055.949440505585, 5061.215511595708, 5101.4133374662015, 5074.2895994560795, 5079.4412614612365, 5091.770870310845, 5093.337866077423, 5094.847154585368, 4466.1067786442845, 4562.8631141065825, 4567.063751930847, 4569.908601827978, 4686.109639168547, 5103.234514822806, 5147.382314122329, 5153.974230128825, 5155.922661160085, 5199.766010529535, 4931.284963680247, 4947.777350019258, 4950.133726597831, 4950.628702847229, 5007.223880298559, 4830.710157390497, 4949.480304567968, 4964.05682920244, 4968.701877887338, 4975.10448119342, 4917.655764096479, 4940.283658869444, 4946.678465899705, 4970.552650261494, 4973.825916092919, 4923.507649235119, 4924.113659541317, 4928.285398617252, 4932.482089380635, 4967.453580106186, 3950.771735946645, 4115.5678653741525, 4119.567445418188, 4122.91754164915, 4163.084121493267, 3510.104923244556, 3623.9094022649647, 3632.854685812538, 3647.854085836537, 3663.157473781045, 4316.266234740102, 4366.323219900921, 4367.803448844809, 4371.912561748811, 4409.603135717736, 4791.880202994953, 5056.9494305057215, 5084.262781896604, 5087.16061849798, 5089.567386772092, 4228.4080228768, 4244.617984381421, 4250.0, 4324.308110702273, 4354.368616550582, 4433.357163211316, 4473.150101480708, 4479.193745125844, 4483.955160448354, 4520.547945205519, 4626.791794369329, 4825.469198388175, 4840.7337596432535, 4842.055799119138, 4879.048585525788, 4927.433345165352, 4938.0, 4940.431850337257, 5050.292958985819, 5059.215821547666, 4748.62011039123, 4749.192637251606, 4766.118268120409, 4787.234042553175, 4801.447833499053, 4897.608191344755, 4900.215965445655, 4902.264660300983, 4924.655274130777, 4946.1591529439365, 4851.58761505276, 4932.432770231326, 4991.80032798684, 4996.325496057985, 5000.974995125001, 4868.707877527359, 4870.926936095891, 4879.219324907997, 4887.291342755351, 4915.680480768739, 4818.734969576707, 4860.17377045896, 4867.902641947212, 4869.829555965524, 4905.583025442877, 4602.562756538124, 4756.762161891961, 4759.23852183649, 4759.833405830779, 4766.45185803637, 4731.550502702238, 4828.468868424541, 4831.830885918976, 4833.540813622701, 4847.75761211931, 4758.095961767253, 4794.2808578713475, 4795.496472870438, 4801.51984801517, 4844.15227335222], "unit": "score", "name": "dojo.html", "value": 4578.987853678763}, {"lowerIsBetter": false, "replicates": [509.44979422224003, 518.5410911343461, 521.0776924843028, 524.4964833759591, 536.0271107939088, 27837.860810695944, 28362.432751344986, 28398.71601283989, 37004.999999999935, 37525.81237093811, 41018.38472422916, 41518.0, 41523.0, 41590.7920460398, 41592.0, 7889.684412623488, 7983.640736166886, 8058.0, 8058.5567793771315, 8062.113167551594, 7076.7169313227405, 7247.818804529903, 10084.798304033915, 10365.792684146312, 10419.687409377693, 9849.950750246238, 10062.49687515625, 10067.949660251725, 10092.949535252314, 10101.0, 7109.289071092901, 7280.453965952574, 10041.447720375376, 10129.898701013006, 10147.847782283272, 10144.644937427229, 10443.895561044445, 10445.373277603368, 10448.32085914413, 10448.79102417955, 37567.436488452695, 37786.81106594477, 38132.42801357982, 38138.80930595357, 38200.61799382026, 2728.7134850840575, 2761.7376349246993, 2773.015430006784, 2785.1922942346696, 3432.2484840985026, 28755.315480979258, 29289.56065659017, 29308.120756377342, 29325.0, 29420.558691619644, 3408.352413041514, 3459.7924124552355, 3486.686198242145, 3510.350585141757, 3607.4769158472136, 210858.0, 211639.53441862308, 213378.0, 213922.0, 214288.0, 49006.26490602644, 49824.02111746614, 49844.50155498471, 55923.720381397834, 56310.43689563052, 42809.0, 43056.78471607622, 66570.33429665738, 68073.65963170152, 68142.65928670325, 14412.927935360256, 14951.551453456414, 14980.700385992219, 14981.176035318162, 14998.0, 10960.0, 10986.505607247693, 11006.779864402828, 11012.944935275433, 11014.724631884106, 18962.14670339838, 19048.0, 19076.80923190778, 19214.0, 19224.134913928905, 7097.89353159703, 7275.854482910312, 7286.453515986216, 7321.231270716498, 7435.851282974419, 827.2720006394454, 848.8302339532119, 851.8637018077078, 860.8364410761935, 879.8196369744187, 26475.205743827715, 26661.86669066681, 26673.33316667099, 26857.597136042976, 26922.5961610576, 970.1414248390225, 977.8728765260471, 993.4635296939572, 1032.2155162076876, 1062.7024433158729, 13913.304334783423, 13943.860561394256, 14627.414903403745, 15114.622134446727, 15137.848621513644, 194304.0, 197551.0, 198119.0, 198403.0, 198482.0, 42802.78598606987, 42878.57121428808, 42908.0, 42951.0, 42971.78514107472, 1222.9143959922899, 1226.3439060102803, 1231.1751126745103, 1236.208826351118, 1243.1484433163312, 26552.601710973966, 27221.1833644991, 27340.72659273382, 27350.863245683646, 27790.166295011182, 36722.816385918966, 37655.2468950614, 40444.595554044085, 41964.79017605015, 42949.78525107355, 188341.0, 189735.0, 191210.0, 191507.0, 192660.0, 24573.754262457147, 27942.580861287504, 28276.85861570679, 28372.574411383433, 28414.573781392883, 27718.72281277242, 33327.0, 33405.66594334025, 39231.0, 40064.7996760026, 32348.514772278904, 33367.49948750819, 33690.32619347648, 33976.32047359089, 34337.82831085929, 29187.41625167442, 29823.0, 29836.70163298426, 30178.0, 30220.5466917992, 30209.848950755986, 30320.84839575788, 30428.0, 33256.334873301916, 33764.493532597524, 26798.65282177831, 29355.85322073376, 29482.557761634023, 29499.55750663699, 29661.85169074141, 23069.88465057664, 23089.538209235387, 23232.3030309091, 23589.882050589636, 23627.40931476727], "unit": "score", "name": "ext.html", "value": 16737.815949522832}, {"lowerIsBetter": false, "replicates": [298.0492229786979, 299.71527049303165, 307.6170168140665, 309.1806712212637, 311.3912301450664, 12535.0, 12934.93532532336, 15577.766333504993, 16062.277197526095, 16221.675566488679, 9963.252756043308, 10173.949130254357, 10177.0, 14752.704945901103, 15217.695646087099, 4444.666650001254, 4498.5559207250335, 4547.15877562652, 4548.931766023512, 4550.317452382136, 3439.2949445363765, 3532.5760908690995, 4972.0, 5071.974640126794, 5211.5830733541225, 5228.607854410915, 5234.607404444663, 5264.92102618461, 5270.947290527103, 5274.841754747345, 5193.792248310063, 5215.843524694265, 5762.567807414419, 5782.190493330916, 5791.8552036199435, 5107.7956881724685, 5112.3865136183895, 5114.974425127888, 5115.565176959963, 5119.513646203642, 11582.884171158265, 15268.007579507297, 18959.146838392167, 19847.404577862686, 19849.106790194477, 1847.3072597775833, 1862.9720554191697, 1864.5618279704258, 1871.373090014833, 1884.8125879620673, 9090.13643703847, 11516.827247591293, 12263.325517096557, 12301.3234272115, 12843.678908027281, 1761.4187318184968, 1774.5652315182733, 1781.8129096444816, 1783.1530023238859, 1813.519417354402, 49024.50975490235, 50179.0, 51914.740426298005, 52629.73685131588, 53607.19589206126, 9510.191633711212, 9626.47054412014, 9644.75888102803, 9646.276529260334, 9694.127528522326, 12175.025997920146, 12189.817152742717, 12195.329256890778, 12199.0, 12202.328871911952, 6961.199462061826, 7069.82325441868, 7079.079719636517, 7113.57318560888, 7209.0, 6860.931390686029, 6977.092977912839, 6977.476689248327, 7042.190148132928, 7045.506814522934, 3990.1421194443333, 4026.6778657707314, 4029.899252518652, 4032.2540330039037, 4088.468499095158, 1562.4531414005007, 1604.1979010494751, 1616.9110698911672, 1620.8622267107191, 1671.6238846259737, 815.8939337886155, 845.4969293270494, 849.0829903703985, 852.0286872964825, 946.1626460582435, 14504.709905801825, 14936.850631493764, 15114.0, 15234.466793662168, 15981.121038343003, 768.6617888129206, 772.795209269544, 796.9760907172794, 831.4553967151526, 856.8414843254018, 8326.542040187727, 8501.234888860032, 8750.518721470253, 8774.517401542977, 8836.55817209137, 321528.0, 323366.0, 325731.0, 327532.0, 486750.0, 61922.0, 67889.0, 73633.6318318405, 81177.0, 83390.0, 417.571988711567, 418.9078402751451, 429.0239704671834, 430.9806058727365, 432.0279371414318, 8899.911000889908, 8900.376973611785, 9044.186023257938, 9058.909410905806, 9340.112689294507, 33844.492332614536, 33994.490082648284, 35156.296874062886, 35205.47191792074, 35303.470447943815, 44115.55884441243, 44523.55476445194, 44600.0, 44800.77599611981, 45493.31760023536, 8860.734177974671, 8866.379353445454, 8966.327525435488, 8969.282457403522, 9007.684731034644, 11421.828672569753, 16236.756448653043, 16323.836761632232, 16459.423920163215, 16530.09084500364, 10242.743931401774, 13304.46782128704, 13563.254021028939, 13564.593062208149, 13569.0, 12957.352132393155, 12979.545715899898, 12995.155314904501, 13180.472781088649, 13709.725805484033, 13118.868811311764, 13167.802482962572, 13842.515511957034, 13942.093763905314, 13992.650183745487, 8384.119667435076, 8582.485050896967, 8619.482831030158, 8623.65505379803, 8636.654533818577, 8810.823783524422, 8856.557172141496, 8875.467471951704, 11661.0, 13595.796063058864], "unit": "score", "name": "jquery.html", "value": 7705.171161299444}, {"lowerIsBetter": false, "replicates": [1455.519678506093, 1681.9747703784444, 1733.0554847608053, 1737.7306517489785, 1744.2499725118194, 6125.203723515942, 7122.501424900259, 7290.963545182267, 7304.3426091651745, 7311.341979221862, 7092.680829362678, 7273.054502914613, 7279.454040946924, 7284.7814565562985, 7286.963565182166, 2031.106313222185, 2057.043474784225, 2057.249104077016, 2063.535704466498, 2064.741907261592, 2103.905324260404, 2106.378618307603, 2128.6806978953127, 2175.5322605639785, 2179.3353027326657, 2020.959580808383, 2078.1998930411823, 2094.853360264782, 2097.685347197925, 2098.27609474731, 2087.812096911278, 2113.7569179544244, 2121.3635909227246, 2121.734783152106, 2123.235635171337, 2087.279888438486, 2127.2129312154448, 2128.840336974733, 2129.7337832770904, 2131.637621604331, 7078.044463997346, 7138.286171382872, 7142.142942846892, 7145.535540189923, 7184.209736928934, 4269.6370808481315, 4327.675424343187, 4339.934900976488, 4343.174796788631, 4345.304751239818, 2792.3158826087533, 2821.7742580593504, 2849.0, 2860.8712607932694, 2861.899833505838, 896.5786080542135, 905.1943770044666, 928.5542939389063, 948.2745699539857, 948.9762755931088, 9903.405795652285, 9910.157636600898, 9917.355371900876, 9934.9503252484, 9935.254855885765, 3996.660283875845, 4035.6973227008093, 4041.3533834586324, 4090.284200264942, 4153.833846646161, 8505.31957443403, 8509.0, 8529.914700852913, 8533.402661813738, 8542.957285213533, 4288.571142885687, 4301.795497260772, 4312.137572485452, 4313.676474264443, 4322.438083049184, 3389.6440873707907, 3442.1566716154202, 3725.962740372616, 3897.707671924617, 3904.531456225271, 3498.073010652179, 3527.2416430467574, 3534.6288639692975, 3558.2883423314925, 3560.074380660995, 1114.8272017837248, 1116.207492680206, 1121.7644294698205, 1133.665568657248, 1133.9092872570175, 771.1247733822153, 775.5850619918317, 777.0209535984587, 784.8116452051466, 802.2699343597299, 6498.935010649928, 6524.086627872101, 6537.5096867734155, 6538.640374779433, 6540.215174179034, 431.9827206911688, 435.8583460375391, 437.7482947305312, 438.96049355558154, 455.4011474910482, 5234.42421333653, 5260.842174734764, 5265.736713164327, 5269.762860671279, 5279.788808447619, 8087.514749115072, 8135.796605084802, 8286.254237118688, 8502.957485212535, 8778.648854045894, 8312.085670576233, 8335.791605209919, 8360.832783344298, 8364.832703346021, 8385.413021088465, 6887.621180835102, 6950.895736563955, 6995.475339349671, 7051.858962820715, 7052.788416347517, 6710.060591517094, 6781.152355955505, 6789.932100679128, 6793.490488213502, 6799.898001529882, 7827.060752709711, 7954.363650908029, 7991.0, 8097.757067287991, 8100.311473524816, 8076.111627720828, 8167.714130005421, 8273.793155171168, 8325.458845175044, 8327.37544684139, 5349.170878513836, 5439.6464229826515, 5599.188117722826, 6316.463100636313, 6403.103565500926, 6335.778247761306, 6625.635590042594, 6659.700313485808, 6662.600243985376, 6672.065910772399, 6249.968750156402, 6343.492520598434, 6352.745890164341, 6374.394432528902, 6450.483961282992, 6111.388861113853, 6165.075238714228, 6210.937890621036, 6268.717907694074, 6277.466415354564, 6405.135306733531, 6405.647689377129, 6425.839354016187, 6441.774537891151, 6448.193975753031, 5800.796972105956, 5839.211706419579, 5843.0, 5843.94156058434, 5850.2102216200265, 5856.385079566584, 5910.172575839472, 5925.4370834770625, 5932.228810254639, 5945.91081133792], "unit": "score", "name": "mootools.html", "value": 4012.501171078539}, {"lowerIsBetter": false, "replicates": [283.72762148337597, 289.5772172627963, 290.39307846600616, 296.04525405567034, 296.12790332470877, 4972.279019542164, 5291.417944026154, 5295.205719142131, 5302.469753024696, 5302.893942121155, 5231.973840130803, 5284.4187139414635, 5287.021900948327, 5293.285406470125, 5296.841094767154, 4092.283850326181, 4113.053997580559, 4121.196366708486, 4123.649489793371, 4128.545859955402, 4123.711340206187, 4126.8761937141935, 4128.256913755523, 4128.587141285878, 4133.3386658134705, 4001.8199181036925, 4087.8773636790797, 4091.9795401022952, 4092.611201935826, 4101.343784994401, 4074.2869997750277, 4075.3886916962397, 4076.0, 4085.8774236772942, 4093.0995181060266, 3551.3785087609567, 3556.5554305711785, 3559.1280136366754, 3566.4472006838982, 3574.571051473814, 5094.006668699605, 5109.897802043938, 5114.105031619451, 5117.974410127926, 5122.538971492546, 4365.192439398721, 4672.042231342567, 4676.883077923045, 4679.064187162581, 4681.88295292617, 5216.165413533817, 5318.989392015505, 5338.973305133488, 5372.462753724635, 5506.559475242011, 3831.4635950966886, 3846.865359712425, 3847.7306588538813, 3848.5766565677754, 3848.826802793881, 5521.420250873641, 5779.855503612402, 5785.074388097884, 5788.739506722165, 5792.82621521355, 3854.6145385461227, 3878.2243551289853, 3967.861124860616, 3972.3445631471063, 3990.501187351581, 4017.0, 4031.0, 4033.596640335943, 4033.6369726724733, 4034.798260086984, 4615.169269531517, 4616.584507394284, 4618.607418369409, 4620.237660785932, 4626.236670949323, 5422.447030175874, 5514.393416724157, 5518.172274158828, 5518.255035570147, 5519.31008623922, 1396.2041636267434, 1428.707115041414, 1431.2342896550297, 1432.3769160415252, 1432.6633241188522, 949.1457688080783, 965.4689920543673, 966.8308046091905, 970.6166064404521, 978.3982850546862, 4867.707937523689, 4870.95129048705, 4875.19559272716, 4876.244182151772, 4880.877978050577, 5704.087346024699, 5734.598578099576, 5741.942580574141, 5742.052561327418, 5742.397048309951, 4863.5379638934955, 4875.975620121877, 4879.902401951941, 4881.658283920163, 4882.5605695487575, 3017.7585793136936, 3070.0482850316475, 3089.1350421881907, 3095.7987730797427, 3102.4570700127388, 5432.402435732066, 5432.483914028161, 5438.184272359099, 5440.619156658996, 5454.536364409069, 5885.176075349455, 5892.086726557391, 5895.469407753323, 5902.6458412495385, 5902.704864756745, 5445.564354851603, 5445.754941027584, 5446.482584154499, 5455.754491047833, 5456.945430545803, 5639.2668953035845, 5721.342045664635, 5814.680192589448, 5821.79623713168, 5831.650100993954, 5682.37493875665, 5683.5737319700365, 5689.089745640677, 5696.11710184922, 5698.1452782083015, 5519.613627046068, 5522.861928451821, 5524.392316845224, 5526.253955715927, 5526.834194973996, 3687.8156092194745, 3787.7348585598743, 3792.9620703792607, 3910.3743401055694, 3913.4521167037205, 5717.656940583578, 5724.0, 5726.713664316684, 5736.311642602915, 5761.366249712612, 5522.558195344443, 5528.944710552843, 5533.861653458695, 5534.142207957773, 5535.280413546213, 5435.402105768441, 5533.0, 5538.280023596906, 5540.307461567304, 5612.971935140298, 5498.642588231752, 5498.752556135064, 5499.50504454601, 5500.862478438071, 5501.642393244426, 5591.0, 5617.662940223599, 5634.070378387521, 5640.492355688008, 5646.153077038477, 5348.812791552277, 5479.369872464717, 5491.093969494988, 5492.780288788404, 5498.037843377473], "unit": "score", "name": "prototype.html", "value": 4156.539121723427}, {"lowerIsBetter": false, "replicates": [664.4817042706691, 666.300384596174, 722.4725950056459, 727.7234650832683, 736.6611358774963, 11383.0, 11538.2500137491, 11550.942245288772, 11565.479553420095, 11566.248193867414, 11180.66458006259, 11267.943660281688, 11802.527898884035, 11830.171887967846, 11868.050555955524, 637.0666972884723, 645.4287955159671, 788.1409263902331, 799.4244144216156, 808.1796976069294, 802.6909639788665, 806.7862016565585, 806.8587997100514, 813.8209593889335, 814.6374863185885, 794.8688466403037, 799.4084377560592, 800.4756884240832, 800.9519428834288, 801.3428988229625, 670.3497607320916, 677.1975209376905, 834.4742812028384, 847.2882778466118, 849.4605925237457, 624.1012941364467, 626.3329554024949, 626.3767551286454, 627.0468887291337, 627.1126356205963, 11401.37292448915, 11433.08535317181, 11437.256578322382, 11447.713807154805, 11511.654650360502, 10512.106470949979, 10520.263581549294, 10756.0, 11002.889971100267, 11011.119110471143, 9097.0, 9258.72223833286, 9382.530873456368, 9429.245660347156, 9550.092741189575, 3275.3285576457006, 3275.950860737091, 3280.7867488613165, 3289.7203737682325, 3304.2400247943106, 39166.60833391658, 39341.213175736324, 39974.20051598981, 40111.19777604461, 40117.398239026435, 8748.34387420946, 8983.416077954913, 9000.09999000108, 9180.44917304964, 9260.166585007382, 15710.27187009428, 15715.449959251371, 15769.05385676863, 15785.289661965238, 15786.921065394828, 6226.937730622726, 6227.221597300338, 6318.115463835067, 6424.903626445607, 6513.381228783258, 3588.928221435609, 3666.0834791302173, 3684.742068055264, 3740.1584643455003, 3828.368319227352, 2602.700689420703, 2609.647697560843, 2615.6599642046795, 2617.607358896181, 2624.238970698514, 440.25816499198953, 451.4807970833568, 458.2873631503045, 458.4132310642383, 462.5212904643745, 544.383181949609, 574.715515819672, 578.1010528628024, 590.6356316907957, 598.8892054969845, 7242.855142897112, 7246.067477079877, 7332.413406927433, 7366.0, 7367.410607151415, 382.1249339013683, 470.74814973989436, 565.6238601330069, 567.1521075991419, 596.3261514488601, 3303.1576947878616, 3333.516640087174, 3356.5972083350152, 3396.677315655009, 3432.708219801345, 48005.75997119992, 48604.0, 48635.27047094297, 48684.513154868, 48763.75618121958, 47483.33808316692, 47495.76252118717, 48332.7583362088, 48732.0, 49055.50944490581, 43099.35350969675, 43521.34717979233, 44100.55899441029, 45112.42106526322, 45220.3216951746, 7286.271372862671, 7290.270972902879, 7297.525660832029, 7385.667644955883, 7518.135414427411, 30762.0, 30836.074917752503, 31002.22494437567, 31276.218094547818, 32019.83990080128, 35685.0, 36181.0, 36224.45663315105, 38471.80764096162, 39022.60977390303, 9420.340576159877, 9462.526873656427, 9745.976672449575, 9780.608775648894, 9792.559334830092, 10233.795324093626, 10734.677959661223, 10778.89221107779, 10804.567817287221, 10832.295900766425, 9560.61757529691, 9644.565994530123, 9736.853947190939, 9779.657711980331, 9835.557399917165, 9739.805203896025, 9765.609375624896, 10092.798144037224, 10230.539625716712, 10284.382937023802, 7595.202503737028, 9443.952780236055, 9632.0, 9654.823944359714, 9721.173700235277, 8153.714619988272, 8367.163283671583, 8482.872756908775, 8535.8719619207, 8770.166834150745, 8310.875336870073, 8359.916400835913, 8373.49759014461, 8659.177378149066, 8792.956035219782], "unit": "score", "name": "yui.html", "value": 5533.7365344480295}], "name": "dromaeo_css", "value": 6157.582076180934}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 112.0, 106.0, 112.0, 113.0, 112.0, 100.0, 113.0, 111.0, 118.0], "unit": "score", "name": "ai-astar", "value": 110.7}, {"lowerIsBetter": true, "replicates": [148.0, 128.0, 154.0, 170.0, 133.0, 150.0, 159.0, 129.0, 170.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 147.1}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 160.0, 159.0, 161.0, 161.0, 162.0, 160.0, 163.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 87.0, 90.0, 72.0, 75.0, 72.0, 101.0, 86.0, 71.0], "unit": "score", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 127.0, 95.0, 94.0, 94.0, 137.0, 95.0, 95.0, 129.0], "unit": "score", "name": "audio-oscillator", "value": 105.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 145.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 71.0, 70.0, 70.0, 70.0, 71.0, 80.0, 69.0, 70.0], "unit": "score", "name": "json-parse-financial", "value": 71.3}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 69.0, 69.0, 68.0, 68.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 83.0, 82.0, 82.0, 82.0, 83.0, 82.0, 84.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.8}, {"lowerIsBetter": true, "replicates": [119.0, 123.0, 121.0, 124.0, 130.0, 123.0, 136.0, 124.0, 124.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.0}, {"lowerIsBetter": true, "replicates": [140.0, 140.0, 146.0, 148.0, 146.0, 134.0, 144.0, 135.0, 144.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 141.2}, {"lowerIsBetter": true, "replicates": [72.0, 68.0, 70.0, 73.0, 70.0, 69.0, 70.0, 68.0, 68.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.6}], "name": "kraken", "value": 1513.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.80998176090202], "unit": "score", "name": "Richards", "value": 212.90636200000006}, {"lowerIsBetter": false, "replicates": [189.28639030853682], "unit": "score", "name": "DeltaBlue", "value": 349.30139399999996}, {"lowerIsBetter": false, "replicates": [277.623542476402], "unit": "score", "name": "Encrypt", "value": 958.7839620000002}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [1960.7843137254902], "unit": "score", "name": "RayTrace", "value": 377.39439}, {"lowerIsBetter": false, "replicates": [794.2811755361398], "unit": "score", "name": "Earley", "value": 839.0769169999999}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [27324.324324324323], "unit": "score", "name": "RegExp", "value": 33.33970820969339}, {"lowerIsBetter": false, "replicates": [607.5334143377886], "unit": "score", "name": "Splay", "value": 134.13418600000003}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19744.95411917358}]} 05:47:55 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19744.95"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1513.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6157.58"}}} 05:47:55 INFO - Return code: 0 05:47:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:47:55 ERROR - # TBPL SUCCESS # 05:47:55 INFO - Running post-action listener: _resource_record_post_action 05:47:55 INFO - Running post-run listener: _resource_record_post_run 05:47:56 INFO - Total resource usage - Wall time: 1238s; CPU: 13.0%; Read bytes: 606208; Write bytes: 315977728; Read time: 132; Write time: 370176 05:47:56 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1413120; Read time: 0; Write time: 112 05:47:56 INFO - run-tests - Wall time: 1222s; CPU: 13.0%; Read bytes: 606208; Write bytes: 314564608; Read time: 132; Write time: 370064 05:47:56 INFO - Running post-run listener: _upload_blobber_files 05:47:56 INFO - Blob upload gear active. 05:47:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:47:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:47:56 INFO - (blobuploader) - INFO - Open directory for files ... 05:47:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:47:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:47:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:47:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:47:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:47:57 INFO - (blobuploader) - INFO - Done attempting. 05:47:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:47:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:47:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:48:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:00 INFO - (blobuploader) - INFO - Done attempting. 05:48:00 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:00 INFO - Return code: 0 05:48:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e68ec12fc75c04d0175fc6637365d083d86d749efb0883e6f4d6abad5a66a68709cc43a65b61926e73dad8123134d26c8158f4b979abf7289e992d71fe39b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db09c429188f87d800dd572cd4365d2e90cc3cb93dfc944a8efcab38f3d5bfaa0ec735bfd7de3699c58617f3c3868ffc5314b82dca5ed36e92b591d1511571d1"} 05:48:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:00 INFO - Contents: 05:48:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e68ec12fc75c04d0175fc6637365d083d86d749efb0883e6f4d6abad5a66a68709cc43a65b61926e73dad8123134d26c8158f4b979abf7289e992d71fe39b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db09c429188f87d800dd572cd4365d2e90cc3cb93dfc944a8efcab38f3d5bfaa0ec735bfd7de3699c58617f3c3868ffc5314b82dca5ed36e92b591d1511571d1"} 05:48:00 INFO - Copying logs to upload dir... 05:48:00 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1278.332761 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-21 05:48:00.094399) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-21 05:48:00.097447) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e68ec12fc75c04d0175fc6637365d083d86d749efb0883e6f4d6abad5a66a68709cc43a65b61926e73dad8123134d26c8158f4b979abf7289e992d71fe39b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db09c429188f87d800dd572cd4365d2e90cc3cb93dfc944a8efcab38f3d5bfaa0ec735bfd7de3699c58617f3c3868ffc5314b82dca5ed36e92b591d1511571d1"} build_url:https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/aTqGZp0MSVy9yq1-knaNGQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e68ec12fc75c04d0175fc6637365d083d86d749efb0883e6f4d6abad5a66a68709cc43a65b61926e73dad8123134d26c8158f4b979abf7289e992d71fe39b41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/db09c429188f87d800dd572cd4365d2e90cc3cb93dfc944a8efcab38f3d5bfaa0ec735bfd7de3699c58617f3c3868ffc5314b82dca5ed36e92b591d1511571d1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-21 05:48:00.122384) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:48:00.122713) ========= 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=e8c3f5a4c0a512351e80f2ca000001d7-1450704169.179846-923055533 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 05:48:00.172900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 05:48:00.173217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 05:48:00.173524) ========= ========= Total master_lag: 0.25 =========