builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1449778600.23 results: success (0) buildid: 20151210111049 builduid: 2e9b58e49ead4318ac489c70728d0b35 revision: 50a0251369dfdc40f5e1a9a77684b77add683117 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.227971) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.228444) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.228762) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006354 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.260019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.260926) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.281910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.282186) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.343016) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.345589) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.346058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.346382) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-10 12:16: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 17.1M=0.001s 2015-12-10 12:16:40 (17.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190335 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.553712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.554044) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019621 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:40.602852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-10 12:16:40.603198) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 50a0251369dfdc40f5e1a9a77684b77add683117 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 50a0251369dfdc40f5e1a9a77684b77add683117 --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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-10 12:16:41,012 truncating revision to first 12 chars 2015-12-10 12:16:41,012 Setting DEBUG logging. 2015-12-10 12:16:41,012 attempt 1/10 2015-12-10 12:16:41,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/50a0251369df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-10 12:16:41,755 unpacking tar archive at: fx-team-50a0251369df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036829 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-10 12:16:41.653129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:41.653471) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:41.663265) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:41.663594) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-10 12:16:41.663997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-10 12:16:41.664296) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:16:42 INFO - MultiFileLogger online at 20151210 12:16:42 in /builds/slave/test 12:16:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:16:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:16:42 INFO - {'append_to_log': False, 12:16:42 INFO - 'base_work_dir': '/builds/slave/test', 12:16:42 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:16:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:16:42 INFO - 'branch': 'Fx-Team-Non-PGO', 12:16:42 INFO - 'buildbot_json_path': 'buildprops.json', 12:16:42 INFO - 'config_files': ('talos/linux_config.py',), 12:16:42 INFO - 'default_actions': ('clobber', 12:16:42 INFO - 'read-buildbot-config', 12:16:42 INFO - 'download-and-extract', 12:16:42 INFO - 'populate-webroot', 12:16:42 INFO - 'create-virtualenv', 12:16:42 INFO - 'install', 12:16:42 INFO - 'run-tests'), 12:16:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:16:42 INFO - 'download_minidump_stackwalk': True, 12:16:42 INFO - 'download_symbols': 'ondemand', 12:16:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:16:42 INFO - 'tooltool.py': '/tools/tooltool.py', 12:16:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:16:42 INFO - '/tools/misc-python/virtualenv.py')}, 12:16:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:16:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:16:42 INFO - 'installer_path': 'installer.exe', 12:16:42 INFO - 'log_level': 'info', 12:16:42 INFO - 'log_name': 'talos', 12:16:42 INFO - 'log_to_console': True, 12:16:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:16:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:16:42 INFO - 'opt_config_files': (), 12:16:42 INFO - 'pip_index': False, 12:16:42 INFO - 'sps_profile': False, 12:16:42 INFO - 'sps_profile_interval': 0, 12:16:42 INFO - 'suite': 'dromaeojs-e10s', 12:16:42 INFO - 'system_bits': '64', 12:16:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:16:42 INFO - 'title': 'talos-linux64-ix-047', 12:16:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:16:42 INFO - 'use_talos_json': True, 12:16:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:16:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:16:42 INFO - 'work_dir': 'build'} 12:16:42 INFO - ##### 12:16:42 INFO - ##### Running clobber step. 12:16:42 INFO - ##### 12:16:42 INFO - Running pre-action listener: _resource_record_pre_action 12:16:42 INFO - Running main action method: clobber 12:16:42 INFO - rmtree: /builds/slave/test/build 12:16:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:16:43 INFO - Running post-action listener: _resource_record_post_action 12:16:43 INFO - ##### 12:16:43 INFO - ##### Running read-buildbot-config step. 12:16:43 INFO - ##### 12:16:43 INFO - Running pre-action listener: _resource_record_pre_action 12:16:43 INFO - Running main action method: read_buildbot_config 12:16:43 INFO - Using buildbot properties: 12:16:43 INFO - { 12:16:43 INFO - "properties": { 12:16:43 INFO - "buildnumber": 555, 12:16:43 INFO - "product": "firefox", 12:16:43 INFO - "script_repo_revision": "production", 12:16:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 12:16:43 INFO - "repository": "", 12:16:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 12:16:43 INFO - "buildid": "20151210111049", 12:16:43 INFO - "slavename": "talos-linux64-ix-047", 12:16:43 INFO - "pgo_build": "False", 12:16:43 INFO - "basedir": "/builds/slave/test", 12:16:43 INFO - "project": "", 12:16:43 INFO - "platform": "ubuntu64_hw", 12:16:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:16:43 INFO - "slavebuilddir": "test", 12:16:43 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:16:43 INFO - "branch": "fx-team", 12:16:43 INFO - "repo_path": "integration/fx-team", 12:16:43 INFO - "stage_platform": "linux64", 12:16:43 INFO - "builduid": "2e9b58e49ead4318ac489c70728d0b35", 12:16:43 INFO - "revision": "50a0251369dfdc40f5e1a9a77684b77add683117" 12:16:43 INFO - }, 12:16:43 INFO - "sourcestamp": { 12:16:43 INFO - "repository": "", 12:16:43 INFO - "hasPatch": false, 12:16:43 INFO - "project": "", 12:16:43 INFO - "branch": "fx-team-linux64-talos", 12:16:43 INFO - "changes": [ 12:16:43 INFO - { 12:16:43 INFO - "category": null, 12:16:43 INFO - "files": [ 12:16:43 INFO - { 12:16:43 INFO - "url": null, 12:16:43 INFO - "name": "https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 12:16:43 INFO - } 12:16:43 INFO - ], 12:16:43 INFO - "repository": "", 12:16:43 INFO - "rev": "50a0251369dfdc40f5e1a9a77684b77add683117", 12:16:43 INFO - "who": "mak77@bonardo.net", 12:16:43 INFO - "when": 1449778542, 12:16:43 INFO - "number": 7267841, 12:16:43 INFO - "comments": "Bug 1231661 - followup: readd somehow lost double quotes. r=post-facto", 12:16:43 INFO - "project": "", 12:16:43 INFO - "at": "Thu 10 Dec 2015 12:15:42", 12:16:43 INFO - "branch": "fx-team-linux64-talos", 12:16:43 INFO - "revlink": "", 12:16:43 INFO - "properties": [ 12:16:43 INFO - [ 12:16:43 INFO - "buildid", 12:16:43 INFO - "20151210111049", 12:16:43 INFO - "Change" 12:16:43 INFO - ], 12:16:43 INFO - [ 12:16:43 INFO - "builduid", 12:16:43 INFO - "2e9b58e49ead4318ac489c70728d0b35", 12:16:43 INFO - "Change" 12:16:43 INFO - ], 12:16:43 INFO - [ 12:16:43 INFO - "pgo_build", 12:16:43 INFO - "False", 12:16:43 INFO - "Change" 12:16:43 INFO - ] 12:16:43 INFO - ], 12:16:43 INFO - "revision": "50a0251369dfdc40f5e1a9a77684b77add683117" 12:16:43 INFO - } 12:16:43 INFO - ], 12:16:43 INFO - "revision": "50a0251369dfdc40f5e1a9a77684b77add683117" 12:16:43 INFO - } 12:16:43 INFO - } 12:16:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 12:16:43 INFO - Running post-action listener: _resource_record_post_action 12:16:43 INFO - ##### 12:16:43 INFO - ##### Running download-and-extract step. 12:16:43 INFO - ##### 12:16:43 INFO - Running pre-action listener: _resource_record_pre_action 12:16:43 INFO - Running main action method: download_and_extract 12:16:43 INFO - mkdir: /builds/slave/test/build/tests 12:16: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')]} 12:16:43 INFO - https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/test_packages.json 12:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/test_packages.json 12:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:16:43 INFO - Downloaded 1302 bytes. 12:16:43 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:16:43 INFO - Using the following test package requirements: 12:16:43 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:16:43 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:16:43 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'jsshell-linux-x86_64.zip'], 12:16:43 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:16:43 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:16:43 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:16:43 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:16:43 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:16:43 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 12:16:43 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 12:16:43 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:16:43 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:16: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')]} 12:16:43 INFO - https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:16:44 INFO - Downloaded 21866925 bytes. 12:16:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:16:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:16:46 INFO - Return code: 0 12:16:46 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:16: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')]} 12:16:46 INFO - https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:16:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:16:48 INFO - Downloaded 21866925 bytes. 12:16:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 12:16:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 12:16:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 12:16:49 INFO - Return code: 0 12:16: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')]} 12:16:49 INFO - https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:16:50 INFO - Downloaded 11132038 bytes. 12:16:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 12:16:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 12:16:50 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 12:16:51 INFO - Return code: 0 12:16: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')]} 12:16:51 INFO - https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:16:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:16:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:16:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:16:52 INFO - Downloaded 57728073 bytes. 12:16:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:16:52 INFO - mkdir: /builds/slave/test/properties 12:16:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:16:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:16:52 INFO - Contents: 12:16:52 INFO - build_url:https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 12:16:52 INFO - Running post-action listener: _resource_record_post_action 12:16:52 INFO - Running post-action listener: set_extra_try_arguments 12:16:52 INFO - ##### 12:16:52 INFO - ##### Running populate-webroot step. 12:16:52 INFO - ##### 12:16:52 INFO - Running pre-action listener: _resource_record_pre_action 12:16:52 INFO - Running main action method: populate_webroot 12:16:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:16:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:16:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:16:52 INFO - '--tppagecycles', 12:16:52 INFO - '7'], 12:16:52 INFO - 'tests': ['tsvgm']}}, 12:16:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:16:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:16:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:16:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:16:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'tests': ['damp', 'tps']}, 12:16:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['damp', 'tps']}, 12:16:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:16:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:16:52 INFO - 'other': {'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'other_l64': {'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'other_nol64': {'tests': ['a11yr', 12:16:52 INFO - 'ts_paint', 12:16:52 INFO - 'tpaint', 12:16:52 INFO - 'sessionrestore', 12:16:52 INFO - 'sessionrestore_no_auto_restore']}, 12:16:52 INFO - 'svgr': {'tests': ['tsvgx', 12:16:52 INFO - 'tsvgr_opacity', 12:16:52 INFO - 'tart', 12:16:52 INFO - 'tscrollx', 12:16:52 INFO - 'cart']}, 12:16:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['tsvgx', 12:16:52 INFO - 'tsvgr_opacity', 12:16:52 INFO - 'tart', 12:16:52 INFO - 'tscrollx', 12:16:52 INFO - 'cart']}, 12:16:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'tests': ['tp5o']}, 12:16:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'talos_options': ['--e10s'], 12:16:52 INFO - 'tests': ['tp5o']}, 12:16:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'talos_options': ['--xperf_path', 12:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:52 INFO - 'tests': ['tp5n']}, 12:16:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:52 INFO - 'talos_options': ['--e10s', 12:16:52 INFO - '--xperf_path', 12:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:52 INFO - 'tests': ['tp5n']}}, 12:16:52 INFO - 'talos.zip': {'path': '', 12:16:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:16:52 INFO - Running post-action listener: _resource_record_post_action 12:16:52 INFO - ##### 12:16:52 INFO - ##### Running create-virtualenv step. 12:16:52 INFO - ##### 12:16:52 INFO - Running pre-action listener: _resource_record_pre_action 12:16:52 INFO - Running main action method: create_virtualenv 12:16:52 INFO - Creating virtualenv /builds/slave/test/build/venv 12:16: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 12:16:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:16:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:16:52 INFO - Using real prefix '/usr' 12:16:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:16:53 INFO - Installing distribute.............................................................................................................................................................................................done. 12:16:55 INFO - Installing pip.................done. 12:16:55 INFO - Return code: 0 12:16:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:16: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', 'pip>=1.5'] in /builds/slave/test/build 12:16: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 pip>=1.5 12:16:55 INFO - Using env: {'DISPLAY': ':0', 12:16:55 INFO - 'HOME': '/home/cltbld', 12:16:55 INFO - 'LANG': 'en_US.UTF-8', 12:16:55 INFO - 'LANGUAGE': 'en_US:en', 12:16:55 INFO - 'LOGNAME': 'cltbld', 12:16:55 INFO - 'MAIL': '/var/mail/cltbld', 12:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:55 INFO - 'MOZ_NO_REMOTE': '1', 12:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:55 INFO - 'NO_EM_RESTART': '1', 12:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:55 INFO - 'PWD': '/builds/slave/test', 12:16:55 INFO - 'SHELL': '/bin/bash', 12:16:55 INFO - 'SHLVL': '1', 12:16:55 INFO - 'TERM': 'linux', 12:16:55 INFO - 'TMOUT': '86400', 12:16:55 INFO - 'USER': 'cltbld', 12:16:55 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:55 INFO - '_': '/tools/buildbot/bin/python'} 12:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16: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 12:16:55 INFO - Cleaning up... 12:16:55 INFO - Return code: 0 12:16:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:16: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 12:16: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 12:16:55 INFO - Using env: {'DISPLAY': ':0', 12:16:55 INFO - 'HOME': '/home/cltbld', 12:16:55 INFO - 'LANG': 'en_US.UTF-8', 12:16:55 INFO - 'LANGUAGE': 'en_US:en', 12:16:55 INFO - 'LOGNAME': 'cltbld', 12:16:55 INFO - 'MAIL': '/var/mail/cltbld', 12:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:55 INFO - 'MOZ_NO_REMOTE': '1', 12:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:55 INFO - 'NO_EM_RESTART': '1', 12:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:55 INFO - 'PWD': '/builds/slave/test', 12:16:55 INFO - 'SHELL': '/bin/bash', 12:16:55 INFO - 'SHLVL': '1', 12:16:55 INFO - 'TERM': 'linux', 12:16:55 INFO - 'TMOUT': '86400', 12:16:55 INFO - 'USER': 'cltbld', 12:16:55 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:55 INFO - '_': '/tools/buildbot/bin/python'} 12:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:55 INFO - Downloading/unpacking psutil>=0.7.1 12:16: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 12:16: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 12:16: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 12:16: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 12:16: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 12:16: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 12:16:57 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 12:16:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:16:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:57 INFO - Installing collected packages: psutil 12:16:57 INFO - Running setup.py install for psutil 12:16:57 INFO - building 'psutil._psutil_linux' extension 12:16: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 12:16: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 12:16:57 INFO - building 'psutil._psutil_posix' extension 12:16: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 12:16:58 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 12:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:58 INFO - Successfully installed psutil 12:16:58 INFO - Cleaning up... 12:16:58 INFO - Return code: 0 12:16:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:58 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:16:58 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 12:16:58 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 12:16:58 INFO - Using env: {'DISPLAY': ':0', 12:16:58 INFO - 'HOME': '/home/cltbld', 12:16:58 INFO - 'LANG': 'en_US.UTF-8', 12:16:58 INFO - 'LANGUAGE': 'en_US:en', 12:16:58 INFO - 'LOGNAME': 'cltbld', 12:16:58 INFO - 'MAIL': '/var/mail/cltbld', 12:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:58 INFO - 'MOZ_NO_REMOTE': '1', 12:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:58 INFO - 'NO_EM_RESTART': '1', 12:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:58 INFO - 'PWD': '/builds/slave/test', 12:16:58 INFO - 'SHELL': '/bin/bash', 12:16:58 INFO - 'SHLVL': '1', 12:16:58 INFO - 'TERM': 'linux', 12:16:58 INFO - 'TMOUT': '86400', 12:16:58 INFO - 'USER': 'cltbld', 12:16:58 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:58 INFO - '_': '/tools/buildbot/bin/python'} 12:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:17:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:17:00 INFO - Installing collected packages: mozsystemmonitor 12:17:00 INFO - Running setup.py install for mozsystemmonitor 12:17:00 INFO - Successfully installed mozsystemmonitor 12:17:00 INFO - Cleaning up... 12:17:00 INFO - Return code: 0 12:17:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:17:00 INFO - Using env: {'DISPLAY': ':0', 12:17:00 INFO - 'HOME': '/home/cltbld', 12:17:00 INFO - 'LANG': 'en_US.UTF-8', 12:17:00 INFO - 'LANGUAGE': 'en_US:en', 12:17:00 INFO - 'LOGNAME': 'cltbld', 12:17:00 INFO - 'MAIL': '/var/mail/cltbld', 12:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:00 INFO - 'MOZ_NO_REMOTE': '1', 12:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:00 INFO - 'NO_EM_RESTART': '1', 12:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:00 INFO - 'PWD': '/builds/slave/test', 12:17:00 INFO - 'SHELL': '/bin/bash', 12:17:00 INFO - 'SHLVL': '1', 12:17:00 INFO - 'TERM': 'linux', 12:17:00 INFO - 'TMOUT': '86400', 12:17:00 INFO - 'USER': 'cltbld', 12:17:00 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:00 INFO - '_': '/tools/buildbot/bin/python'} 12:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:00 INFO - Downloading/unpacking blobuploader==1.2.4 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:02 INFO - Downloading blobuploader-1.2.4.tar.gz 12:17:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:17:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:17:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:02 INFO - Downloading docopt-0.6.1.tar.gz 12:17:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:17:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:17:03 INFO - Running setup.py install for blobuploader 12:17:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:17:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:17:03 INFO - Running setup.py install for requests 12:17:03 INFO - Running setup.py install for docopt 12:17:03 INFO - Successfully installed blobuploader requests docopt 12:17:03 INFO - Cleaning up... 12:17:03 INFO - Return code: 0 12:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:03 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:03 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 12:17:03 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 12:17:03 INFO - Using env: {'DISPLAY': ':0', 12:17:03 INFO - 'HOME': '/home/cltbld', 12:17:03 INFO - 'LANG': 'en_US.UTF-8', 12:17:03 INFO - 'LANGUAGE': 'en_US:en', 12:17:03 INFO - 'LOGNAME': 'cltbld', 12:17:03 INFO - 'MAIL': '/var/mail/cltbld', 12:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:03 INFO - 'MOZ_NO_REMOTE': '1', 12:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:03 INFO - 'NO_EM_RESTART': '1', 12:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:03 INFO - 'PWD': '/builds/slave/test', 12:17:03 INFO - 'SHELL': '/bin/bash', 12:17:03 INFO - 'SHLVL': '1', 12:17:03 INFO - 'TERM': 'linux', 12:17:03 INFO - 'TMOUT': '86400', 12:17:03 INFO - 'USER': 'cltbld', 12:17:03 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:03 INFO - '_': '/tools/buildbot/bin/python'} 12:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:03 INFO - Running setup.py (path:/tmp/pip-XpE612-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:03 INFO - Running setup.py (path:/tmp/pip-KdKnF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:03 INFO - Running setup.py (path:/tmp/pip-reSHoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:04 INFO - Running setup.py (path:/tmp/pip-7FEqZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:04 INFO - Running setup.py (path:/tmp/pip-hlxZCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:04 INFO - Running setup.py (path:/tmp/pip-UN_YZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:04 INFO - Running setup.py (path:/tmp/pip-qwLOG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:04 INFO - Running setup.py (path:/tmp/pip-eeNrAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:04 INFO - Running setup.py (path:/tmp/pip-OlYl1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:04 INFO - Running setup.py (path:/tmp/pip-K75GjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:04 INFO - Running setup.py (path:/tmp/pip-KjJT20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:04 INFO - Running setup.py (path:/tmp/pip-uJwSwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:04 INFO - Running setup.py (path:/tmp/pip-nC_NY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:04 INFO - Running setup.py (path:/tmp/pip-5KE3tZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:04 INFO - Running setup.py (path:/tmp/pip-QCOdlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:04 INFO - Running setup.py (path:/tmp/pip-MweqiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:04 INFO - Running setup.py (path:/tmp/pip-Osf_IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:17:05 INFO - Running setup.py install for manifestparser 12:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozcrash 12:17:05 INFO - Running setup.py install for mozdebug 12:17:05 INFO - Running setup.py install for mozdevice 12:17:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozfile 12:17:05 INFO - Running setup.py install for mozhttpd 12:17:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozinfo 12:17:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozInstall 12:17:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozleak 12:17:05 INFO - Running setup.py install for mozlog 12:17:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for moznetwork 12:17:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozprocess 12:17:06 INFO - Running setup.py install for mozprofile 12:17:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozrunner 12:17:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozscreenshot 12:17:06 INFO - Running setup.py install for moztest 12:17:06 INFO - Running setup.py install for mozversion 12:17:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:17:06 INFO - Cleaning up... 12:17:06 INFO - Return code: 0 12:17:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:06 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:06 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 12:17:06 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 12:17:06 INFO - Using env: {'DISPLAY': ':0', 12:17:06 INFO - 'HOME': '/home/cltbld', 12:17:06 INFO - 'LANG': 'en_US.UTF-8', 12:17:06 INFO - 'LANGUAGE': 'en_US:en', 12:17:06 INFO - 'LOGNAME': 'cltbld', 12:17:06 INFO - 'MAIL': '/var/mail/cltbld', 12:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:06 INFO - 'MOZ_NO_REMOTE': '1', 12:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:06 INFO - 'NO_EM_RESTART': '1', 12:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:06 INFO - 'PWD': '/builds/slave/test', 12:17:06 INFO - 'SHELL': '/bin/bash', 12:17:06 INFO - 'SHLVL': '1', 12:17:06 INFO - 'TERM': 'linux', 12:17:06 INFO - 'TMOUT': '86400', 12:17:06 INFO - 'USER': 'cltbld', 12:17:06 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:06 INFO - '_': '/tools/buildbot/bin/python'} 12:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:07 INFO - Running setup.py (path:/tmp/pip-WiGJQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:07 INFO - Running setup.py (path:/tmp/pip-brhITQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:07 INFO - Running setup.py (path:/tmp/pip-R1zrG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:07 INFO - Running setup.py (path:/tmp/pip-9ZHTyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:07 INFO - Running setup.py (path:/tmp/pip-XKI69h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:07 INFO - Running setup.py (path:/tmp/pip-DzskgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:07 INFO - Running setup.py (path:/tmp/pip-bMyHrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:07 INFO - Running setup.py (path:/tmp/pip-8uQzNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:07 INFO - Running setup.py (path:/tmp/pip-5gwZHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:07 INFO - Running setup.py (path:/tmp/pip-LKRqTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:07 INFO - Running setup.py (path:/tmp/pip-gGWjg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:07 INFO - Running setup.py (path:/tmp/pip-AMQ2mS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:07 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)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:07 INFO - Running setup.py (path:/tmp/pip-r053xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:07 INFO - Running setup.py (path:/tmp/pip-CpScqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:08 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)) 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:08 INFO - Running setup.py (path:/tmp/pip-ETKveL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:08 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)) 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:08 INFO - Running setup.py (path:/tmp/pip-OarRUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:08 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)) 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:08 INFO - Running setup.py (path:/tmp/pip-jajN90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:08 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)) 12:17:08 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)) 12:17:08 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)) 12:17:08 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)) 12:17:08 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)) 12:17:08 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)) 12:17:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:09 INFO - Downloading blessings-1.5.1.tar.gz 12:17:09 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 12:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:17:10 INFO - Installing collected packages: blessings 12:17:10 INFO - Running setup.py install for blessings 12:17:10 INFO - Successfully installed blessings 12:17:10 INFO - Cleaning up... 12:17:10 INFO - Return code: 0 12:17:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:10 INFO - Reading from file tmpfile_stdout 12:17:10 INFO - Current package versions: 12:17:10 INFO - argparse == 1.2.1 12:17:10 INFO - blessings == 1.5.1 12:17:10 INFO - blobuploader == 1.2.4 12:17:10 INFO - docopt == 0.6.1 12:17:10 INFO - manifestparser == 1.1 12:17:10 INFO - mozInstall == 1.12 12:17:10 INFO - mozcrash == 0.16 12:17:10 INFO - mozdebug == 0.1 12:17:10 INFO - mozdevice == 0.47 12:17:10 INFO - mozfile == 1.2 12:17:10 INFO - mozhttpd == 0.7 12:17:10 INFO - mozinfo == 0.9 12:17:10 INFO - mozleak == 0.1 12:17:10 INFO - mozlog == 3.1 12:17:10 INFO - moznetwork == 0.27 12:17:10 INFO - mozprocess == 0.22 12:17:10 INFO - mozprofile == 0.27 12:17:10 INFO - mozrunner == 6.11 12:17:10 INFO - mozscreenshot == 0.1 12:17:10 INFO - mozsystemmonitor == 0.0 12:17:10 INFO - moztest == 0.7 12:17:10 INFO - mozversion == 1.4 12:17:10 INFO - psutil == 3.1.1 12:17:10 INFO - requests == 1.2.3 12:17:10 INFO - wsgiref == 0.1.2 12:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:10 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')]} 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:10 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')]} 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:10 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:10 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 12:17:10 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 12:17:10 INFO - Using env: {'DISPLAY': ':0', 12:17:10 INFO - 'HOME': '/home/cltbld', 12:17:10 INFO - 'LANG': 'en_US.UTF-8', 12:17:10 INFO - 'LANGUAGE': 'en_US:en', 12:17:10 INFO - 'LOGNAME': 'cltbld', 12:17:10 INFO - 'MAIL': '/var/mail/cltbld', 12:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:10 INFO - 'MOZ_NO_REMOTE': '1', 12:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:10 INFO - 'NO_EM_RESTART': '1', 12:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:10 INFO - 'PWD': '/builds/slave/test', 12:17:10 INFO - 'SHELL': '/bin/bash', 12:17:10 INFO - 'SHLVL': '1', 12:17:10 INFO - 'TERM': 'linux', 12:17:10 INFO - 'TMOUT': '86400', 12:17:10 INFO - 'USER': 'cltbld', 12:17:10 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:10 INFO - '_': '/tools/buildbot/bin/python'} 12:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:17:10 INFO - Cleaning up... 12:17:10 INFO - Return code: 0 12:17:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:17:10 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')]} 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:10 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')]} 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:10 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:10 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 12:17:10 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 12:17:10 INFO - Using env: {'DISPLAY': ':0', 12:17:10 INFO - 'HOME': '/home/cltbld', 12:17:10 INFO - 'LANG': 'en_US.UTF-8', 12:17:10 INFO - 'LANGUAGE': 'en_US:en', 12:17:10 INFO - 'LOGNAME': 'cltbld', 12:17:10 INFO - 'MAIL': '/var/mail/cltbld', 12:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:10 INFO - 'MOZ_NO_REMOTE': '1', 12:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:10 INFO - 'NO_EM_RESTART': '1', 12:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:10 INFO - 'PWD': '/builds/slave/test', 12:17:10 INFO - 'SHELL': '/bin/bash', 12:17:10 INFO - 'SHLVL': '1', 12:17:10 INFO - 'TERM': 'linux', 12:17:10 INFO - 'TMOUT': '86400', 12:17:10 INFO - 'USER': 'cltbld', 12:17:10 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:10 INFO - '_': '/tools/buildbot/bin/python'} 12:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:10 INFO - Downloading/unpacking jsonschema 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:17:12 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 12:17:12 INFO - Downloading/unpacking functools32 (from jsonschema) 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:12 INFO - Downloading functools32-3.2.3-2.tar.gz 12:17:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 12:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:17:12 INFO - warning: no files found matching '*.txt' 12:17:12 INFO - no previously-included directories found matching 'build' 12:17:12 INFO - no previously-included directories found matching 'dist' 12:17:12 INFO - no previously-included directories found matching '.git*' 12:17:12 INFO - Installing collected packages: jsonschema, functools32 12:17:12 INFO - Running setup.py install for functools32 12:17:12 INFO - warning: no files found matching '*.txt' 12:17:12 INFO - no previously-included directories found matching 'build' 12:17:12 INFO - no previously-included directories found matching 'dist' 12:17:12 INFO - no previously-included directories found matching '.git*' 12:17:12 INFO - Successfully installed jsonschema functools32 12:17:12 INFO - Cleaning up... 12:17:12 INFO - Return code: 0 12:17:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:12 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 0x7f4d02c48f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2216240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, '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': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', '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 12:17:12 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 12:17:13 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 12:17:13 INFO - Using env: {'DISPLAY': ':0', 12:17:13 INFO - 'HOME': '/home/cltbld', 12:17:13 INFO - 'LANG': 'en_US.UTF-8', 12:17:13 INFO - 'LANGUAGE': 'en_US:en', 12:17:13 INFO - 'LOGNAME': 'cltbld', 12:17:13 INFO - 'MAIL': '/var/mail/cltbld', 12:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:13 INFO - 'MOZ_NO_REMOTE': '1', 12:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:13 INFO - 'NO_EM_RESTART': '1', 12:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:13 INFO - 'PWD': '/builds/slave/test', 12:17:13 INFO - 'SHELL': '/bin/bash', 12:17:13 INFO - 'SHLVL': '1', 12:17:13 INFO - 'TERM': 'linux', 12:17:13 INFO - 'TMOUT': '86400', 12:17:13 INFO - 'USER': 'cltbld', 12:17:13 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:13 INFO - '_': '/tools/buildbot/bin/python'} 12:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:13 INFO - Downloading/unpacking flake8 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:17:14 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 12:17:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17: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 12:17:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:17:15 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 12:17:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 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 12:17:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 12:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:17:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:17:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:17:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:17:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:17:15 INFO - no previously-included directories found matching 'docs/_build' 12:17:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:17:15 INFO - Running setup.py install for pep8 12:17:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:17:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:17:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:17:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:17:15 INFO - no previously-included directories found matching 'docs/_build' 12:17:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:17:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:17:15 INFO - Cleaning up... 12:17:15 INFO - Return code: 0 12:17:15 INFO - Running post-action listener: _resource_record_post_action 12:17:15 INFO - Running post-action listener: _start_resource_monitoring 12:17:15 INFO - Starting resource monitoring. 12:17:15 INFO - ##### 12:17:15 INFO - ##### Running install step. 12:17:15 INFO - ##### 12:17:15 INFO - Running pre-action listener: _resource_record_pre_action 12:17:15 INFO - Running main action method: install 12:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:16 INFO - Reading from file tmpfile_stdout 12:17:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:17:16 INFO - Reading from file tmpfile_stdout 12:17:16 INFO - Output received: 12:17:16 INFO - Usage: mozinstall [options] installer 12:17:16 INFO - Options: 12:17:16 INFO - -h, --help show this help message and exit 12:17:16 INFO - -d DEST, --destination=DEST 12:17:16 INFO - Directory to install application into. [default: 12:17:16 INFO - "/builds/slave/test"] 12:17:16 INFO - --app=APP Application being installed. [default: firefox] 12:17:16 INFO - mkdir: /builds/slave/test/build/application 12:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:17:30 INFO - Reading from file tmpfile_stdout 12:17:30 INFO - Output received: 12:17:30 INFO - /builds/slave/test/build/application/firefox/firefox 12:17:30 INFO - Running post-action listener: _resource_record_post_action 12:17:30 INFO - ##### 12:17:30 INFO - ##### Running run-tests step. 12:17:30 INFO - ##### 12:17:30 INFO - Running pre-action listener: _resource_record_pre_action 12:17:30 INFO - Running main action method: run_tests 12:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:17:30 INFO - Python 2.7.3 12:17:30 INFO - Return code: 0 12:17:30 INFO - grabbing minidump binary from tooltool 12:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a50b0>, '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 0x22a5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a6440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:17:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:17:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:17:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:17:30 INFO - Return code: 0 12:17:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:17:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:17:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:17:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:17:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:17:31 INFO - Return code: 0 12:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] in /builds/slave/test/build 12:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost 12:17:31 INFO - Using env: {'DISPLAY': ':0', 12:17:31 INFO - 'HOME': '/home/cltbld', 12:17:31 INFO - 'LANG': 'en_US.UTF-8', 12:17:31 INFO - 'LANGUAGE': 'en_US:en', 12:17:31 INFO - 'LOGNAME': 'cltbld', 12:17:31 INFO - 'MAIL': '/var/mail/cltbld', 12:17:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:17:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:31 INFO - 'MOZ_NO_REMOTE': '1', 12:17:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:31 INFO - 'NO_EM_RESTART': '1', 12:17:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:31 INFO - 'PWD': '/builds/slave/test', 12:17:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:17:31 INFO - 'SHELL': '/bin/bash', 12:17:31 INFO - 'SHLVL': '1', 12:17:31 INFO - 'TERM': 'linux', 12:17:31 INFO - 'TMOUT': '86400', 12:17:31 INFO - 'USER': 'cltbld', 12:17:31 INFO - 'XDG_SESSION_COOKIE': 'f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757', 12:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:31 INFO - '_': '/tools/buildbot/bin/python'} 12:17:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost'] with output_timeout 3600 12:17:31 INFO - mozversion INFO | application_buildid: 20151210111049 12:17:31 INFO - mozversion INFO | application_changeset: 50a0251369dfdc40f5e1a9a77684b77add683117 12:17:31 INFO - mozversion INFO | application_display_name: Nightly 12:17:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:17:31 INFO - mozversion INFO | application_name: Firefox 12:17:31 INFO - mozversion INFO | application_remotingname: firefox 12:17:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 12:17:31 INFO - mozversion INFO | application_vendor: Mozilla 12:17:31 INFO - mozversion INFO | application_version: 45.0a1 12:17:31 INFO - mozversion INFO | platform_buildid: 20151210111049 12:17:31 INFO - mozversion INFO | platform_changeset: 50a0251369dfdc40f5e1a9a77684b77add683117 12:17:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 12:17:31 INFO - mozversion INFO | platform_version: 45.0a1 12:17:31 INFO - 2015-12-10 12:17:31,848 DEBUG : using testdate: 1449778651 12:17:31 INFO - 2015-12-10 12:17:31,848 DEBUG : actual date: 1449778651 12:17:31 INFO - 2015-12-10 12:17:31,856 INFO : starting webserver on 'localhost:48659' 12:17:31 INFO - 2015-12-10 12:17:31,857 INFO : Starting test suite talos-linux64-ix-047.e 12:17:31 INFO - 2015-12-10 12:17:31,858 INFO : Starting test dromaeo_css 12:17:31 INFO - 2015-12-10 12:17:31,858 DEBUG : operating with platform_type : linux_ 12:17:31 INFO - 2015-12-10 12:17:31,858 INFO : Initialising browser for dromaeo_css test... 12:17:31 INFO - 2015-12-10 12:17:31,874 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJGcXza/profile http://localhost:48659/getInfo.html 12:17:36 INFO - 2015-12-10 12:17:36,563 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:17:36 INFO - 2015-12-10 12:17:36,564 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:17:36 INFO - 2015-12-10 12:17:36,564 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:17:36 INFO - 2015-12-10 12:17:36,564 DEBUG : BROWSER_OUTPUT: __metrics 12:17:36 INFO - 2015-12-10 12:17:36,700 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:17:37 INFO - 2015-12-10 12:17:37,637 INFO : Browser initialized. 12:17:37 INFO - 2015-12-10 12:17:37,637 INFO : Running cycle 1/1 for dromaeo_css test... 12:17:37 INFO - 2015-12-10 12:17:37,638 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJGcXza/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:17:49 INFO - 2015-12-10 12:17:49,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 143859712 12:17:49 INFO - 2015-12-10 12:17:49,786 DEBUG : BROWSER_OUTPUT: 12:18:39 INFO - 2015-12-10 12:18:39,402 DEBUG : BROWSER_OUTPUT: 1449778719399 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] 12:20:51 INFO - 2015-12-10 12:20:51,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48659/tests/dromaeo/cssquery-ext.html) 12:20:52 INFO - 2015-12-10 12:20:52,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 144666624 12:20:52 INFO - 2015-12-10 12:20:52,133 DEBUG : BROWSER_OUTPUT: 12:23:54 INFO - 2015-12-10 12:23:54,105 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-ext.html (next: http://localhost:48659/tests/dromaeo/cssquery-jquery.html) 12:23:54 INFO - 2015-12-10 12:23:54,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 144441344 12:23:54 INFO - 2015-12-10 12:23:54,707 DEBUG : BROWSER_OUTPUT: 12:26:56 INFO - 2015-12-10 12:26:56,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48659/tests/dromaeo/cssquery-mootools.html) 12:26:57 INFO - 2015-12-10 12:26:57,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 145035264 12:26:57 INFO - 2015-12-10 12:26:57,158 DEBUG : BROWSER_OUTPUT: 12:29:58 INFO - 2015-12-10 12:29:58,908 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48659/tests/dromaeo/cssquery-prototype.html) 12:29:59 INFO - 2015-12-10 12:29:59,511 DEBUG : BROWSER_OUTPUT: RSS: Main: 143880192 12:29:59 INFO - 2015-12-10 12:29:59,512 DEBUG : BROWSER_OUTPUT: 12:33:02 INFO - 2015-12-10 12:33:02,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48659/tests/dromaeo/cssquery-yui.html) 12:33:02 INFO - 2015-12-10 12:33:02,728 DEBUG : BROWSER_OUTPUT: RSS: Main: 143863808 12:33:02 INFO - 2015-12-10 12:33:02,728 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,163 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/dromaeo/cssquery-yui.html (next: http://localhost:48659/tests/dromaeo/cssquery-dojo.html) 12:36:05 INFO - 2015-12-10 12:36:05,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 146669568 12:36:05 INFO - 2015-12-10 12:36:05,410 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,411 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:36:05 INFO - 2015-12-10 12:36:05,411 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:36:05 INFO - 2015-12-10 12:36:05,411 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:36:05 INFO - 2015-12-10 12:36:05,411 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:36:05 INFO - 2015-12-10 12:36:05,413 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2632.723564025777;2690.811643184978;2717.152336411241;2727.87724552395;2741.040635777478;4465.196264672358;4693.4367875854905;4732.550407711271;4751.311059896321;4763.571278584926;4623.42207224097;4637.837675681352;4654.348391225222;4766.022965292115;4769.379980602517;4269.573042695729;4333.739975601458;4389.429374181352;4395.05506316142;4435.024294655187;4283.764392958386;4315.072259464215;4377.846775362863;4406.559344065584;4438.378626992224;4170.4995400551825;4270.551592082848;4278.679099067583;4359.171757366106;4381.561843815625;4318.201132790444;4352.89117772055;4361.520232774392;4369.191699535596;4371.388005679208;4286.549912259199;4311.91376172478;4332;4340.305551111807;4381.452318460192;4366.235908715962;4448.399466072071;4452.30989196675;4531.252343363341;4620.983383655591;5033.219850923113;5041.369828771403;5057.974710126463;5061;5118;5100.387953445573;5104.05574968632;5108.821191258288;5116.181410974264;5118.667286626357;4397.340398940152;4511.909761804745;4650.348951146842;4702.6708130431225;4732.574068333866;5212.40057393397;5223.869403264873;5225.111731005738;5230.529252367229;5242.397124330674;4664.160451118832;4999.47505511916;5006.574441172468;5011.123053465629;5013.423456302499;4923.310736496894;4970.552650261494;5051.4695956924725;5107.693538387709;5131.948680513222;5018.347614810052;5044.798208071709;5068.265101560253;5080.26336181259;5116.846494605168;4733.763311834464;4858.684185527929;5034.949650503521;5044.419891712427;5058.064258112187;4078.877633670995;4146.0464093258715;4158.4178215049915;4171.749695018309;4189.2668782962855;3626.9455958160647;3666.4683620874825;3668.0646435159397;3704.759190652599;3705.9629403705617;4196.076863090177;4298.097399546068;4326.848560300375;4352.107817897323;4366.345048242789;4965.776540055706;4995.150824359867;5013.373328333958;5016.648834581617;5030.773615187325;4317.460317460317;4325.243082460557;4336.804843782037;4337.50118736343;4366.19225443294;4289.120730250291;4380.080183161508;4390.473143222834;4438.134563760036;4501.41481607389;4785.904281914342;4821.5178482151505;4830.2513110467935;4830.927536086891;4856.781444835062;5087.54212120896;5103.7448127592725;5120.897582048413;5121.667091639031;5160.509751573594;4660.533946605312;4681.976590117164;4753.168195565831;4800.687955282895;4807;4965.8013679452415;4987.127252730685;4998.750062496933;5005.824796132118;5043.067032598981;5058.418281897629;5067.265246539305;5077.365329333833;5078.21287700407;5104.285400043923;4715.882102947454;4813.061453016629;4857.514248575114;4872.4640289567415;4946.752662366939;4830.95169048305;4847.975760121177;4867.95132048675;4870.683405578626;4906.558409742997;4589.242774942097;4704.270838020112;4763.976180119077;4774.307725379731;4789.3294938709305;4826.493218212037;4833.226683730727;4879.048585525788;4899.240617704261;4936.901261974668;4726.787294571821;4766;4788.928166077442;4799.9760001199775;4824.421069471686 12:36:05 INFO - 2015-12-10 12:36:05,414 DEBUG : BROWSER_OUTPUT: |1;ext.html;568.7525926222094;578.6643746626956;585.2538014032109;590.309338074453;606.0969155957624;35008.4748728769;35759.4636080459;35765.28469430617;35787.284254314894;35831;36908.81545592268;37105.257894842085;37573.43639845405;37746;37767.24465510688;7736.845263094735;7950.920490795076;7954.443188976774;7969.481983671072;7994.080680721704;7707.383409327241;7785.844283114335;7811.296983271504;7818.296353328199;7846.568438735867;7778.766637000871;7872.960635196816;7878.409119316046;7881.05427348719;7909.3672506199655;7684.884726729104;7697.191794861543;7735.0331208598645;7736.883946740803;7772.65023073963;7664.770056898302;7758.922410775876;7803.921960780376;7806;7807.804804879924;34532.482012769826;34814.825925870464;34970.65029349699;35036.64963350359;35055;2694.2994821346397;2698.190542837141;2782.4713304472084;2889.2054684961595;2894.3343031102754;27463.17610471689;27744.445111097866;27933.86033069842;28093.29766755827;28748.568771468446;3315.4529502632035;3321.3523362944234;3325.1354647791504;3425.486177073434;3507.9824600877087;215095;215591;215918;216159;216415;51218.74390628023;52561.10913899696;61485.692571536856;62127.68936155381;62898.685506572176;53712.73143634335;54075.45924540783;54157;54295;54362.728186358814;13595.796063059062;13744.793828092586;13799.655008624864;13886.583402497941;13892.86107138936;10216.131628811634;10278.588856445658;10281.383117013003;10297.485125743682;10332.070113689804;18753.81246187548;18771.718424223647;19070;19106.71339929902;19176.232950682097;7852.371810255166;7986.440949133621;8029.799255018671;8047.798805029921;8074.394420418492;818.5129847740585;827.3877330775183;853.5177624642057;877.140402405646;893.3701740273092;26653.733462665514;26740.19779406621;26921.596176057374;27116;27176;945.4658118163215;947.8815148106487;952.3999880075509;958.1472489484364;1075.3010243814754;13802.17186968785;14076.71846563063;14092.859071409155;14103.294835258195;14202.644933876736;259406;263462;264800;266863;267685;45858;46349.536504635194;46412.535874640824;46458;46473.53526464692;1157.6816375496637;1169.8654654714646;1184.59131599598;1195.2947760821157;1200.1838749650321;25485.490290194462;26212.8689356552;26388.736112638628;26458.338541536614;26655.866720666272;40761.184776303715;41229.38155927604;41258.174836502505;41331.966700831515;41380.37929431121;249136.82337000832;249429;252042;252316;252589;26156.34609134787;26700.866495667397;28406.715932841234;28886.277843054093;29098.563521547618;33452.498212527316;33912.83043584766;34040.31919361649;34652;34726.47910281297;31004;31322.530162048042;31972;32461;32812;31939.201519962186;37216.813915930245;40382.596174037884;41117.79441102775;42280;34905.65094349024;35789.82105089562;37081.44377834281;37363.813180933925;37587.81206094062;35623;36769.448458273684;37058;37089.814550927076;37436.625633743315;28896.42207155887;29061.273468163465;29319.267018324714;29991.850040749658;29999 12:36:05 INFO - 2015-12-10 12:36:05,418 DEBUG : BROWSER_OUTPUT: |2;jquery.html;343.5345107379501;344.3732407019225;345.62844941687695;345.9221675123096;347.0369724015857;14951.850481495181;15134.848651513481;16180.514584562452;16394.67210655786;16770.07764572948;9871.160951319129;9966.152877005445;10003.349782264148;12432.813507797367;14465.34905929233;4530.252508336121;4692.530746925314;4702.717836929795;4707.670463067587;5038.345015148026;5122.564582010515;5136.383633963929;5140.820071297505;5145;5148.150555158394;4973.030259099477;5151.459096794839;5154.819581314655;5181.222816577506;5200.167973124301;5078.339815823956;5153.587712982952;5155.5360017598405;5164.741762911858;5179.715115668637;5166.940777140677;5186.0665080285535;5186.948130518685;5195.272661827347;5200.167973124282;11307.660770176908;11437.428128593538;14391.424343026267;17608.295668173258;17772.111394430354;1762.207006846924;1763.6913540130427;1770.1503278426428;1858.7397764313007;1867.8505719542404;8594.269487093605;9139.634414623408;10684.839727404094;12615.369231538387;12897.09720319578;1697.8896371735798;1712.8544073753746;1716.3048965169125;1809.4209852847093;1847.0764617691154;58009.70995145039;59184.70407647934;59459.70270148665;59825;60106.39893601052;9624.181944534654;9833.164181044549;9990.30067895255;10080.495975201211;10145.594176233015;12825.486980520862;12897.677558061123;12905.741885162244;12914.354282285849;12921;7158.14102307716;7244.673989670478;7276.890846637305;7278.1266248049515;7301.853962920818;6945.89581156283;6951.82620434483;6974.58152510851;6983.336583024605;7027.332403421667;4222.19778242133;4227.7040607157205;4268.509121451011;4277.059047009653;4306.5262821089655;1781.5457058450013;1784.5627821183894;1825.0600940515644;1830.304484295959;1831.3407173417559;801.9719309824245;853.5860107847689;965.6716716316486;989.8317286061387;995.785906030207;10318.793624127475;15212.315445804965;15529.922350388175;16047.839521604868;16178;408.5403920589337;409.32870093047427;416.0410254363721;419.44004753653604;421.1639894808802;5191.8182863599595;5375.731213439312;5550.417206193372;5571.220029195916;5624.887502249932;507266;515598;516053;530555;536326;74651.88022179672;75751;76518;77520.61239693878;77822.61088694634;468.82419092840183;469.59380136182637;471.8843883248626;472.86759707282016;473.76548608438355;9758;9760.365576237522;9807.705768826947;9825.705228843146;9848;31863.84068079645;32644.34711305713;33281.66718332786;33563.83218083894;33781.66218337785;45796;48099.27851082166;48107.27839082487;48257.75871120622;49348.75325623349;8159.79600509992;8319.251267385722;8576.871346929676;8607.225349718556;8664.436811607226;10556.102731267621;10599;10603.469826508797;10711.571537138427;10767.515461804394;10282.742931426774;14377.209253491159;14495.492657756928;14650.534946505264;14735;14017.719645606827;14083.929580352442;14275.785863211853;14650.487232946796;14674.119552826865;13432.597022089352;13577.796333054814;13674.179549227078;13820.170789752647;14112;9106.817863642558;9289.674861379819;9310.767230819283;9366.812663746823;9435.811283774148;9989.800203996025;11334.433278336215;14570.4900328488;14757.852421475647;14851.405943762129 12:36:05 INFO - 2015-12-10 12:36:05,419 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1443.5019918128241;1676.2456894397521;1739.4086010756341;1748.7202047672374;1769.0447158534384;6085.2393450818645;6149.4772944299675;6765.864682706349;7204.5677259364475;7252.782416527506;6580.111684922534;6737.2925842786535;6814.5570537915;6837.213720422153;6862.82842928927;2059.052835695582;2089.0703636881594;2101.9159233630635;2102.2431924507164;2105.821005214551;2114.6616541353387;2120.6182887080317;2122.3314655883423;2138.540213854021;2140.8180304674042;2092.550101728128;2094.968575471369;2101.3275751759443;2111.092230340953;2134.3063504361066;2048.1090725534364;2076.0761461149796;2078.293380250722;2078.906449209782;2086.5201003769066;2015.042854644047;2049.7130401743766;2055.085486958304;2058.5985732782115;2081.573277478113;6970.059042029312;6991.196012458581;7005.439564834801;7008.229094799619;7011.579305241651;4185.058361868586;4274.615284624399;4278.358246263086;4288.571142885687;4293.22721910056;2626.487834872201;2677.692065412483;2690.744379283965;2744.821586596865;2753.600727894464;863.8488264553678;875.951822649754;882.4572887674034;888.5157589113949;903.5933829776561;9427.292953028551;9435.669751558731;9451.385659932082;9451.574679139456;9490.00354962726;3862.806859657006;3895.5325360956867;3914.9804250978564;3923.548791888912;3935.5670876203594;8181.713640022571;8207.835843283101;8257.587120643944;8258.876116858131;8261.8347633047;4194.433751443577;4198.433211516406;4200.411942328076;4205.621494065549;4209.452771139732;3299.6205436374644;3419.897403077912;3811;3870.767753934773;3877.7673339599714;3467.358538670354;3503.632118627558;3515.402381595135;3530.5586801649793;3547.627499112556;1130.66645339625;1151.4645689754304;1156.2195518025467;1160.9941950290363;1162.2213117211488;845.8519759042143;852.8933883264592;860.1484530315068;861.8276344731079;1068.2255364860414;6579.934200657932;6587.209534855848;6604.801855944329;6623.768168114093;6637.269900310962;428.04759410311806;440;441.8343121329501;442.65694087082767;442.99335509967375;4872.19608764557;4913.926291105637;4914.33656456381;4918.458969513351;4921.114199444063;8150.633221505046;8246.340292776564;8386.8322633547;8649.308055355556;8667.219950204386;8452.74641760748;8471.703490377808;8481.915180848113;8523.531205783742;8530.70142545008;6169.76604679066;6555.180602424697;6627.966860165765;6687.398134167948;6694.564853284521;6736.5958042517605;6787.490938179558;6810.489213309121;6849.760258390932;6891.965540172267;7600.1639819618695;7709.190534993969;7750.728724494616;7761.805954851174;7853.725119620786;7720.884186737316;7845.725399611215;7953.085395179397;7992.240737129963;7993.760187194393;5004.799808007639;5145.3568303962;5779.711014449176;6045.063015232646;6191.102290167991;6752.291009443938;6770.763023294358;6776.08522849409;6777.864442711217;6793.932060679527;6240.282367527792;6329.5252856036905;6369.617822930639;6421.8073457796345;6426.811039957623;6123.510119190365;6173.197484327188;6248.4064013918605;6262.436380725757;6279.623222606476;6257.56197066201;6313.210848643919;6324;6358.491320694426;6377.553571250153;6001.2498437695285;6020.217371741645;6025.849353766191;6035;6036.607620504653;5745.6552606843725;5859.970700146642;5864.296284445893;5920.319163296276;5924.555658325557 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: |4;prototype.html;310.13008511126293;313.03725640235723;318.3315038419318;323.50826743350103;323.7992444684296;5476.205950137232;5491.890162196763;5551.916721249185;5580.6093573449825;5610.775568977236;5473.124300111983;5493.7802487900535;5515.034868897937;5519.98520540003;5533.861653458665;4120.278951183546;4193.748375097489;4196.454460920091;4251.4898212214575;4257.084726783741;4199.055212577161;4208.6633069354375;4214.620684138427;4239.215745087154;4254.468191476066;4182.121754431573;4197.18154959783;4200.97899510502;4201.243776120297;4206.390073439349;4185.660588611646;4200.810963506649;4204.579542045802;4207.43199668044;4226.725262857904;3577.8926632201074;3664.2305115925687;3670.541182352206;3673.797941113238;3678.779273243614;5206.297149884755;5295.152775555931;5303.6552624079695;5306.548943339821;5318.44156363584;4824.396950381202;4847.903041939177;4852.102361063215;4853.563179313844;4859.538343857328;5798.594098413113;5803.709814509258;5805.245318108661;5808;5827.7668893244645;3910.4134379842967;3948.624880636335;3976.005998500375;3995.920081598381;4007.5391329997133;6007.669578173199;6059.303180134297;6080.695965201722;6087.939120608781;6094.238220222428;4082.714210005271;4098.979505102456;4104.076582768913;4104.15864747726;4131.648809851136;4171.749695018248;4183.079722461053;4184.8744537663915;4187.811548480326;4188.141431006637;4699.365585645963;4765.428148622118;4801.615870730333;4808.08646357191;4812.25410061441;5755.366909639936;5765.135229715576;5767.36558978512;5769.740361683735;5783.826485205451;1456.7013762178728;1474.5502621700484;1477.5050358129931;1479.142097583399;1479.7114562660392;977.9022097790164;981.2983716642635;988.7379844341256;1006.5772375602264;1010.4594042187395;5028.170351891897;5030.748462576857;5042.39491261051;5044.394672639307;5053.166227572483;5967.164596956342;5988.401159883977;6006.249218847644;6007.579469437185;6008.909866352008;5026.597872170218;5031.84904452867;5042.873928151826;5045.84862454127;5046.722430266297;3142.6543080261154;3219;3234.6603606620965;3242.3191129862994;3255.202475393544;5585.441455854463;5630.324361076697;5640.125780504029;5640.915386269209;5723.6851973141875;6199.876002480015;6216.968915155395;6219.813405597749;6229.314775374705;6245.843853903689;5682.7442765075675;5694.857628559319;5708.31500219968;5709.286339207599;5709.314882213995;5896.882062358814;6038.456538911519;6048.727807248596;6065.2418447694035;6069.544784141119;5948.137520059654;5955;5958.1658567801405;5961;5963.254593175853;5669.801556945652;5678.630888992202;5688.3742788294085;5702.572307077069;5708.600397972102;4004.899877503086;4043.9191216174922;4047.21079389528;4047.372657238133;4055.5538890721405;5873.4126587340925;6035.305939816977;6039.75840966374;6041.456268935817;6055.818325450244;5777.6822274775295;5811.82564523065;5812.622179558315;5822.388649191774;5827.155062515996;5787.797427090031;5795.7391917362975;5809.9419005809405;5810.6513609183585;5830.271216097988;5584.525315348078;5692.31692196939;5701.629394089371;5705.172749951317;5716.456936591017;5847.473727364558;5864.970675146597;5872.559558033079;5874.236349274567;5900.203472531153;5660.264165658603;5694.971525142348;5721.427857214412;5726.656400615976;5729.9713501432225 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: |5;yui.html;615.1695211464527;667.7295695243427;735.4557627355758;793.0206195348745;809.668036105197;11554.942225288873;11756.823647645293;11757.35334556599;11818.527258909644;11838.644840654773;11400.62223285868;11931.7613647727;12087.456064477097;12116.515339386415;12118.636440906786;637.3117033603697;643.3212960326854;809.5547448903109;825.0553116681397;825.4923222218353;810.2828996338225;823.6499487717726;826.68999125328;828.0642873552877;828.159418190537;856.4775486952954;864.2524216552699;868.7784614923218;868.9000764912021;869.1091631078132;846.7502086884354;852.6078004118114;858.159004175908;858.3862538285093;860.9913900861005;633.4932054356497;634.7429291137097;638.5913015669975;638.7285403703407;639.389383139101;11567.13246506507;11652.941735291353;11667.94166029173;11669.591564295293;11682.299062056303;10882.61910833117;11530.942345288304;11588.826167607493;11773.470193841213;11883.643490695293;9418.576164072632;9432.103950124727;9502.572384242725;9532.857007144898;10057.195424366033;3219.8390080495883;3256.4301247281633;3288.671132886716;3291.901242962715;3373.9662603374145;39916.40125398121;40169.39745903814;40469.595304046874;40774.388384174265;41083.7945810272;9361.344705870524;9509.33434659581;9545.713628591015;9615.547865514176;9669.758256043515;15379.077255364715;15467.5359739208;15600.92199538995;15618.141002244758;15641.60895977587;6234.127222188898;6574.868502629921;6611.50413718973;6641.900371494431;6662.433693136138;3530.135116896377;3618.6381361863605;3677.889663310105;3763.41667041609;3780.905477363088;2743.231895069384;2883.567464880243;2966.525355943039;2975.538791487323;2984.7313741763346;514.6140394703972;519.870032491877;527.5858451115855;528.9656172348786;539.8893226888479;419.96010379013944;443.68516180147805;459.4348950790546;475.6694097602202;547.6823442403396;7165.176004759415;7189.245129261457;7198.5320954137815;7305.4886157969495;7352.19125896151;521.455079441984;536.2037374498866;543.2421771628635;547.6823442403396;564.8023191882891;3416.5558477398276;3454.671806178409;3456.844442000116;3461.1866211440292;3579.749417540799;47817.521824782;48344.51655483471;49028;49065.75467122713;49079.509204907496;43449;43555.56444435578;44144.55855441404;44214.778926105806;44312.3353149703;40300.178987470594;40804.38793418101;41017.15527467369;41097.383539246934;41194.79402603028;6793.864122717617;6809.455243580601;6892.689828957809;6954.582725036514;7030.613316267654;31678.841605791822;31777.8411107943;31864.044078677678;32661;32792.50811237881;36126.82056673639;39639.40540891831;39749.00627484336;40170;40270.59729402669;8692.130786921258;8823.161799629283;8901.198892099743;9004.099590041205;9013.233875120688;11209;11682.824757628812;11701.648950531498;11801.586944456903;12022.218555793845;10452.268341216042;11018.338899666045;11035.558577656804;11187.664370068911;11190.384528850991;10012.299139060196;10222.897771022492;10410.843837342598;10543.472826358804;10717.089047431056;9998.167745385961;10252.487375631039;10423.009814067651;10691.625793097204;10901.563937442414;8757.124287571192;9023.909760902307;9500.809983800147;9681.419114853132;9835.592300762208;7938.64276107565;8335.624896879775;8594.183552562497;9096.590653420479;9102.863457048283 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,325 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: __startTimestamp1449779765409__endTimestamp 12:36:05 INFO - 2015-12-10 12:36:05,423 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4635.42 Median:4763.98 stddev:492.80 (10.3%) stddev-sans-first:468.59 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: Values: 2632.7 2690.8 2717.2 2727.9 2741.0 4465.2 4693.4 4732.6 4751.3 4763.6 4623.4 4637.8 4654.3 4766.0 4769.4 4269.6 4333.7 4389.4 4395.1 4435.0 4283.8 4315.1 4377.8 4406.6 4438.4 4170.5 4270.6 4278.7 4359.2 4381.6 4318.2 4352.9 4361.5 4369.2 4371.4 4286.5 4311.9 4332.0 4340.3 4381.5 4366.2 4448.4 4452.3 4531.3 4621.0 5033.2 5041.4 5058.0 5061.0 5118.0 5100.4 5104.1 5108.8 5116.2 5118.7 4397.3 4511.9 4650.3 4702.7 4732.6 5212.4 5223.9 5225.1 5230.5 5242.4 4664.2 4999.5 5006.6 5011.1 5013.4 4923.3 4970.6 5051.5 5107.7 5131.9 5018.3 5044.8 5068.3 5080.3 5116.8 4733.8 4858.7 5034.9 5044.4 5058.1 4078.9 4146.0 4158.4 4171.7 4189.3 3626.9 3666.5 3668.1 3704.8 3706.0 4196.1 4298.1 4326.8 4352.1 4366.3 4965.8 4995.2 5013.4 5016.6 5030.8 4317.5 4325.2 4336.8 4337.5 4366.2 4289.1 4380.1 4390.5 4438.1 4501.4 4785.9 4821.5 4830.3 4830.9 4856.8 5087.5 5103.7 5120.9 5121.7 5160.5 4660.5 4682.0 4753.2 4800.7 4807.0 4965.8 4987.1 4998.8 5005.8 5043.1 5058.4 5067.3 5077.4 5078.2 5104.3 4715.9 4813.1 4857.5 4872.5 4946.8 4831.0 4848.0 4868.0 4870.7 4906.6 4589.2 4704.3 4764.0 4774.3 4789.3 4826.5 4833.2 4879.0 4899.2 4936.9 4726.8 4766.0 4788.9 4800.0 4824.4 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40816.44 Median:35022.56 stddev:63669.17 (181.8%) stddev-sans-first:63776.06 12:36:05 INFO - 2015-12-10 12:36:05,424 DEBUG : BROWSER_OUTPUT: Values: 568.8 578.7 585.3 590.3 606.1 35008.5 35759.5 35765.3 35787.3 35831.0 36908.8 37105.3 37573.4 37746.0 37767.2 7736.8 7950.9 7954.4 7969.5 7994.1 7707.4 7785.8 7811.3 7818.3 7846.6 7778.8 7873.0 7878.4 7881.1 7909.4 7684.9 7697.2 7735.0 7736.9 7772.7 7664.8 7758.9 7803.9 7806.0 7807.8 34532.5 34814.8 34970.7 35036.6 35055.0 2694.3 2698.2 2782.5 2889.2 2894.3 27463.2 27744.4 27933.9 28093.3 28748.6 3315.5 3321.4 3325.1 3425.5 3508.0 215095.0 215591.0 215918.0 216159.0 216415.0 51218.7 52561.1 61485.7 62127.7 62898.7 53712.7 54075.5 54157.0 54295.0 54362.7 13595.8 13744.8 13799.7 13886.6 13892.9 10216.1 10278.6 10281.4 10297.5 10332.1 18753.8 18771.7 19070.0 19106.7 19176.2 7852.4 7986.4 8029.8 8047.8 8074.4 818.5 827.4 853.5 877.1 893.4 26653.7 26740.2 26921.6 27116.0 27176.0 945.5 947.9 952.4 958.1 1075.3 13802.2 14076.7 14092.9 14103.3 14202.6 259406.0 263462.0 264800.0 266863.0 267685.0 45858.0 46349.5 46412.5 46458.0 46473.5 1157.7 1169.9 1184.6 1195.3 1200.2 25485.5 26212.9 26388.7 26458.3 26655.9 40761.2 41229.4 41258.2 41332.0 41380.4 249136.8 249429.0 252042.0 252316.0 252589.0 26156.3 26700.9 28406.7 28886.3 29098.6 33452.5 33912.8 34040.3 34652.0 34726.5 31004.0 31322.5 31972.0 32461.0 32812.0 31939.2 37216.8 40382.6 41117.8 42280.0 34905.7 35789.8 37081.4 37363.8 37587.8 35623.0 36769.4 37058.0 37089.8 37436.6 28896.4 29061.3 29319.3 29991.9 29999.0 12:36:05 INFO - 2015-12-10 12:36:05,425 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,425 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27160.83 Median:2581.06 stddev:85309.60 (3305.2%) stddev-sans-first:85525.15 12:36:05 INFO - 2015-12-10 12:36:05,425 DEBUG : BROWSER_OUTPUT: Values: 343.5 344.4 345.6 345.9 347.0 14951.9 15134.8 16180.5 16394.7 16770.1 9871.2 9966.2 10003.3 12432.8 14465.3 4530.3 4692.5 4702.7 4707.7 5038.3 5122.6 5136.4 5140.8 5145.0 5148.2 4973.0 5151.5 5154.8 5181.2 5200.2 5078.3 5153.6 5155.5 5164.7 5179.7 5166.9 5186.1 5186.9 5195.3 5200.2 11307.7 11437.4 14391.4 17608.3 17772.1 1762.2 1763.7 1770.2 1858.7 1867.9 8594.3 9139.6 10684.8 12615.4 12897.1 1697.9 1712.9 1716.3 1809.4 1847.1 58009.7 59184.7 59459.7 59825.0 60106.4 9624.2 9833.2 9990.3 10080.5 10145.6 12825.5 12897.7 12905.7 12914.4 12921.0 7158.1 7244.7 7276.9 7278.1 7301.9 6945.9 6951.8 6974.6 6983.3 7027.3 4222.2 4227.7 4268.5 4277.1 4306.5 1781.5 1784.6 1825.1 1830.3 1831.3 802.0 853.6 965.7 989.8 995.8 10318.8 15212.3 15529.9 16047.8 16178.0 408.5 409.3 416.0 419.4 421.2 5191.8 5375.7 5550.4 5571.2 5624.9 507266.0 515598.0 516053.0 530555.0 536326.0 74651.9 75751.0 76518.0 77520.6 77822.6 468.8 469.6 471.9 472.9 473.8 9758.0 9760.4 9807.7 9825.7 9848.0 31863.8 32644.3 33281.7 33563.8 33781.7 45796.0 48099.3 48107.3 48257.8 49348.8 8159.8 8319.3 8576.9 8607.2 8664.4 10556.1 10599.0 10603.5 10711.6 10767.5 10282.7 14377.2 14495.5 14650.5 14735.0 14017.7 14083.9 14275.8 14650.5 14674.1 13432.6 13577.8 13674.2 13820.2 14112.0 9106.8 9289.7 9310.8 9366.8 9435.8 9989.8 11334.4 14570.5 14757.9 14851.4 12:36:05 INFO - 2015-12-10 12:36:05,425 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,425 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4902.75 Median:6104.37 stddev:2554.17 (41.8%) stddev-sans-first:2548.10 12:36:05 INFO - 2015-12-10 12:36:05,426 DEBUG : BROWSER_OUTPUT: Values: 1443.5 1676.2 1739.4 1748.7 1769.0 6085.2 6149.5 6765.9 7204.6 7252.8 6580.1 6737.3 6814.6 6837.2 6862.8 2059.1 2089.1 2101.9 2102.2 2105.8 2114.7 2120.6 2122.3 2138.5 2140.8 2092.6 2095.0 2101.3 2111.1 2134.3 2048.1 2076.1 2078.3 2078.9 2086.5 2015.0 2049.7 2055.1 2058.6 2081.6 6970.1 6991.2 7005.4 7008.2 7011.6 4185.1 4274.6 4278.4 4288.6 4293.2 2626.5 2677.7 2690.7 2744.8 2753.6 863.8 876.0 882.5 888.5 903.6 9427.3 9435.7 9451.4 9451.6 9490.0 3862.8 3895.5 3915.0 3923.5 3935.6 8181.7 8207.8 8257.6 8258.9 8261.8 4194.4 4198.4 4200.4 4205.6 4209.5 3299.6 3419.9 3811.0 3870.8 3877.8 3467.4 3503.6 3515.4 3530.6 3547.6 1130.7 1151.5 1156.2 1161.0 1162.2 845.9 852.9 860.1 861.8 1068.2 6579.9 6587.2 6604.8 6623.8 6637.3 428.0 440.0 441.8 442.7 443.0 4872.2 4913.9 4914.3 4918.5 4921.1 8150.6 8246.3 8386.8 8649.3 8667.2 8452.7 8471.7 8481.9 8523.5 8530.7 6169.8 6555.2 6628.0 6687.4 6694.6 6736.6 6787.5 6810.5 6849.8 6892.0 7600.2 7709.2 7750.7 7761.8 7853.7 7720.9 7845.7 7953.1 7992.2 7993.8 5004.8 5145.4 5779.7 6045.1 6191.1 6752.3 6770.8 6776.1 6777.9 6793.9 6240.3 6329.5 6369.6 6421.8 6426.8 6123.5 6173.2 6248.4 6262.4 6279.6 6257.6 6313.2 6324.0 6358.5 6377.6 6001.2 6020.2 6025.8 6035.0 6036.6 5745.7 5860.0 5864.3 5920.3 5924.6 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4793.91 Median:5504.41 stddev:1426.45 (25.9%) stddev-sans-first:1390.19 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: Values: 310.1 313.0 318.3 323.5 323.8 5476.2 5491.9 5551.9 5580.6 5610.8 5473.1 5493.8 5515.0 5520.0 5533.9 4120.3 4193.7 4196.5 4251.5 4257.1 4199.1 4208.7 4214.6 4239.2 4254.5 4182.1 4197.2 4201.0 4201.2 4206.4 4185.7 4200.8 4204.6 4207.4 4226.7 3577.9 3664.2 3670.5 3673.8 3678.8 5206.3 5295.2 5303.7 5306.5 5318.4 4824.4 4847.9 4852.1 4853.6 4859.5 5798.6 5803.7 5805.2 5808.0 5827.8 3910.4 3948.6 3976.0 3995.9 4007.5 6007.7 6059.3 6080.7 6087.9 6094.2 4082.7 4099.0 4104.1 4104.2 4131.6 4171.7 4183.1 4184.9 4187.8 4188.1 4699.4 4765.4 4801.6 4808.1 4812.3 5755.4 5765.1 5767.4 5769.7 5783.8 1456.7 1474.6 1477.5 1479.1 1479.7 977.9 981.3 988.7 1006.6 1010.5 5028.2 5030.7 5042.4 5044.4 5053.2 5967.2 5988.4 6006.2 6007.6 6008.9 5026.6 5031.8 5042.9 5045.8 5046.7 3142.7 3219.0 3234.7 3242.3 3255.2 5585.4 5630.3 5640.1 5640.9 5723.7 6199.9 6217.0 6219.8 6229.3 6245.8 5682.7 5694.9 5708.3 5709.3 5709.3 5896.9 6038.5 6048.7 6065.2 6069.5 5948.1 5955.0 5958.2 5961.0 5963.3 5669.8 5678.6 5688.4 5702.6 5708.6 4004.9 4043.9 4047.2 4047.4 4055.6 5873.4 6035.3 6039.8 6041.5 6055.8 5777.7 5811.8 5812.6 5822.4 5827.2 5787.8 5795.7 5809.9 5810.7 5830.3 5584.5 5692.3 5701.6 5705.2 5716.5 5847.5 5865.0 5872.6 5874.2 5900.2 5660.3 5695.0 5721.4 5726.7 5730.0 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12150.43 Median:22378.01 stddev:13730.11 (61.4%) stddev-sans-first:13741.29 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: Values: 615.2 667.7 735.5 793.0 809.7 11554.9 11756.8 11757.4 11818.5 11838.6 11400.6 11931.8 12087.5 12116.5 12118.6 637.3 643.3 809.6 825.1 825.5 810.3 823.6 826.7 828.1 828.2 856.5 864.3 868.8 868.9 869.1 846.8 852.6 858.2 858.4 861.0 633.5 634.7 638.6 638.7 639.4 11567.1 11652.9 11667.9 11669.6 11682.3 10882.6 11530.9 11588.8 11773.5 11883.6 9418.6 9432.1 9502.6 9532.9 10057.2 3219.8 3256.4 3288.7 3291.9 3374.0 39916.4 40169.4 40469.6 40774.4 41083.8 9361.3 9509.3 9545.7 9615.5 9669.8 15379.1 15467.5 15600.9 15618.1 15641.6 6234.1 6574.9 6611.5 6641.9 6662.4 3530.1 3618.6 3677.9 3763.4 3780.9 2743.2 2883.6 2966.5 2975.5 2984.7 514.6 519.9 527.6 529.0 539.9 420.0 443.7 459.4 475.7 547.7 7165.2 7189.2 7198.5 7305.5 7352.2 521.5 536.2 543.2 547.7 564.8 3416.6 3454.7 3456.8 3461.2 3579.7 47817.5 48344.5 49028.0 49065.8 49079.5 43449.0 43555.6 44144.6 44214.8 44312.3 40300.2 40804.4 41017.2 41097.4 41194.8 6793.9 6809.5 6892.7 6954.6 7030.6 31678.8 31777.8 31864.0 32661.0 32792.5 36126.8 39639.4 39749.0 40170.0 40270.6 8692.1 8823.2 8901.2 9004.1 9013.2 11209.0 11682.8 11701.6 11801.6 12022.2 10452.3 11018.3 11035.6 11187.7 11190.4 10012.3 10222.9 10410.8 10543.5 10717.1 9998.2 10252.5 10423.0 10691.6 10901.6 8757.1 9023.9 9500.8 9681.4 9835.6 7938.6 8335.6 8594.2 9096.6 9102.9 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:36:05 INFO - 2015-12-10 12:36:05,427 DEBUG : BROWSER_OUTPUT: 12:36:06 INFO - 2015-12-10 12:36:06,137 INFO : Browser exited with error code: 0 12:36:06 INFO - 2015-12-10 12:36:06,159 INFO : Completed test dromaeo_css (00:18:34) 12:36:06 INFO - 2015-12-10 12:36:06,159 INFO : Starting test kraken 12:36:06 INFO - 2015-12-10 12:36:06,159 DEBUG : operating with platform_type : linux_ 12:36:06 INFO - 2015-12-10 12:36:06,159 INFO : Initialising browser for kraken test... 12:36:06 INFO - 2015-12-10 12:36:06,165 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplCEzGt/profile http://localhost:48659/getInfo.html 12:36:10 INFO - 2015-12-10 12:36:10,767 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:36:10 INFO - 2015-12-10 12:36:10,767 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:36:10 INFO - 2015-12-10 12:36:10,767 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:36:10 INFO - 2015-12-10 12:36:10,767 DEBUG : BROWSER_OUTPUT: __metrics 12:36:10 INFO - 2015-12-10 12:36:10,912 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:36:11 INFO - 2015-12-10 12:36:11,869 INFO : Browser initialized. 12:36:11 INFO - 2015-12-10 12:36:11,869 INFO : Running cycle 1/1 for kraken test... 12:36:11 INFO - 2015-12-10 12:36:11,870 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplCEzGt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:36:23 INFO - 2015-12-10 12:36:23,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 143192064 12:36:23 INFO - 2015-12-10 12:36:23,899 DEBUG : BROWSER_OUTPUT: 12:36:26 INFO - 2015-12-10 12:36:26,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48659/tests/kraken/driver.html?testName=audio-beat-detection) 12:36:27 INFO - 2015-12-10 12:36:27,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 143441920 12:36:27 INFO - 2015-12-10 12:36:27,112 DEBUG : BROWSER_OUTPUT: 12:36:29 INFO - 2015-12-10 12:36:29,882 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48659/tests/kraken/driver.html?testName=audio-dft) 12:36:30 INFO - 2015-12-10 12:36:30,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 142766080 12:36:30 INFO - 2015-12-10 12:36:30,484 DEBUG : BROWSER_OUTPUT: 12:36:33 INFO - 2015-12-10 12:36:33,405 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48659/tests/kraken/driver.html?testName=audio-fft) 12:36:33 INFO - 2015-12-10 12:36:33,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 137650176 12:36:33 INFO - 2015-12-10 12:36:33,994 DEBUG : BROWSER_OUTPUT: 12:36:36 INFO - 2015-12-10 12:36:36,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48659/tests/kraken/driver.html?testName=audio-oscillator) 12:36:36 INFO - 2015-12-10 12:36:36,672 DEBUG : BROWSER_OUTPUT: RSS: Main: 138141696 12:36:36 INFO - 2015-12-10 12:36:36,672 DEBUG : BROWSER_OUTPUT: 12:36:38 INFO - 2015-12-10 12:36:38,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48659/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:36:39 INFO - 2015-12-10 12:36:39,522 DEBUG : BROWSER_OUTPUT: RSS: Main: 139755520 12:36:39 INFO - 2015-12-10 12:36:39,522 DEBUG : BROWSER_OUTPUT: 12:36:42 INFO - 2015-12-10 12:36:42,602 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48659/tests/kraken/driver.html?testName=imaging-darkroom) 12:36:43 INFO - 2015-12-10 12:36:43,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 139505664 12:36:43 INFO - 2015-12-10 12:36:43,205 DEBUG : BROWSER_OUTPUT: 12:36:46 INFO - 2015-12-10 12:36:46,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48659/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:47 INFO - 2015-12-10 12:36:47,281 DEBUG : BROWSER_OUTPUT: RSS: Main: 137306112 12:36:47 INFO - 2015-12-10 12:36:47,281 DEBUG : BROWSER_OUTPUT: 12:36:50 INFO - 2015-12-10 12:36:50,173 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48659/tests/kraken/driver.html?testName=json-parse-financial) 12:36:50 INFO - 2015-12-10 12:36:50,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 140029952 12:36:50 INFO - 2015-12-10 12:36:50,775 DEBUG : BROWSER_OUTPUT: 12:36:52 INFO - 2015-12-10 12:36:52,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48659/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:53 INFO - 2015-12-10 12:36:53,135 DEBUG : BROWSER_OUTPUT: RSS: Main: 140595200 12:36:53 INFO - 2015-12-10 12:36:53,135 DEBUG : BROWSER_OUTPUT: 12:36:55 INFO - 2015-12-10 12:36:55,030 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:55 INFO - 2015-12-10 12:36:55,651 DEBUG : BROWSER_OUTPUT: RSS: Main: 139886592 12:36:55 INFO - 2015-12-10 12:36:55,652 DEBUG : BROWSER_OUTPUT: 12:36:57 INFO - 2015-12-10 12:36:57,805 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:36:58 INFO - 2015-12-10 12:36:58,406 DEBUG : BROWSER_OUTPUT: RSS: Main: 141725696 12:36:58 INFO - 2015-12-10 12:36:58,406 DEBUG : BROWSER_OUTPUT: 12:37:01 INFO - 2015-12-10 12:37:01,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:37:01 INFO - 2015-12-10 12:37:01,649 DEBUG : BROWSER_OUTPUT: RSS: Main: 140926976 12:37:01 INFO - 2015-12-10 12:37:01,649 DEBUG : BROWSER_OUTPUT: 12:37:04 INFO - 2015-12-10 12:37:04,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:37:04 INFO - 2015-12-10 12:37:04,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 141209600 12:37:04 INFO - 2015-12-10 12:37:04,838 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48659/tests/kraken/driver.html?testName=ai-astar) 12:37:06 INFO - 2015-12-10 12:37:06,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 141144064 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:37:06 INFO - 2015-12-10 12:37:06,878 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;108;114;113;112;125;112;113;114;115 12:37:06 INFO - 2015-12-10 12:37:06,879 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;167;137;176;161;130;154;158;131;171;132 12:37:06 INFO - 2015-12-10 12:37:06,879 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;164;165;162;165;169;166;167;165;162 12:37:06 INFO - 2015-12-10 12:37:06,879 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;73;73;75;137;76;78;71;73 12:37:06 INFO - 2015-12-10 12:37:06,879 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;96;135;95;95;95;134;95;95;144 12:37:06 INFO - 2015-12-10 12:37:06,879 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;110;110;111;110;109;110;119 12:37:06 INFO - 2015-12-10 12:37:06,880 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;149;147;148;147;148;148;147;148;148;147 12:37:06 INFO - 2015-12-10 12:37:06,880 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;98;96;96;96;96;96;97;96;96;96 12:37:06 INFO - 2015-12-10 12:37:06,880 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;77;74;73;73;80;72;74;75;73;71 12:37:06 INFO - 2015-12-10 12:37:06,880 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;68;69;68;68;72;68;71;74 12:37:06 INFO - 2015-12-10 12:37:06,880 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;86;86;84;90;85;86;87;83;83 12:37:06 INFO - 2015-12-10 12:37:06,881 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;137;123;137;131;132;130;133;120;127 12:37:06 INFO - 2015-12-10 12:37:06,881 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;150;153;148;147;140;149;137;149;135 12:37:06 INFO - 2015-12-10 12:37:06,881 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;69;70;74;72;70;71;71;69;70 12:37:06 INFO - 2015-12-10 12:37:06,881 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:37:06 INFO - 2015-12-10 12:37:06,882 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:37:06 INFO - 2015-12-10 12:37:06,882 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,804 12:37:06 INFO - 2015-12-10 12:37:06,882 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:37:06 INFO - 2015-12-10 12:37:06,882 DEBUG : BROWSER_OUTPUT: __startTimestamp1449779826872__endTimestamp 12:37:06 INFO - 2015-12-10 12:37:06,883 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,883 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:37:06 INFO - 2015-12-10 12:37:06,883 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:37:06 INFO - 2015-12-10 12:37:06,883 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,883 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.90 Median:113.50 stddev:4.33 (3.8%) stddev-sans-first:4.58 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: Values: 113.0 108.0 114.0 113.0 112.0 125.0 112.0 113.0 114.0 115.0 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:151.70 Median:159.50 stddev:17.74 (11.1%) stddev-sans-first:17.93 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: Values: 167.0 137.0 176.0 161.0 130.0 154.0 158.0 131.0 171.0 132.0 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,884 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.90 Median:165.00 stddev:2.13 (1.3%) stddev-sans-first:2.24 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: Values: 164.0 164.0 165.0 162.0 165.0 169.0 166.0 167.0 165.0 162.0 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.00 Median:73.00 stddev:20.14 (27.6%) stddev-sans-first:21.15 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 73.0 73.0 75.0 137.0 76.0 78.0 71.0 73.0 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.90 Median:95.00 stddev:20.71 (21.8%) stddev-sans-first:21.43 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 135.0 95.0 95.0 95.0 134.0 95.0 95.0 144.0 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.80 Median:110.00 stddev:2.94 (2.7%) stddev-sans-first:3.10 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 110.0 110.0 111.0 110.0 109.0 110.0 119.0 12:37:06 INFO - 2015-12-10 12:37:06,885 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,886 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 12:37:06 INFO - 2015-12-10 12:37:06,887 DEBUG : BROWSER_OUTPUT: Values: 149.0 147.0 148.0 147.0 148.0 148.0 147.0 148.0 148.0 147.0 12:37:06 INFO - 2015-12-10 12:37:06,887 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,887 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.33 12:37:06 INFO - 2015-12-10 12:37:06,887 DEBUG : BROWSER_OUTPUT: Values: 98.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.20 Median:74.00 stddev:2.62 (3.5%) stddev-sans-first:2.57 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: Values: 77.0 74.0 73.0 73.0 80.0 72.0 74.0 75.0 73.0 71.0 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:2.06 (3.0%) stddev-sans-first:2.17 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 68.0 69.0 68.0 68.0 72.0 68.0 71.0 74.0 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:2.07 (2.4%) stddev-sans-first:2.19 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 86.0 84.0 90.0 85.0 86.0 87.0 83.0 83.0 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:131.50 stddev:5.49 (4.2%) stddev-sans-first:5.81 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: Values: 129.0 137.0 123.0 137.0 131.0 132.0 130.0 133.0 120.0 127.0 12:37:06 INFO - 2015-12-10 12:37:06,888 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.90 Median:149.00 stddev:6.24 (4.2%) stddev-sans-first:6.34 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: Values: 151.0 150.0 153.0 148.0 147.0 140.0 149.0 137.0 149.0 135.0 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:70.50 stddev:1.51 (2.1%) stddev-sans-first:1.58 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: Values: 70.0 69.0 70.0 74.0 72.0 70.0 71.0 71.0 69.0 70.0 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:37:06 INFO - 2015-12-10 12:37:06,889 DEBUG : BROWSER_OUTPUT: 12:37:07 INFO - 2015-12-10 12:37:07,769 INFO : Browser exited with error code: 0 12:37:07 INFO - 2015-12-10 12:37:07,824 INFO : Completed test kraken (00:01:01) 12:37:07 INFO - 2015-12-10 12:37:07,824 INFO : Starting test v8_7 12:37:07 INFO - 2015-12-10 12:37:07,824 DEBUG : operating with platform_type : linux_ 12:37:07 INFO - 2015-12-10 12:37:07,824 INFO : Initialising browser for v8_7 test... 12:37:07 INFO - 2015-12-10 12:37:07,830 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph6brbw/profile http://localhost:48659/getInfo.html 12:37:12 INFO - 2015-12-10 12:37:12,419 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:37:12 INFO - 2015-12-10 12:37:12,420 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:37:12 INFO - 2015-12-10 12:37:12,420 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 12:37:12 INFO - 2015-12-10 12:37:12,420 DEBUG : BROWSER_OUTPUT: __metrics 12:37:12 INFO - 2015-12-10 12:37:12,564 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 12:37:13 INFO - 2015-12-10 12:37:13,469 INFO : Browser initialized. 12:37:13 INFO - 2015-12-10 12:37:13,469 INFO : Running cycle 1/1 for v8_7 test... 12:37:13 INFO - 2015-12-10 12:37:13,470 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph6brbw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:37:25 INFO - 2015-12-10 12:37:25,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 142958592 12:37:25 INFO - 2015-12-10 12:37:25,490 DEBUG : BROWSER_OUTPUT: 12:37:46 INFO - 2015-12-10 12:37:46,833 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48659/tests/v8_7/run.html (next: http://localhost:48659/tests/v8_7/run.html) 12:37:47 INFO - 2015-12-10 12:37:47,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 138371072 12:37:47 INFO - 2015-12-10 12:37:47,084 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |0;Richards;165.97510373443984 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.49751053236307 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.0083102493075 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1051.5247108307046 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |5;Earley;701.2622720897616 12:37:47 INFO - 2015-12-10 12:37:47,085 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: |7;RegExp;26315.78947368421 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: |8;Splay;607.90273556231 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: __startTimestamp1449779867084__endTimestamp 12:37:47 INFO - 2015-12-10 12:37:47,086 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,087 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:37:47 INFO - 2015-12-10 12:37:47,087 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:37:47 INFO - 2015-12-10 12:37:47,087 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,087 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.98 Median:165.98 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,087 DEBUG : BROWSER_OUTPUT: Values: 166.0 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.50 Median:191.50 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: Values: 191.5 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.01 Median:277.01 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: Values: 277.0 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: Values: 5202.1 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1051.52 Median:1051.52 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,088 DEBUG : BROWSER_OUTPUT: Values: 1051.5 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:701.26 Median:701.26 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: Values: 701.3 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: Values: 12268.3 12:37:47 INFO - 2015-12-10 12:37:47,089 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26315.79 Median:26315.79 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: Values: 26315.8 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:607.90 Median:607.90 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: Values: 607.9 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 12:37:47 INFO - 2015-12-10 12:37:47,090 DEBUG : BROWSER_OUTPUT: Values: 4830.9 12:37:47 INFO - 2015-12-10 12:37:47,091 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:37:47 INFO - 2015-12-10 12:37:47,091 DEBUG : BROWSER_OUTPUT: 12:37:47 INFO - 2015-12-10 12:37:47,993 INFO : Browser exited with error code: 0 12:37:48 INFO - 2015-12-10 12:37:48,010 INFO : Completed test v8_7 (00:00:40) 12:37:48 INFO - 2015-12-10 12:37:48,169 INFO : Completed test suite (00:20:16) 12:37:48 INFO - 2015-12-10 12:37:48,169 DEBUG : Working with test: dromaeo_css 12:37:48 INFO - 2015-12-10 12:37:48,169 DEBUG : Generating results file: dromaeo_css 12:37:48 INFO - 2015-12-10 12:37:48,170 DEBUG : Working with test: kraken 12:37:48 INFO - 2015-12-10 12:37:48,171 DEBUG : Generating results file: kraken 12:37:48 INFO - 2015-12-10 12:37:48,171 INFO : javascript benchmark 12:37:48 INFO - 2015-12-10 12:37:48,171 DEBUG : Working with test: v8_7 12:37:48 INFO - 2015-12-10 12:37:48,171 DEBUG : Generating results file: v8_7 12:37:48 INFO - 2015-12-10 12:37:48,171 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:37:48 INFO - 2015-12-10 12:37:48,226 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 12:37:48 INFO - 2015-12-10 12:37:48,226 DEBUG : process_Request line: dromaeo_css 6231.76 graph.html#tests=[[72,132,43]] 12:37:48 INFO - 2015-12-10 12:37:48,226 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:37:48 INFO - 2015-12-10 12:37:48,255 DEBUG : process_Request line: kraken 1549.10 graph.html#tests=[[232,132,43]] 12:37:48 INFO - 2015-12-10 12:37:48,255 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:37:48 INFO - 2015-12-10 12:37:48,282 DEBUG : process_Request line: v8_7 21329.00 graph.html#tests=[[230,132,43]] 12:37:48 INFO - 2015-12-10 12:37:48,283 INFO : javascript benchmark 12:37:48 INFO - 2015-12-10 12:37:48,285 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2632.723564025777, 2690.811643184978, 2717.152336411241, 2727.87724552395, 2741.040635777478, 4465.196264672358, 4693.4367875854905, 4732.550407711271, 4751.311059896321, 4763.571278584926, 4623.42207224097, 4637.837675681352, 4654.348391225222, 4766.022965292115, 4769.379980602517, 4269.573042695729, 4333.739975601458, 4389.429374181352, 4395.05506316142, 4435.024294655187, 4283.764392958386, 4315.072259464215, 4377.846775362863, 4406.559344065584, 4438.378626992224, 4170.4995400551825, 4270.551592082848, 4278.679099067583, 4359.171757366106, 4381.561843815625, 4318.201132790444, 4352.89117772055, 4361.520232774392, 4369.191699535596, 4371.388005679208, 4286.549912259199, 4311.91376172478, 4332.0, 4340.305551111807, 4381.452318460192, 4366.235908715962, 4448.399466072071, 4452.30989196675, 4531.252343363341, 4620.983383655591, 5033.219850923113, 5041.369828771403, 5057.974710126463, 5061.0, 5118.0, 5100.387953445573, 5104.05574968632, 5108.821191258288, 5116.181410974264, 5118.667286626357, 4397.340398940152, 4511.909761804745, 4650.348951146842, 4702.6708130431225, 4732.574068333866, 5212.40057393397, 5223.869403264873, 5225.111731005738, 5230.529252367229, 5242.397124330674, 4664.160451118832, 4999.47505511916, 5006.574441172468, 5011.123053465629, 5013.423456302499, 4923.310736496894, 4970.552650261494, 5051.4695956924725, 5107.693538387709, 5131.948680513222, 5018.347614810052, 5044.798208071709, 5068.265101560253, 5080.26336181259, 5116.846494605168, 4733.763311834464, 4858.684185527929, 5034.949650503521, 5044.419891712427, 5058.064258112187, 4078.877633670995, 4146.0464093258715, 4158.4178215049915, 4171.749695018309, 4189.2668782962855, 3626.9455958160647, 3666.4683620874825, 3668.0646435159397, 3704.759190652599, 3705.9629403705617, 4196.076863090177, 4298.097399546068, 4326.848560300375, 4352.107817897323, 4366.345048242789, 4965.776540055706, 4995.150824359867, 5013.373328333958, 5016.648834581617, 5030.773615187325, 4317.460317460317, 4325.243082460557, 4336.804843782037, 4337.50118736343, 4366.19225443294, 4289.120730250291, 4380.080183161508, 4390.473143222834, 4438.134563760036, 4501.41481607389, 4785.904281914342, 4821.5178482151505, 4830.2513110467935, 4830.927536086891, 4856.781444835062, 5087.54212120896, 5103.7448127592725, 5120.897582048413, 5121.667091639031, 5160.509751573594, 4660.533946605312, 4681.976590117164, 4753.168195565831, 4800.687955282895, 4807.0, 4965.8013679452415, 4987.127252730685, 4998.750062496933, 5005.824796132118, 5043.067032598981, 5058.418281897629, 5067.265246539305, 5077.365329333833, 5078.21287700407, 5104.285400043923, 4715.882102947454, 4813.061453016629, 4857.514248575114, 4872.4640289567415, 4946.752662366939, 4830.95169048305, 4847.975760121177, 4867.95132048675, 4870.683405578626, 4906.558409742997, 4589.242774942097, 4704.270838020112, 4763.976180119077, 4774.307725379731, 4789.3294938709305, 4826.493218212037, 4833.226683730727, 4879.048585525788, 4899.240617704261, 4936.901261974668, 4726.787294571821, 4766.0, 4788.928166077442, 4799.9760001199775, 4824.421069471686], "unit": "ms", "name": "dojo.html", "value": 4604.667550338754}, {"lowerIsBetter": false, "replicates": [568.7525926222094, 578.6643746626956, 585.2538014032109, 590.309338074453, 606.0969155957624, 35008.4748728769, 35759.4636080459, 35765.28469430617, 35787.284254314894, 35831.0, 36908.81545592268, 37105.257894842085, 37573.43639845405, 37746.0, 37767.24465510688, 7736.845263094735, 7950.920490795076, 7954.443188976774, 7969.481983671072, 7994.080680721704, 7707.383409327241, 7785.844283114335, 7811.296983271504, 7818.296353328199, 7846.568438735867, 7778.766637000871, 7872.960635196816, 7878.409119316046, 7881.05427348719, 7909.3672506199655, 7684.884726729104, 7697.191794861543, 7735.0331208598645, 7736.883946740803, 7772.65023073963, 7664.770056898302, 7758.922410775876, 7803.921960780376, 7806.0, 7807.804804879924, 34532.482012769826, 34814.825925870464, 34970.65029349699, 35036.64963350359, 35055.0, 2694.2994821346397, 2698.190542837141, 2782.4713304472084, 2889.2054684961595, 2894.3343031102754, 27463.17610471689, 27744.445111097866, 27933.86033069842, 28093.29766755827, 28748.568771468446, 3315.4529502632035, 3321.3523362944234, 3325.1354647791504, 3425.486177073434, 3507.9824600877087, 215095.0, 215591.0, 215918.0, 216159.0, 216415.0, 51218.74390628023, 52561.10913899696, 61485.692571536856, 62127.68936155381, 62898.685506572176, 53712.73143634335, 54075.45924540783, 54157.0, 54295.0, 54362.728186358814, 13595.796063059062, 13744.793828092586, 13799.655008624864, 13886.583402497941, 13892.86107138936, 10216.131628811634, 10278.588856445658, 10281.383117013003, 10297.485125743682, 10332.070113689804, 18753.81246187548, 18771.718424223647, 19070.0, 19106.71339929902, 19176.232950682097, 7852.371810255166, 7986.440949133621, 8029.799255018671, 8047.798805029921, 8074.394420418492, 818.5129847740585, 827.3877330775183, 853.5177624642057, 877.140402405646, 893.3701740273092, 26653.733462665514, 26740.19779406621, 26921.596176057374, 27116.0, 27176.0, 945.4658118163215, 947.8815148106487, 952.3999880075509, 958.1472489484364, 1075.3010243814754, 13802.17186968785, 14076.71846563063, 14092.859071409155, 14103.294835258195, 14202.644933876736, 259406.0, 263462.0, 264800.0, 266863.0, 267685.0, 45858.0, 46349.536504635194, 46412.535874640824, 46458.0, 46473.53526464692, 1157.6816375496637, 1169.8654654714646, 1184.59131599598, 1195.2947760821157, 1200.1838749650321, 25485.490290194462, 26212.8689356552, 26388.736112638628, 26458.338541536614, 26655.866720666272, 40761.184776303715, 41229.38155927604, 41258.174836502505, 41331.966700831515, 41380.37929431121, 249136.82337000832, 249429.0, 252042.0, 252316.0, 252589.0, 26156.34609134787, 26700.866495667397, 28406.715932841234, 28886.277843054093, 29098.563521547618, 33452.498212527316, 33912.83043584766, 34040.31919361649, 34652.0, 34726.47910281297, 31004.0, 31322.530162048042, 31972.0, 32461.0, 32812.0, 31939.201519962186, 37216.813915930245, 40382.596174037884, 41117.79441102775, 42280.0, 34905.65094349024, 35789.82105089562, 37081.44377834281, 37363.813180933925, 37587.81206094062, 35623.0, 36769.448458273684, 37058.0, 37089.814550927076, 37436.625633743315, 28896.42207155887, 29061.273468163465, 29319.267018324714, 29991.850040749658, 29999.0], "unit": "ms", "name": "ext.html", "value": 16946.52573031206}, {"lowerIsBetter": false, "replicates": [343.5345107379501, 344.3732407019225, 345.62844941687695, 345.9221675123096, 347.0369724015857, 14951.850481495181, 15134.848651513481, 16180.514584562452, 16394.67210655786, 16770.07764572948, 9871.160951319129, 9966.152877005445, 10003.349782264148, 12432.813507797367, 14465.34905929233, 4530.252508336121, 4692.530746925314, 4702.717836929795, 4707.670463067587, 5038.345015148026, 5122.564582010515, 5136.383633963929, 5140.820071297505, 5145.0, 5148.150555158394, 4973.030259099477, 5151.459096794839, 5154.819581314655, 5181.222816577506, 5200.167973124301, 5078.339815823956, 5153.587712982952, 5155.5360017598405, 5164.741762911858, 5179.715115668637, 5166.940777140677, 5186.0665080285535, 5186.948130518685, 5195.272661827347, 5200.167973124282, 11307.660770176908, 11437.428128593538, 14391.424343026267, 17608.295668173258, 17772.111394430354, 1762.207006846924, 1763.6913540130427, 1770.1503278426428, 1858.7397764313007, 1867.8505719542404, 8594.269487093605, 9139.634414623408, 10684.839727404094, 12615.369231538387, 12897.09720319578, 1697.8896371735798, 1712.8544073753746, 1716.3048965169125, 1809.4209852847093, 1847.0764617691154, 58009.70995145039, 59184.70407647934, 59459.70270148665, 59825.0, 60106.39893601052, 9624.181944534654, 9833.164181044549, 9990.30067895255, 10080.495975201211, 10145.594176233015, 12825.486980520862, 12897.677558061123, 12905.741885162244, 12914.354282285849, 12921.0, 7158.14102307716, 7244.673989670478, 7276.890846637305, 7278.1266248049515, 7301.853962920818, 6945.89581156283, 6951.82620434483, 6974.58152510851, 6983.336583024605, 7027.332403421667, 4222.19778242133, 4227.7040607157205, 4268.509121451011, 4277.059047009653, 4306.5262821089655, 1781.5457058450013, 1784.5627821183894, 1825.0600940515644, 1830.304484295959, 1831.3407173417559, 801.9719309824245, 853.5860107847689, 965.6716716316486, 989.8317286061387, 995.785906030207, 10318.793624127475, 15212.315445804965, 15529.922350388175, 16047.839521604868, 16178.0, 408.5403920589337, 409.32870093047427, 416.0410254363721, 419.44004753653604, 421.1639894808802, 5191.8182863599595, 5375.731213439312, 5550.417206193372, 5571.220029195916, 5624.887502249932, 507266.0, 515598.0, 516053.0, 530555.0, 536326.0, 74651.88022179672, 75751.0, 76518.0, 77520.61239693878, 77822.61088694634, 468.82419092840183, 469.59380136182637, 471.8843883248626, 472.86759707282016, 473.76548608438355, 9758.0, 9760.365576237522, 9807.705768826947, 9825.705228843146, 9848.0, 31863.84068079645, 32644.34711305713, 33281.66718332786, 33563.83218083894, 33781.66218337785, 45796.0, 48099.27851082166, 48107.27839082487, 48257.75871120622, 49348.75325623349, 8159.79600509992, 8319.251267385722, 8576.871346929676, 8607.225349718556, 8664.436811607226, 10556.102731267621, 10599.0, 10603.469826508797, 10711.571537138427, 10767.515461804394, 10282.742931426774, 14377.209253491159, 14495.492657756928, 14650.534946505264, 14735.0, 14017.719645606827, 14083.929580352442, 14275.785863211853, 14650.487232946796, 14674.119552826865, 13432.597022089352, 13577.796333054814, 13674.179549227078, 13820.170789752647, 14112.0, 9106.817863642558, 9289.674861379819, 9310.767230819283, 9366.812663746823, 9435.811283774148, 9989.800203996025, 11334.433278336215, 14570.4900328488, 14757.852421475647, 14851.405943762129], "unit": "ms", "name": "jquery.html", "value": 7698.2510619563345}, {"lowerIsBetter": false, "replicates": [1443.5019918128241, 1676.2456894397521, 1739.4086010756341, 1748.7202047672374, 1769.0447158534384, 6085.2393450818645, 6149.4772944299675, 6765.864682706349, 7204.5677259364475, 7252.782416527506, 6580.111684922534, 6737.2925842786535, 6814.5570537915, 6837.213720422153, 6862.82842928927, 2059.052835695582, 2089.0703636881594, 2101.9159233630635, 2102.2431924507164, 2105.821005214551, 2114.6616541353387, 2120.6182887080317, 2122.3314655883423, 2138.540213854021, 2140.8180304674042, 2092.550101728128, 2094.968575471369, 2101.3275751759443, 2111.092230340953, 2134.3063504361066, 2048.1090725534364, 2076.0761461149796, 2078.293380250722, 2078.906449209782, 2086.5201003769066, 2015.042854644047, 2049.7130401743766, 2055.085486958304, 2058.5985732782115, 2081.573277478113, 6970.059042029312, 6991.196012458581, 7005.439564834801, 7008.229094799619, 7011.579305241651, 4185.058361868586, 4274.615284624399, 4278.358246263086, 4288.571142885687, 4293.22721910056, 2626.487834872201, 2677.692065412483, 2690.744379283965, 2744.821586596865, 2753.600727894464, 863.8488264553678, 875.951822649754, 882.4572887674034, 888.5157589113949, 903.5933829776561, 9427.292953028551, 9435.669751558731, 9451.385659932082, 9451.574679139456, 9490.00354962726, 3862.806859657006, 3895.5325360956867, 3914.9804250978564, 3923.548791888912, 3935.5670876203594, 8181.713640022571, 8207.835843283101, 8257.587120643944, 8258.876116858131, 8261.8347633047, 4194.433751443577, 4198.433211516406, 4200.411942328076, 4205.621494065549, 4209.452771139732, 3299.6205436374644, 3419.897403077912, 3811.0, 3870.767753934773, 3877.7673339599714, 3467.358538670354, 3503.632118627558, 3515.402381595135, 3530.5586801649793, 3547.627499112556, 1130.66645339625, 1151.4645689754304, 1156.2195518025467, 1160.9941950290363, 1162.2213117211488, 845.8519759042143, 852.8933883264592, 860.1484530315068, 861.8276344731079, 1068.2255364860414, 6579.934200657932, 6587.209534855848, 6604.801855944329, 6623.768168114093, 6637.269900310962, 428.04759410311806, 440.0, 441.8343121329501, 442.65694087082767, 442.99335509967375, 4872.19608764557, 4913.926291105637, 4914.33656456381, 4918.458969513351, 4921.114199444063, 8150.633221505046, 8246.340292776564, 8386.8322633547, 8649.308055355556, 8667.219950204386, 8452.74641760748, 8471.703490377808, 8481.915180848113, 8523.531205783742, 8530.70142545008, 6169.76604679066, 6555.180602424697, 6627.966860165765, 6687.398134167948, 6694.564853284521, 6736.5958042517605, 6787.490938179558, 6810.489213309121, 6849.760258390932, 6891.965540172267, 7600.1639819618695, 7709.190534993969, 7750.728724494616, 7761.805954851174, 7853.725119620786, 7720.884186737316, 7845.725399611215, 7953.085395179397, 7992.240737129963, 7993.760187194393, 5004.799808007639, 5145.3568303962, 5779.711014449176, 6045.063015232646, 6191.102290167991, 6752.291009443938, 6770.763023294358, 6776.08522849409, 6777.864442711217, 6793.932060679527, 6240.282367527792, 6329.5252856036905, 6369.617822930639, 6421.8073457796345, 6426.811039957623, 6123.510119190365, 6173.197484327188, 6248.4064013918605, 6262.436380725757, 6279.623222606476, 6257.56197066201, 6313.210848643919, 6324.0, 6358.491320694426, 6377.553571250153, 6001.2498437695285, 6020.217371741645, 6025.849353766191, 6035.0, 6036.607620504653, 5745.6552606843725, 5859.970700146642, 5864.296284445893, 5920.319163296276, 5924.555658325557], "unit": "ms", "name": "mootools.html", "value": 3964.590838736626}, {"lowerIsBetter": false, "replicates": [310.13008511126293, 313.03725640235723, 318.3315038419318, 323.50826743350103, 323.7992444684296, 5476.205950137232, 5491.890162196763, 5551.916721249185, 5580.6093573449825, 5610.775568977236, 5473.124300111983, 5493.7802487900535, 5515.034868897937, 5519.98520540003, 5533.861653458665, 4120.278951183546, 4193.748375097489, 4196.454460920091, 4251.4898212214575, 4257.084726783741, 4199.055212577161, 4208.6633069354375, 4214.620684138427, 4239.215745087154, 4254.468191476066, 4182.121754431573, 4197.18154959783, 4200.97899510502, 4201.243776120297, 4206.390073439349, 4185.660588611646, 4200.810963506649, 4204.579542045802, 4207.43199668044, 4226.725262857904, 3577.8926632201074, 3664.2305115925687, 3670.541182352206, 3673.797941113238, 3678.779273243614, 5206.297149884755, 5295.152775555931, 5303.6552624079695, 5306.548943339821, 5318.44156363584, 4824.396950381202, 4847.903041939177, 4852.102361063215, 4853.563179313844, 4859.538343857328, 5798.594098413113, 5803.709814509258, 5805.245318108661, 5808.0, 5827.7668893244645, 3910.4134379842967, 3948.624880636335, 3976.005998500375, 3995.920081598381, 4007.5391329997133, 6007.669578173199, 6059.303180134297, 6080.695965201722, 6087.939120608781, 6094.238220222428, 4082.714210005271, 4098.979505102456, 4104.076582768913, 4104.15864747726, 4131.648809851136, 4171.749695018248, 4183.079722461053, 4184.8744537663915, 4187.811548480326, 4188.141431006637, 4699.365585645963, 4765.428148622118, 4801.615870730333, 4808.08646357191, 4812.25410061441, 5755.366909639936, 5765.135229715576, 5767.36558978512, 5769.740361683735, 5783.826485205451, 1456.7013762178728, 1474.5502621700484, 1477.5050358129931, 1479.142097583399, 1479.7114562660392, 977.9022097790164, 981.2983716642635, 988.7379844341256, 1006.5772375602264, 1010.4594042187395, 5028.170351891897, 5030.748462576857, 5042.39491261051, 5044.394672639307, 5053.166227572483, 5967.164596956342, 5988.401159883977, 6006.249218847644, 6007.579469437185, 6008.909866352008, 5026.597872170218, 5031.84904452867, 5042.873928151826, 5045.84862454127, 5046.722430266297, 3142.6543080261154, 3219.0, 3234.6603606620965, 3242.3191129862994, 3255.202475393544, 5585.441455854463, 5630.324361076697, 5640.125780504029, 5640.915386269209, 5723.6851973141875, 6199.876002480015, 6216.968915155395, 6219.813405597749, 6229.314775374705, 6245.843853903689, 5682.7442765075675, 5694.857628559319, 5708.31500219968, 5709.286339207599, 5709.314882213995, 5896.882062358814, 6038.456538911519, 6048.727807248596, 6065.2418447694035, 6069.544784141119, 5948.137520059654, 5955.0, 5958.1658567801405, 5961.0, 5963.254593175853, 5669.801556945652, 5678.630888992202, 5688.3742788294085, 5702.572307077069, 5708.600397972102, 4004.899877503086, 4043.9191216174922, 4047.21079389528, 4047.372657238133, 4055.5538890721405, 5873.4126587340925, 6035.305939816977, 6039.75840966374, 6041.456268935817, 6055.818325450244, 5777.6822274775295, 5811.82564523065, 5812.622179558315, 5822.388649191774, 5827.155062515996, 5787.797427090031, 5795.7391917362975, 5809.9419005809405, 5810.6513609183585, 5830.271216097988, 5584.525315348078, 5692.31692196939, 5701.629394089371, 5705.172749951317, 5716.456936591017, 5847.473727364558, 5864.970675146597, 5872.559558033079, 5874.236349274567, 5900.203472531153, 5660.264165658603, 5694.971525142348, 5721.427857214412, 5726.656400615976, 5729.9713501432225], "unit": "ms", "name": "prototype.html", "value": 4336.162548271906}, {"lowerIsBetter": false, "replicates": [615.1695211464527, 667.7295695243427, 735.4557627355758, 793.0206195348745, 809.668036105197, 11554.942225288873, 11756.823647645293, 11757.35334556599, 11818.527258909644, 11838.644840654773, 11400.62223285868, 11931.7613647727, 12087.456064477097, 12116.515339386415, 12118.636440906786, 637.3117033603697, 643.3212960326854, 809.5547448903109, 825.0553116681397, 825.4923222218353, 810.2828996338225, 823.6499487717726, 826.68999125328, 828.0642873552877, 828.159418190537, 856.4775486952954, 864.2524216552699, 868.7784614923218, 868.9000764912021, 869.1091631078132, 846.7502086884354, 852.6078004118114, 858.159004175908, 858.3862538285093, 860.9913900861005, 633.4932054356497, 634.7429291137097, 638.5913015669975, 638.7285403703407, 639.389383139101, 11567.13246506507, 11652.941735291353, 11667.94166029173, 11669.591564295293, 11682.299062056303, 10882.61910833117, 11530.942345288304, 11588.826167607493, 11773.470193841213, 11883.643490695293, 9418.576164072632, 9432.103950124727, 9502.572384242725, 9532.857007144898, 10057.195424366033, 3219.8390080495883, 3256.4301247281633, 3288.671132886716, 3291.901242962715, 3373.9662603374145, 39916.40125398121, 40169.39745903814, 40469.595304046874, 40774.388384174265, 41083.7945810272, 9361.344705870524, 9509.33434659581, 9545.713628591015, 9615.547865514176, 9669.758256043515, 15379.077255364715, 15467.5359739208, 15600.92199538995, 15618.141002244758, 15641.60895977587, 6234.127222188898, 6574.868502629921, 6611.50413718973, 6641.900371494431, 6662.433693136138, 3530.135116896377, 3618.6381361863605, 3677.889663310105, 3763.41667041609, 3780.905477363088, 2743.231895069384, 2883.567464880243, 2966.525355943039, 2975.538791487323, 2984.7313741763346, 514.6140394703972, 519.870032491877, 527.5858451115855, 528.9656172348786, 539.8893226888479, 419.96010379013944, 443.68516180147805, 459.4348950790546, 475.6694097602202, 547.6823442403396, 7165.176004759415, 7189.245129261457, 7198.5320954137815, 7305.4886157969495, 7352.19125896151, 521.455079441984, 536.2037374498866, 543.2421771628635, 547.6823442403396, 564.8023191882891, 3416.5558477398276, 3454.671806178409, 3456.844442000116, 3461.1866211440292, 3579.749417540799, 47817.521824782, 48344.51655483471, 49028.0, 49065.75467122713, 49079.509204907496, 43449.0, 43555.56444435578, 44144.55855441404, 44214.778926105806, 44312.3353149703, 40300.178987470594, 40804.38793418101, 41017.15527467369, 41097.383539246934, 41194.79402603028, 6793.864122717617, 6809.455243580601, 6892.689828957809, 6954.582725036514, 7030.613316267654, 31678.841605791822, 31777.8411107943, 31864.044078677678, 32661.0, 32792.50811237881, 36126.82056673639, 39639.40540891831, 39749.00627484336, 40170.0, 40270.59729402669, 8692.130786921258, 8823.161799629283, 8901.198892099743, 9004.099590041205, 9013.233875120688, 11209.0, 11682.824757628812, 11701.648950531498, 11801.586944456903, 12022.218555793845, 10452.268341216042, 11018.338899666045, 11035.558577656804, 11187.664370068911, 11190.384528850991, 10012.299139060196, 10222.897771022492, 10410.843837342598, 10543.472826358804, 10717.089047431056, 9998.167745385961, 10252.487375631039, 10423.009814067651, 10691.625793097204, 10901.563937442414, 8757.124287571192, 9023.909760902307, 9500.809983800147, 9681.419114853132, 9835.592300762208, 7938.64276107565, 8335.624896879775, 8594.183552562497, 9096.590653420479, 9102.863457048283], "unit": "ms", "name": "yui.html", "value": 5670.820677943993}], "name": "dromaeo_css", "value": 6231.76248240104}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 114.0, 113.0, 112.0, 125.0, 112.0, 113.0, 114.0, 115.0], "unit": "ms", "name": "ai-astar", "value": 113.9}, {"lowerIsBetter": true, "replicates": [167.0, 137.0, 176.0, 161.0, 130.0, 154.0, 158.0, 131.0, 171.0, 132.0], "unit": "ms", "name": "audio-beat-detection", "value": 151.7}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 165.0, 162.0, 165.0, 169.0, 166.0, 167.0, 165.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 164.9}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 73.0, 75.0, 137.0, 76.0, 78.0, 71.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 135.0, 95.0, 95.0, 95.0, 134.0, 95.0, 95.0, 144.0], "unit": "ms", "name": "audio-oscillator", "value": 107.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 110.0, 110.0, 111.0, 110.0, 109.0, 110.0, 119.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.8}, {"lowerIsBetter": true, "replicates": [149.0, 147.0, 148.0, 147.0, 148.0, 148.0, 147.0, 148.0, 148.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [98.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [77.0, 74.0, 73.0, 73.0, 80.0, 72.0, 74.0, 75.0, 73.0, 71.0], "unit": "ms", "name": "json-parse-financial", "value": 74.2}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 68.0, 69.0, 68.0, 68.0, 72.0, 68.0, 71.0, 74.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.7}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 86.0, 84.0, 90.0, 85.0, 86.0, 87.0, 83.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.6}, {"lowerIsBetter": true, "replicates": [129.0, 137.0, 123.0, 137.0, 131.0, 132.0, 130.0, 133.0, 120.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 129.9}, {"lowerIsBetter": true, "replicates": [151.0, 150.0, 153.0, 148.0, 147.0, 140.0, 149.0, 137.0, 149.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.9}, {"lowerIsBetter": true, "replicates": [70.0, 69.0, 70.0, 74.0, 72.0, 70.0, 71.0, 71.0, 69.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.6}], "name": "kraken", "value": 1549.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.97510373443984], "unit": "score", "name": "Richards", "value": 212.69455}, {"lowerIsBetter": false, "replicates": [191.49751053236307], "unit": "score", "name": "DeltaBlue", "value": 345.26819599999993}, {"lowerIsBetter": false, "replicates": [277.0083102493075], "unit": "score", "name": "Encrypt", "value": 960.9134100000001}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1051.5247108307046], "unit": "score", "name": "RayTrace", "value": 703.7295389999999}, {"lowerIsBetter": false, "replicates": [701.2622720897616], "unit": "score", "name": "Earley", "value": 950.3762380000003}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [26315.78947368421], "unit": "score", "name": "RegExp", "value": 34.61742999999998}, {"lowerIsBetter": false, "replicates": [607.90273556231], "unit": "score", "name": "Splay", "value": 134.052695}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 21328.99709646189}]} 12:37:48 INFO - RETURN: dromaeo_css: 6231.76 12:37:48 INFO - RETURN: kraken: 1549.10 12:37:48 INFO - RETURN: v8_7: 21329.00 12:37:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21329.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1549.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6231.76"}}} 12:37:48 INFO - Return code: 0 12:37:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:37:48 INFO - # TBPL SUCCESS # 12:37:48 INFO - Running post-action listener: _resource_record_post_action 12:37:48 INFO - Running post-run listener: _resource_record_post_run 12:37:49 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 0; Write bytes: 519155712; Read time: 0; Write time: 624092 12:37:49 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 245116928; Read time: 0; Write time: 456708 12:37:49 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 273842176; Read time: 0; Write time: 167360 12:37:49 INFO - Running post-run listener: _upload_blobber_files 12:37:49 INFO - Blob upload gear active. 12:37:49 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:37:49 INFO - Copying logs to upload dir... 12:37:49 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.512659 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-10 12:37:49.199938) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:37:49.203820) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009933 build_url: 'https://queue.taskcluster.net/v1/task/6FYATP7VRc2J2CyuTEnhCA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-10 12:37:49.255941) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:37:49.256262) ========= 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=f5f5a079460e1a9be8676904000001c1-1449778293.476699-2047649757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004578 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-10 12:37:49.306199) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-10 12:37:49.306514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-10 12:37:49.306825) ========= ========= Total master_lag: 0.25 =========