builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1448419495.5 results: success (0) buildid: 20151124163001 builduid: d92ca229ec8541ecb69f5fe59020abf0 revision: d9243e369c22ae1df321491a15eeb931ee43f1f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.502554) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.503050) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.503391) ========= bash -c pwd in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006305 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.547689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.548005) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.620344) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.620703) ========= rm -rf properties in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023491 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.682038) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.682380) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.682761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.686121) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 18:44:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 60.2M=0s 2015-11-24 18:44:55 (60.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102654 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.812433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.812762) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.118758 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.953536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:55.953903) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d9243e369c22ae1df321491a15eeb931ee43f1f5 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d9243e369c22ae1df321491a15eeb931ee43f1f5 --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 18:44:56,174 truncating revision to first 12 chars 2015-11-24 18:44:56,175 Setting DEBUG logging. 2015-11-24 18:44:56,175 attempt 1/10 2015-11-24 18:44:56,175 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d9243e369c22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 18:44:56,424 unpacking tar archive at: mozilla-central-d9243e369c22/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541500 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:56.521115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:56.521937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:56.537183) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:56.537604) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:44:56.538103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 18:44:56.538486) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:44:56 INFO - MultiFileLogger online at 20151124 18:44:56 in /builds/slave/test-pgo 18:44:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:44:56 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:44:56 INFO - {'append_to_log': False, 18:44:56 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:44:56 INFO - 'blob_upload_branch': 'Firefox', 18:44:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:44:56 INFO - 'branch': 'Firefox', 18:44:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:44:56 INFO - 'config_files': ('talos/linux_config.py',), 18:44:56 INFO - 'default_actions': ('clobber', 18:44:56 INFO - 'read-buildbot-config', 18:44:56 INFO - 'download-and-extract', 18:44:56 INFO - 'populate-webroot', 18:44:56 INFO - 'create-virtualenv', 18:44:56 INFO - 'install', 18:44:56 INFO - 'run-tests'), 18:44:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:44:56 INFO - 'download_minidump_stackwalk': True, 18:44:56 INFO - 'download_symbols': 'ondemand', 18:44:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:44:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:44:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:44:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:44:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:44:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:44:56 INFO - 'installer_path': 'installer.exe', 18:44:56 INFO - 'log_level': 'info', 18:44:56 INFO - 'log_name': 'talos', 18:44:56 INFO - 'log_to_console': True, 18:44:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:44:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:44:56 INFO - 'opt_config_files': (), 18:44:56 INFO - 'pip_index': False, 18:44:56 INFO - 'sps_profile': False, 18:44:56 INFO - 'sps_profile_interval': 0, 18:44:56 INFO - 'suite': 'dromaeojs', 18:44:56 INFO - 'system_bits': '64', 18:44:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:44:56 INFO - 'title': 'talos-linux64-ix-041', 18:44:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:44:56 INFO - 'use_talos_json': True, 18:44:56 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:44:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:44:56 INFO - 'work_dir': 'build'} 18:44:56 INFO - ##### 18:44:56 INFO - ##### Running clobber step. 18:44:56 INFO - ##### 18:44:56 INFO - Running pre-action listener: _resource_record_pre_action 18:44:56 INFO - Running main action method: clobber 18:44:56 INFO - rmtree: /builds/slave/test-pgo/build 18:44:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:45:02 INFO - Running post-action listener: _resource_record_post_action 18:45:02 INFO - ##### 18:45:02 INFO - ##### Running read-buildbot-config step. 18:45:02 INFO - ##### 18:45:02 INFO - Running pre-action listener: _resource_record_pre_action 18:45:02 INFO - Running main action method: read_buildbot_config 18:45:02 INFO - Using buildbot properties: 18:45:02 INFO - { 18:45:02 INFO - "properties": { 18:45:02 INFO - "buildnumber": 120, 18:45:02 INFO - "product": "firefox", 18:45:02 INFO - "script_repo_revision": "production", 18:45:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:45:02 INFO - "repository": "", 18:45:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:45:02 INFO - "buildid": "20151124163001", 18:45:02 INFO - "slavename": "talos-linux64-ix-041", 18:45:02 INFO - "pgo_build": "True", 18:45:02 INFO - "basedir": "/builds/slave/test-pgo", 18:45:02 INFO - "project": "", 18:45:02 INFO - "platform": "ubuntu64_hw", 18:45:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:45:02 INFO - "slavebuilddir": "test", 18:45:02 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:45:02 INFO - "branch": "mozilla-central", 18:45:02 INFO - "repo_path": "mozilla-central", 18:45:02 INFO - "stage_platform": "linux64-pgo", 18:45:02 INFO - "builduid": "d92ca229ec8541ecb69f5fe59020abf0", 18:45:02 INFO - "revision": "d9243e369c22ae1df321491a15eeb931ee43f1f5" 18:45:02 INFO - }, 18:45:02 INFO - "sourcestamp": { 18:45:02 INFO - "repository": "", 18:45:02 INFO - "hasPatch": false, 18:45:02 INFO - "project": "", 18:45:02 INFO - "branch": "mozilla-central-linux64-pgo-talos", 18:45:02 INFO - "changes": [ 18:45:02 INFO - { 18:45:02 INFO - "category": null, 18:45:02 INFO - "files": [ 18:45:02 INFO - { 18:45:02 INFO - "url": null, 18:45:02 INFO - "name": "https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:45:02 INFO - } 18:45:02 INFO - ], 18:45:02 INFO - "repository": "", 18:45:02 INFO - "rev": "d9243e369c22ae1df321491a15eeb931ee43f1f5", 18:45:02 INFO - "who": "sendchange", 18:45:02 INFO - "when": 1448419432, 18:45:02 INFO - "number": 6703349, 18:45:02 INFO - "comments": "", 18:45:02 INFO - "project": "", 18:45:02 INFO - "at": "Tue 24 Nov 2015 18:43:52", 18:45:02 INFO - "branch": "mozilla-central-linux64-pgo-talos", 18:45:02 INFO - "revlink": "", 18:45:02 INFO - "properties": [ 18:45:02 INFO - [ 18:45:02 INFO - "buildid", 18:45:02 INFO - "20151124163001", 18:45:02 INFO - "Change" 18:45:02 INFO - ], 18:45:02 INFO - [ 18:45:02 INFO - "builduid", 18:45:02 INFO - "d92ca229ec8541ecb69f5fe59020abf0", 18:45:02 INFO - "Change" 18:45:02 INFO - ], 18:45:02 INFO - [ 18:45:02 INFO - "pgo_build", 18:45:02 INFO - "True", 18:45:02 INFO - "Change" 18:45:02 INFO - ] 18:45:02 INFO - ], 18:45:02 INFO - "revision": "d9243e369c22ae1df321491a15eeb931ee43f1f5" 18:45:02 INFO - } 18:45:02 INFO - ], 18:45:02 INFO - "revision": "d9243e369c22ae1df321491a15eeb931ee43f1f5" 18:45:02 INFO - } 18:45:02 INFO - } 18:45:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:45:02 INFO - Running post-action listener: _resource_record_post_action 18:45:02 INFO - ##### 18:45:02 INFO - ##### Running download-and-extract step. 18:45:02 INFO - ##### 18:45:02 INFO - Running pre-action listener: _resource_record_pre_action 18:45:02 INFO - Running main action method: download_and_extract 18:45:02 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:02 INFO - https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:45:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/test_packages.json 18:45:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/test_packages.json 18:45:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:45:04 INFO - Downloaded 1302 bytes. 18:45:04 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:45:04 INFO - Using the following test package requirements: 18:45:04 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:45:04 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:45:04 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'jsshell-linux-x86_64.zip'], 18:45:04 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:45:04 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:45:04 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:45:04 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:45:04 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:45:04 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:45:04 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:45:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:45:04 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:04 INFO - https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:45:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:45:11 INFO - Downloaded 22529680 bytes. 18:45:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:45:11 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:11 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:45:12 INFO - Return code: 0 18:45:12 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 18:45: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')]} 18:45:12 INFO - https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:45:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:45:14 INFO - Downloaded 22529680 bytes. 18:45:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:45:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:45:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:45:15 INFO - Return code: 0 18:45:15 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')]} 18:45:15 INFO - https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:45:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:45:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:45:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:45:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:45:16 INFO - Downloaded 11131717 bytes. 18:45:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:45:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:45:16 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:45:16 INFO - Return code: 0 18:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:16 INFO - https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:45:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:45:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:45:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:45:18 INFO - Downloaded 58381118 bytes. 18:45:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:45:18 INFO - mkdir: /builds/slave/test-pgo/properties 18:45:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:45:18 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:45:18 INFO - Contents: 18:45:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:45:18 INFO - Running post-action listener: _resource_record_post_action 18:45:18 INFO - Running post-action listener: set_extra_try_arguments 18:45:18 INFO - ##### 18:45:18 INFO - ##### Running populate-webroot step. 18:45:18 INFO - ##### 18:45:18 INFO - Running pre-action listener: _resource_record_pre_action 18:45:18 INFO - Running main action method: populate_webroot 18:45:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:45:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:45:18 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:45:18 INFO - '../fennec_ids.txt'], 18:45:18 INFO - 'tests': ['tcheck2']}, 18:45:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:45:18 INFO - '--tppagecycles', 18:45:18 INFO - '7'], 18:45:18 INFO - 'tests': ['tsvgm']}}, 18:45:18 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:45:18 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:45:18 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:45:18 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:45:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'tests': ['damp', 'tps']}, 18:45:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['damp', 'tps']}, 18:45:18 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:45:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:45:18 INFO - 'other': {'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'other_l64': {'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'other_nol64': {'tests': ['a11yr', 18:45:18 INFO - 'ts_paint', 18:45:18 INFO - 'tpaint', 18:45:18 INFO - 'sessionrestore', 18:45:18 INFO - 'sessionrestore_no_auto_restore']}, 18:45:18 INFO - 'svgr': {'tests': ['tsvgx', 18:45:18 INFO - 'tsvgr_opacity', 18:45:18 INFO - 'tart', 18:45:18 INFO - 'tscrollx', 18:45:18 INFO - 'cart']}, 18:45:18 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['tsvgx', 18:45:18 INFO - 'tsvgr_opacity', 18:45:18 INFO - 'tart', 18:45:18 INFO - 'tscrollx', 18:45:18 INFO - 'cart']}, 18:45:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'tests': ['tp5o']}, 18:45:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'talos_options': ['--e10s'], 18:45:18 INFO - 'tests': ['tp5o']}, 18:45:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'talos_options': ['--xperf_path', 18:45:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:18 INFO - 'tests': ['tp5n']}, 18:45:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:45:18 INFO - 'talos_options': ['--e10s', 18:45:18 INFO - '--xperf_path', 18:45:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:18 INFO - 'tests': ['tp5n']}}, 18:45:18 INFO - 'talos.zip': {'path': '', 18:45:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:45:18 INFO - Running post-action listener: _resource_record_post_action 18:45:18 INFO - ##### 18:45:18 INFO - ##### Running create-virtualenv step. 18:45:18 INFO - ##### 18:45:18 INFO - Running pre-action listener: _resource_record_pre_action 18:45:18 INFO - Running main action method: create_virtualenv 18:45:18 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:45:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:45:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:45:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:45:18 INFO - Using real prefix '/usr' 18:45:18 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:45:18 INFO - Installing distribute.............................................................................................................................................................................................done. 18:45:21 INFO - Installing pip.................done. 18:45:21 INFO - Return code: 0 18:45:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:45:21 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')]} 18:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:21 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')]} 18:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:21 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 18:45:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 18:45:21 INFO - Using env: {'DISPLAY': ':0', 18:45:21 INFO - 'HOME': '/home/cltbld', 18:45:21 INFO - 'LANG': 'en_US.UTF-8', 18:45:21 INFO - 'LANGUAGE': 'en_US:en', 18:45:21 INFO - 'LOGNAME': 'cltbld', 18:45:21 INFO - 'MAIL': '/var/mail/cltbld', 18:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:21 INFO - 'MOZ_NO_REMOTE': '1', 18:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:21 INFO - 'NO_EM_RESTART': '1', 18:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:21 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:21 INFO - 'SHELL': '/bin/bash', 18:45:21 INFO - 'SHLVL': '1', 18:45:21 INFO - 'TERM': 'linux', 18:45:21 INFO - 'TMOUT': '86400', 18:45:21 INFO - 'USER': 'cltbld', 18:45:21 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:21 INFO - '_': '/tools/buildbot/bin/python'} 18:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:22 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 18:45:22 INFO - Cleaning up... 18:45:22 INFO - Return code: 0 18:45:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 18:45:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 18:45:22 INFO - Using env: {'DISPLAY': ':0', 18:45:22 INFO - 'HOME': '/home/cltbld', 18:45:22 INFO - 'LANG': 'en_US.UTF-8', 18:45:22 INFO - 'LANGUAGE': 'en_US:en', 18:45:22 INFO - 'LOGNAME': 'cltbld', 18:45:22 INFO - 'MAIL': '/var/mail/cltbld', 18:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:22 INFO - 'MOZ_NO_REMOTE': '1', 18:45:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:22 INFO - 'NO_EM_RESTART': '1', 18:45:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:22 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:22 INFO - 'SHELL': '/bin/bash', 18:45:22 INFO - 'SHLVL': '1', 18:45:22 INFO - 'TERM': 'linux', 18:45:22 INFO - 'TMOUT': '86400', 18:45:22 INFO - 'USER': 'cltbld', 18:45:22 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:22 INFO - '_': '/tools/buildbot/bin/python'} 18:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:22 INFO - Downloading/unpacking psutil>=0.7.1 18:45:22 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 18:45:22 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 18:45:22 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 18:45:22 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 18:45:22 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 18:45:22 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 18:45:23 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:45:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:45:23 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:45:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:24 INFO - Installing collected packages: psutil 18:45:24 INFO - Running setup.py install for psutil 18:45:24 INFO - building 'psutil._psutil_linux' extension 18:45:24 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 18:45:24 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 18:45:24 INFO - building 'psutil._psutil_posix' extension 18:45:24 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 18:45:24 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 18:45:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:24 INFO - Successfully installed psutil 18:45:24 INFO - Cleaning up... 18:45:24 INFO - Return code: 0 18:45:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:45:24 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')]} 18:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:24 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')]} 18:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:24 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 18:45:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 18:45:24 INFO - Using env: {'DISPLAY': ':0', 18:45:24 INFO - 'HOME': '/home/cltbld', 18:45:24 INFO - 'LANG': 'en_US.UTF-8', 18:45:24 INFO - 'LANGUAGE': 'en_US:en', 18:45:24 INFO - 'LOGNAME': 'cltbld', 18:45:24 INFO - 'MAIL': '/var/mail/cltbld', 18:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:24 INFO - 'MOZ_NO_REMOTE': '1', 18:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:24 INFO - 'NO_EM_RESTART': '1', 18:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:24 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:24 INFO - 'SHELL': '/bin/bash', 18:45:24 INFO - 'SHLVL': '1', 18:45:24 INFO - 'TERM': 'linux', 18:45:24 INFO - 'TMOUT': '86400', 18:45:24 INFO - 'USER': 'cltbld', 18:45:24 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:24 INFO - '_': '/tools/buildbot/bin/python'} 18:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:45:24 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 18:45:24 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 18:45:24 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 18:45:24 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 18:45:24 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 18:45:24 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 18:45:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:45:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:45:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:45:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:45:26 INFO - Installing collected packages: mozsystemmonitor 18:45:26 INFO - Running setup.py install for mozsystemmonitor 18:45:27 INFO - Successfully installed mozsystemmonitor 18:45:27 INFO - Cleaning up... 18:45:27 INFO - Return code: 0 18:45:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:45:27 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')]} 18:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:27 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')]} 18:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:27 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 18:45:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 18:45:27 INFO - Using env: {'DISPLAY': ':0', 18:45:27 INFO - 'HOME': '/home/cltbld', 18:45:27 INFO - 'LANG': 'en_US.UTF-8', 18:45:27 INFO - 'LANGUAGE': 'en_US:en', 18:45:27 INFO - 'LOGNAME': 'cltbld', 18:45:27 INFO - 'MAIL': '/var/mail/cltbld', 18:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:27 INFO - 'MOZ_NO_REMOTE': '1', 18:45:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:27 INFO - 'NO_EM_RESTART': '1', 18:45:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:27 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:27 INFO - 'SHELL': '/bin/bash', 18:45:27 INFO - 'SHLVL': '1', 18:45:27 INFO - 'TERM': 'linux', 18:45:27 INFO - 'TMOUT': '86400', 18:45:27 INFO - 'USER': 'cltbld', 18:45:27 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:27 INFO - '_': '/tools/buildbot/bin/python'} 18:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:45:27 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 18:45:27 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 18:45:27 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 18:45:27 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 18:45:27 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 18:45:27 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 18:45:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:45:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:45:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:45:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:45:29 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 18:45:29 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 18:45:29 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 18:45:29 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 18:45:29 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 18:45:29 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 18:45:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:45:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:45:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:45:30 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 18:45:30 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 18:45:30 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 18:45:30 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 18:45:30 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 18:45:30 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 18:45:30 INFO - Downloading docopt-0.6.1.tar.gz 18:45:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:45:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:45:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:45:30 INFO - Running setup.py install for blobuploader 18:45:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:45:30 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:45:30 INFO - Running setup.py install for requests 18:45:30 INFO - Running setup.py install for docopt 18:45:30 INFO - Successfully installed blobuploader requests docopt 18:45:30 INFO - Cleaning up... 18:45:30 INFO - Return code: 0 18:45:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:45: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')]} 18:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45: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')]} 18:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 18:45:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 18:45:30 INFO - Using env: {'DISPLAY': ':0', 18:45:30 INFO - 'HOME': '/home/cltbld', 18:45:30 INFO - 'LANG': 'en_US.UTF-8', 18:45:30 INFO - 'LANGUAGE': 'en_US:en', 18:45:30 INFO - 'LOGNAME': 'cltbld', 18:45:30 INFO - 'MAIL': '/var/mail/cltbld', 18:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:30 INFO - 'MOZ_NO_REMOTE': '1', 18:45:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:30 INFO - 'NO_EM_RESTART': '1', 18:45:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:30 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:30 INFO - 'SHELL': '/bin/bash', 18:45:30 INFO - 'SHLVL': '1', 18:45:30 INFO - 'TERM': 'linux', 18:45:30 INFO - 'TMOUT': '86400', 18:45:30 INFO - 'USER': 'cltbld', 18:45:30 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:30 INFO - '_': '/tools/buildbot/bin/python'} 18:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:45:31 INFO - Running setup.py (path:/tmp/pip-fj4QXj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:45:31 INFO - Running setup.py (path:/tmp/pip-QdEBKM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:45:31 INFO - Running setup.py (path:/tmp/pip-tNbFjw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:45:31 INFO - Running setup.py (path:/tmp/pip-Bg9mFe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:45:31 INFO - Running setup.py (path:/tmp/pip-FEcdbA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:45:31 INFO - Running setup.py (path:/tmp/pip-exOOKP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:45:31 INFO - Running setup.py (path:/tmp/pip-9acRgz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:45:31 INFO - Running setup.py (path:/tmp/pip-i_me2Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:45:31 INFO - Running setup.py (path:/tmp/pip-QqwOpC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:45:31 INFO - Running setup.py (path:/tmp/pip-TIA1DR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:45:31 INFO - Running setup.py (path:/tmp/pip-90adNq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:45:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:45:31 INFO - Running setup.py (path:/tmp/pip-aK3M05-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:45:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:45:32 INFO - Running setup.py (path:/tmp/pip-YPsHTG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:45:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:45:32 INFO - Running setup.py (path:/tmp/pip-Tgpr_W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:45:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:45:32 INFO - Running setup.py (path:/tmp/pip-UScbFX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:45:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:45:32 INFO - Running setup.py (path:/tmp/pip-nj2e4M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:45:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:45:32 INFO - Running setup.py (path:/tmp/pip-MqZPcR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:45:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:45:32 INFO - Running setup.py install for manifestparser 18:45:32 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:45:32 INFO - Running setup.py install for mozcrash 18:45:32 INFO - Running setup.py install for mozdebug 18:45:32 INFO - Running setup.py install for mozdevice 18:45:32 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:45:32 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:45:32 INFO - Running setup.py install for mozfile 18:45:32 INFO - Running setup.py install for mozhttpd 18:45:32 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:45:32 INFO - Running setup.py install for mozinfo 18:45:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for mozInstall 18:45:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for mozleak 18:45:33 INFO - Running setup.py install for mozlog 18:45:33 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for moznetwork 18:45:33 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for mozprocess 18:45:33 INFO - Running setup.py install for mozprofile 18:45:33 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for mozrunner 18:45:33 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:45:33 INFO - Running setup.py install for mozscreenshot 18:45:33 INFO - Running setup.py install for moztest 18:45:34 INFO - Running setup.py install for mozversion 18:45:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:45:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:45:34 INFO - Cleaning up... 18:45:34 INFO - Return code: 0 18:45:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 18:45:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 18:45:34 INFO - Using env: {'DISPLAY': ':0', 18:45:34 INFO - 'HOME': '/home/cltbld', 18:45:34 INFO - 'LANG': 'en_US.UTF-8', 18:45:34 INFO - 'LANGUAGE': 'en_US:en', 18:45:34 INFO - 'LOGNAME': 'cltbld', 18:45:34 INFO - 'MAIL': '/var/mail/cltbld', 18:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:34 INFO - 'MOZ_NO_REMOTE': '1', 18:45:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:34 INFO - 'NO_EM_RESTART': '1', 18:45:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:34 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:34 INFO - 'SHELL': '/bin/bash', 18:45:34 INFO - 'SHLVL': '1', 18:45:34 INFO - 'TERM': 'linux', 18:45:34 INFO - 'TMOUT': '86400', 18:45:34 INFO - 'USER': 'cltbld', 18:45:34 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:34 INFO - '_': '/tools/buildbot/bin/python'} 18:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:45:34 INFO - Running setup.py (path:/tmp/pip-a3KvBb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:45:34 INFO - Running setup.py (path:/tmp/pip-5pmqCy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:45:34 INFO - Running setup.py (path:/tmp/pip-FujUbp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:45:34 INFO - Running setup.py (path:/tmp/pip-ctnoLR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:45:34 INFO - Running setup.py (path:/tmp/pip-vy83lF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:45:34 INFO - Running setup.py (path:/tmp/pip-Z_awSA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:45:34 INFO - Running setup.py (path:/tmp/pip-FxX7JN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:45:34 INFO - Running setup.py (path:/tmp/pip-7QzX_8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:45:34 INFO - Running setup.py (path:/tmp/pip-URLMh8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:45:35 INFO - Running setup.py (path:/tmp/pip-9wM4ND-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:45:35 INFO - Running setup.py (path:/tmp/pip-6J0qEZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:45:35 INFO - Running setup.py (path:/tmp/pip-SRvONM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:45:35 INFO - Running setup.py (path:/tmp/pip-ggEWgl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:45:35 INFO - Running setup.py (path:/tmp/pip-1A752N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:45:35 INFO - Running setup.py (path:/tmp/pip-pri_y0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:45:35 INFO - Running setup.py (path:/tmp/pip-yYHupD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:45:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:45:35 INFO - Running setup.py (path:/tmp/pip-aTPEmO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:45:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:35 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 18:45:35 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 18:45:35 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 18:45:35 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 18:45:35 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 18:45:35 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 18:45:37 INFO - Downloading blessings-1.5.1.tar.gz 18:45:37 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:45:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:45:37 INFO - Installing collected packages: blessings 18:45:37 INFO - Running setup.py install for blessings 18:45:37 INFO - Successfully installed blessings 18:45:37 INFO - Cleaning up... 18:45:37 INFO - Return code: 0 18:45:37 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:45:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:45:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:45:37 INFO - Reading from file tmpfile_stdout 18:45:37 INFO - Current package versions: 18:45:37 INFO - argparse == 1.2.1 18:45:37 INFO - blessings == 1.5.1 18:45:37 INFO - blobuploader == 1.2.4 18:45:37 INFO - docopt == 0.6.1 18:45:37 INFO - manifestparser == 1.1 18:45:37 INFO - mozInstall == 1.12 18:45:37 INFO - mozcrash == 0.16 18:45:37 INFO - mozdebug == 0.1 18:45:37 INFO - mozdevice == 0.47 18:45:37 INFO - mozfile == 1.2 18:45:37 INFO - mozhttpd == 0.7 18:45:37 INFO - mozinfo == 0.9 18:45:37 INFO - mozleak == 0.1 18:45:37 INFO - mozlog == 3.1 18:45:37 INFO - moznetwork == 0.27 18:45:37 INFO - mozprocess == 0.22 18:45:37 INFO - mozprofile == 0.27 18:45:37 INFO - mozrunner == 6.11 18:45:37 INFO - mozscreenshot == 0.1 18:45:37 INFO - mozsystemmonitor == 0.0 18:45:37 INFO - moztest == 0.7 18:45:37 INFO - mozversion == 1.4 18:45:37 INFO - psutil == 3.1.1 18:45:37 INFO - requests == 1.2.3 18:45:37 INFO - wsgiref == 0.1.2 18:45:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 18:45:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 18:45:37 INFO - Using env: {'DISPLAY': ':0', 18:45:37 INFO - 'HOME': '/home/cltbld', 18:45:37 INFO - 'LANG': 'en_US.UTF-8', 18:45:37 INFO - 'LANGUAGE': 'en_US:en', 18:45:37 INFO - 'LOGNAME': 'cltbld', 18:45:37 INFO - 'MAIL': '/var/mail/cltbld', 18:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:37 INFO - 'MOZ_NO_REMOTE': '1', 18:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:37 INFO - 'NO_EM_RESTART': '1', 18:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:37 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:37 INFO - 'SHELL': '/bin/bash', 18:45:37 INFO - 'SHLVL': '1', 18:45:37 INFO - 'TERM': 'linux', 18:45:37 INFO - 'TMOUT': '86400', 18:45:37 INFO - 'USER': 'cltbld', 18:45:37 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:37 INFO - '_': '/tools/buildbot/bin/python'} 18:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:45:37 INFO - Cleaning up... 18:45:37 INFO - Return code: 0 18:45:37 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:45:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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 0x15ca030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 18:45:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/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-pgo/build 18:45:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/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 18:45:37 INFO - Using env: {'DISPLAY': ':0', 18:45:37 INFO - 'HOME': '/home/cltbld', 18:45:37 INFO - 'LANG': 'en_US.UTF-8', 18:45:37 INFO - 'LANGUAGE': 'en_US:en', 18:45:37 INFO - 'LOGNAME': 'cltbld', 18:45:37 INFO - 'MAIL': '/var/mail/cltbld', 18:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:37 INFO - 'MOZ_NO_REMOTE': '1', 18:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:37 INFO - 'NO_EM_RESTART': '1', 18:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:37 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:37 INFO - 'SHELL': '/bin/bash', 18:45:37 INFO - 'SHLVL': '1', 18:45:37 INFO - 'TERM': 'linux', 18:45:37 INFO - 'TMOUT': '86400', 18:45:37 INFO - 'USER': 'cltbld', 18:45:37 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:37 INFO - '_': '/tools/buildbot/bin/python'} 18:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:38 INFO - Downloading/unpacking jsonschema 18:45:38 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 18:45:38 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 18:45:38 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 18:45:38 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 18:45:38 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 18:45:38 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 18:45:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:45:39 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 18:45:39 INFO - Downloading/unpacking functools32 (from jsonschema) 18:45:39 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 18:45:39 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 18:45:39 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 18:45:39 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 18:45:39 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 18:45:39 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 18:45:40 INFO - Downloading functools32-3.2.3-2.tar.gz 18:45:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:45:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:45:40 INFO - warning: no files found matching '*.txt' 18:45:40 INFO - no previously-included directories found matching 'build' 18:45:40 INFO - no previously-included directories found matching 'dist' 18:45:40 INFO - no previously-included directories found matching '.git*' 18:45:40 INFO - Installing collected packages: jsonschema, functools32 18:45:40 INFO - Running setup.py install for functools32 18:45:40 INFO - warning: no files found matching '*.txt' 18:45:40 INFO - no previously-included directories found matching 'build' 18:45:40 INFO - no previously-included directories found matching 'dist' 18:45:40 INFO - no previously-included directories found matching '.git*' 18:45:40 INFO - Successfully installed jsonschema functools32 18:45:40 INFO - Cleaning up... 18:45:40 INFO - Return code: 0 18:45:40 INFO - Running post-action listener: _resource_record_post_action 18:45:40 INFO - Running post-action listener: _start_resource_monitoring 18:45:40 INFO - Starting resource monitoring. 18:45:40 INFO - ##### 18:45:40 INFO - ##### Running install step. 18:45:40 INFO - ##### 18:45:40 INFO - Running pre-action listener: _resource_record_pre_action 18:45:40 INFO - Running main action method: install 18:45:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:45:40 INFO - Reading from file tmpfile_stdout 18:45:40 INFO - Detecting whether we're running mozinstall >=1.0... 18:45:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:45:40 INFO - Reading from file tmpfile_stdout 18:45:40 INFO - Output received: 18:45:40 INFO - Usage: mozinstall [options] installer 18:45:40 INFO - Options: 18:45:40 INFO - -h, --help show this help message and exit 18:45:40 INFO - -d DEST, --destination=DEST 18:45:40 INFO - Directory to install application into. [default: 18:45:40 INFO - "/builds/slave/test-pgo"] 18:45:40 INFO - --app=APP Application being installed. [default: firefox] 18:45:40 INFO - mkdir: /builds/slave/test-pgo/build/application 18:45:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:45:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:45:55 INFO - Reading from file tmpfile_stdout 18:45:55 INFO - Output received: 18:45:55 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:45:55 INFO - Running post-action listener: _resource_record_post_action 18:45:55 INFO - ##### 18:45:55 INFO - ##### Running run-tests step. 18:45:55 INFO - ##### 18:45:55 INFO - Running pre-action listener: _resource_record_pre_action 18:45:55 INFO - Running main action method: run_tests 18:45:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:45:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:45:55 INFO - Python 2.7.3 18:45:55 INFO - Return code: 0 18:45:55 INFO - grabbing minidump binary from tooltool 18:45: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')]} 18:45:55 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-pgo/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 0x1886640>, '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 0x1887540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18879d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:45:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:45:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:45:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:45:55 INFO - Return code: 0 18:45:55 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:45:55 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:45:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:45:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:45:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:45:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:45:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost 18:45:55 INFO - Using env: {'DISPLAY': ':0', 18:45:55 INFO - 'HOME': '/home/cltbld', 18:45:55 INFO - 'LANG': 'en_US.UTF-8', 18:45:55 INFO - 'LANGUAGE': 'en_US:en', 18:45:55 INFO - 'LOGNAME': 'cltbld', 18:45:55 INFO - 'MAIL': '/var/mail/cltbld', 18:45:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:45:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:55 INFO - 'MOZ_NO_REMOTE': '1', 18:45:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:45:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:55 INFO - 'NO_EM_RESTART': '1', 18:45:55 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:45:55 INFO - 'PWD': '/builds/slave/test-pgo', 18:45:55 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:45:55 INFO - 'SHELL': '/bin/bash', 18:45:55 INFO - 'SHLVL': '1', 18:45:55 INFO - 'TERM': 'linux', 18:45:55 INFO - 'TMOUT': '86400', 18:45:55 INFO - 'USER': 'cltbld', 18:45:55 INFO - 'XDG_SESSION_COOKIE': '7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380', 18:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:55 INFO - '_': '/tools/buildbot/bin/python'} 18:45:55 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] with output_timeout 3600 18:45:55 INFO - mozversion INFO | application_buildid: 20151124163001 18:45:55 INFO - mozversion INFO | application_changeset: d9243e369c22ae1df321491a15eeb931ee43f1f5 18:45:55 INFO - mozversion INFO | application_display_name: Nightly 18:45:55 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:45:55 INFO - mozversion INFO | application_name: Firefox 18:45:55 INFO - mozversion INFO | application_remotingname: firefox 18:45:55 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:45:55 INFO - mozversion INFO | application_vendor: Mozilla 18:45:55 INFO - mozversion INFO | application_version: 45.0a1 18:45:55 INFO - mozversion INFO | platform_buildid: 20151124163001 18:45:55 INFO - mozversion INFO | platform_changeset: d9243e369c22ae1df321491a15eeb931ee43f1f5 18:45:55 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:45:55 INFO - mozversion INFO | platform_version: 45.0a1 18:45:55 INFO - 2015-11-24 18:45:55,490 DEBUG : using testdate: 1448419555 18:45:55 INFO - 2015-11-24 18:45:55,490 DEBUG : actual date: 1448419555 18:45:55 INFO - 2015-11-24 18:45:55,568 INFO : starting webserver on 'localhost:47181' 18:45:55 INFO - 2015-11-24 18:45:55,569 INFO : Starting test suite talos-linux64-ix-041 18:45:55 INFO - 2015-11-24 18:45:55,569 INFO : Starting test dromaeo_css 18:45:55 INFO - 2015-11-24 18:45:55,569 DEBUG : operating with platform_type : linux_ 18:45:55 INFO - 2015-11-24 18:45:55,570 INFO : Initialising browser for dromaeo_css test... 18:45:55 INFO - 2015-11-24 18:45:55,586 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbT0IBI/profile http://localhost:47181/getInfo.html 18:45:59 INFO - 2015-11-24 18:45:59,871 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:45:59 INFO - 2015-11-24 18:45:59,871 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:45:59 INFO - 2015-11-24 18:45:59,871 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:45:59 INFO - 2015-11-24 18:45:59,871 DEBUG : BROWSER_OUTPUT: __metrics 18:46:00 INFO - 2015-11-24 18:46:00,008 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:46:01 INFO - 2015-11-24 18:46:01,407 INFO : Browser initialized. 18:46:01 INFO - 2015-11-24 18:46:01,407 INFO : Running cycle 1/1 for dromaeo_css test... 18:46:01 INFO - 2015-11-24 18:46:01,407 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbT0IBI/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:46:13 INFO - 2015-11-24 18:46:13,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 139018240 18:46:13 INFO - 2015-11-24 18:46:13,165 DEBUG : BROWSER_OUTPUT: 18:47:04 INFO - 2015-11-24 18:47:04,795 DEBUG : BROWSER_OUTPUT: 1448419624792 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] 18:49:13 INFO - 2015-11-24 18:49:13,632 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47181/tests/dromaeo/cssquery-ext.html) 18:49:14 INFO - 2015-11-24 18:49:14,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 259477504 18:49:14 INFO - 2015-11-24 18:49:14,776 DEBUG : BROWSER_OUTPUT: 18:52:17 INFO - 2015-11-24 18:52:17,211 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-ext.html (next: http://localhost:47181/tests/dromaeo/cssquery-jquery.html) 18:52:17 INFO - 2015-11-24 18:52:17,780 DEBUG : BROWSER_OUTPUT: RSS: Main: 226766848 18:52:17 INFO - 2015-11-24 18:52:17,780 DEBUG : BROWSER_OUTPUT: 18:55:19 INFO - 2015-11-24 18:55:19,650 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47181/tests/dromaeo/cssquery-mootools.html) 18:55:20 INFO - 2015-11-24 18:55:20,231 DEBUG : BROWSER_OUTPUT: RSS: Main: 221429760 18:55:20 INFO - 2015-11-24 18:55:20,231 DEBUG : BROWSER_OUTPUT: 18:58:22 INFO - 2015-11-24 18:58:22,111 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47181/tests/dromaeo/cssquery-prototype.html) 18:58:22 INFO - 2015-11-24 18:58:22,678 DEBUG : BROWSER_OUTPUT: RSS: Main: 200970240 18:58:22 INFO - 2015-11-24 18:58:22,678 DEBUG : BROWSER_OUTPUT: 19:01:25 INFO - 2015-11-24 19:01:25,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47181/tests/dromaeo/cssquery-yui.html) 19:01:25 INFO - 2015-11-24 19:01:25,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 205717504 19:01:25 INFO - 2015-11-24 19:01:25,659 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/dromaeo/cssquery-yui.html (next: http://localhost:47181/tests/dromaeo/cssquery-dojo.html) 19:04:28 INFO - 2015-11-24 19:04:28,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 227840000 19:04:28 INFO - 2015-11-24 19:04:28,414 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,414 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:04:28 INFO - 2015-11-24 19:04:28,414 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:04:28 INFO - 2015-11-24 19:04:28,415 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:04:28 INFO - 2015-11-24 19:04:28,415 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:04:28 INFO - 2015-11-24 19:04:28,415 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3152.2277042124683;3202.695743904328;3242.789218700784;3250.106220789283;3272;5148.150555158404;5281.6830990140525;5354.625176237665;5382.2464854920345;5404.756785944637;5229.947700523003;5316.175992721134;5366.141417373221;5440.564754819615;5444.972775136119;4896.877578060541;4954.3063971044085;4970.776315065831;4976.054549635575;4980.302757613937;4749.833755818547;4998.600111991032;5010.649254552182;5051.595872330205;5105.3873535175835;4772.117158325721;4796.52034796521;4815.22956326986;4868.269759536062;4887.780049897763;4824.710517368969;4827.51724827518;4861.052094841507;4866.6106711463;4866.756662166913;4671.462781780105;4793.520647935214;4795.808167673289;4808.206645903457;4826.444958829745;5113.258577506278;5178.637495375326;5179.715115668637;5194.662346947436;5216.426193118754;5793.768249270024;5800.680962547058;5801.82594522165;5802.100674395476;5806.896689628957;5546.77812887484;5708.086706127;5828.766849326022;5833.387494313079;5833.416658334175;5296.602754793367;5354.4645535446925;5389.83830485086;5392.245085688007;5407.648502847303;5921.437463440966;5923.378045305267;5926.525877929756;5942.940570594239;5951.791687291011;5517.917231241535;5673.177389278531;5687.118496633028;5695.686737229492;5739.483446489836;5605.579581531401;5785.652860828363;5863.648181109147;5869.61847479921;5924.7926322578505;5702.059160238514;5720.056190728566;5724.25584673998;5725.427457254241;5733.598648094593;5450.236966824647;5632.493075623213;5737.311522617312;5740.1102829061565;5750.137479378043;4316.827326906889;4378.671599630041;4392.780360981938;4400.73595584266;4409.184300904343;3924.430957511202;3925.9018524537114;3974.960250397517;3978.5026871641044;3979.721419500665;5329.7068661224;5339.946600534023;5346.43862394443;5347.732613369394;5365.919511207335;5555.111182210827;5596.440355964371;5658.547316214693;5746.942530574641;5871.207387002785;5394.703291319015;5444;5449.918251226235;5474.42518535556;5490;5044.571211447068;5058.291839142523;5062.190049592121;5064.468230835783;5109.182530795055;5805.593608447452;5916.822495325147;5931.6441013539325;5992.610480318765;5996;5831.504322132666;5918.378570250062;5928.49607783326;5930.9110363343725;5983.9401605983385;6022.608530445507;6075.513958883191;6086.817395478142;6090.056041313604;6095.146679464967;5508.9724551376985;5620.156976453564;5754.769809207753;5800.274965629296;5819.79630712923;5743.396943320891;5776.740046697824;5784.392638772868;5786.6528008317955;5813.418658134153;5640.85897852557;5647.040003199548;5648.293963254593;5653.434656534314;5657.943420565906;5389.056915039931;5512.255845460972;5623.072193088258;5644.745986430703;5746.454086861741;5578.748956296895;5603.495685388335;5604.047311956896;5608.158776183605;5615.606907516435;5669.31968163823;5681.062624667049;5687.573431992534;5697.088465845445;5737.024705800108;5357.062514059946;5486.1222204447095;5494.313210848644;5568.6658800472105;5621.8594535136945 19:04:28 INFO - 2015-11-24 19:04:28,416 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.2143160291394;602.138941313921;612.3080918562024;616.8858761129194;618.3167599802218;31377.215569610744;32079.51880721791;32152.35695286093;32163;39661.405078923766;44157.337639935344;44648.553514464846;44671.10657786842;44712.32931506022;44744.552554474445;8708.651653933835;8871.866921996176;8874.156955089256;8875.112488751105;8877.201051905327;7831.373490120776;7983.920160798376;7985.441019128663;8706.0858609846;8805.911940880605;10629.893701063007;10807.4055926924;10818.513166907469;10832.945835270813;10839.295445796037;10414.843777343345;10417.479126043705;10484;10491.213159013028;10524.631637892637;7836.138024817265;7993.96003019987;10291.94854025725;10431.426271555061;10547.472626368652;40473.19053618911;41185.794071029755;41308.79345603253;41325.380119298236;41363;3304.256542277992;3361.882334118319;3449.6550344965553;3866.033491627093;4046.413270075852;30687.846560767277;30840.383192336027;30844.691553084405;31133.976291540977;32048;3880.049387899983;3947.4473573699706;3991.1419044905197;3996.6003399660094;4116.423700681907;268707;270910;273290;273994;274096;62158;62471.375286247465;62502.68748656318;62527.6873615629;62535.37464625386;63488.50813205226;63567;63971.360286397474;64147;64168.67915660392;14723.190224537539;14874;14890.404383824742;14928.552143435714;14931.477398291008;10454.843177352346;10464.790704185874;10511.316764410289;10514.842277365846;10521.84217236742;19195.61608767817;20241.493962651053;20256;20393.98030098489;20432;7850.921490785133;8087.595620218965;8101;8186.222308880766;8204.917950820534;1028.7890982348601;1050.2700623067049;1067.951942162589;1069.4652673663168;1072.5217158363153;28969.56545651817;29520.409591808475;29540;33597.83201084028;33636.32727345439;1004.683524689735;1014.6600888702368;1021.6066814276409;1022.2486472442748;1084.766775143348;15533.45632902843;15549.689006219813;15592;15620.297086631128;15786.526404207892;385115;390626;390875;395716;396460;55592.72203638956;56013.71993140008;56053.719731401085;56144.719276404176;56230.71884640551;1313.4614807928704;1327.2169420042221;1332.9533466328826;1336.6123824091098;1342.8657134286493;24368;28092.297692557033;28810.567841482814;29221;29394.853025734734;49782.253266200314;50361;50424;50427;50502.494975051246;297873;298362;300707;300732;300803;27442.862785685942;27557.173284801487;27645.30886727848;31960.84019579887;31983.840080799448;43571;43766.78116609396;44199.558004419545;44280.778596106815;44405;33161.66838331586;33541.49687754637;33733.66266337403;36891.8155409232;37066;36666.45000325051;42366.57633423626;43071.78464107785;43442.34836477392;44005;37061.62938370582;37507.81246093752;37617.81191094027;37636;41913.79043104765;32274.515882262254;32386.514202286515;35274.47088293729;35311.82344088263;37395.2520949574;26146;27068;27679.44641107126;27822.86088569544;27856.44287114287 19:04:28 INFO - 2015-11-24 19:04:28,417 DEBUG : BROWSER_OUTPUT: |2;jquery.html;349.67480243373655;350.58805903063893;350.9087637214324;351.22029095408203;354.36214813336005;14238.572842814732;14594.124352538855;16487.83512164878;16725.916370418145;17488.21303041363;12763.936180319108;12801.743965120691;12812.551560695378;14764.409423623043;15340.616484587863;4446.888827779299;5225.6864588124645;5377.005254027998;5423.159410291391;5428.972855135738;5354.946450535484;5392.811251606195;5393.298871146745;5393.865153371158;5399.9730001349935;5215.374155101393;5343.492368225013;5350.143976963686;5350.250964864922;5355;5313.22958171065;5322.574194064504;5331.306930099062;5331.360236771593;5350.812721554766;4650.325702773112;4725.645576581735;4732.02993386355;4766.904661906742;4775.092732380836;20057.1977120915;20080.598388032304;20207.898960505103;20852.791472085235;21051.68422473664;1934.845212383006;2152.5802468518646;2197.7802219778055;2223.244097007025;2230.408941630469;9303.953480232623;9379.718608441757;9702.175315098226;10143.442110683909;12644.114911956167;1828.7531183290353;1836.9265229390808;1849.7040473524305;1864.3474783825552;1878.1250464575776;56394.154087688716;59249.40750592525;59516.70241648764;59652;60105;9986.251031172545;10007.749806254757;10040.497975101216;10041.347312424816;10081.143102836195;11880.168388212742;12139.757204855854;12381.62855114348;13191.40638671243;13199.40602672882;7020.192677842114;7153.856922861513;7176.246494118147;7208.963955180296;7254.96372518134;6926.341997510229;7024.719011239596;7046.330598593126;7110.395616372666;7116.466265030143;4095.508538975342;4109.630133287958;4265.637420819203;4334.21984042869;4365.858765116957;1729.991350043267;1783.3669047488215;1792.865535084874;1809.7737782777153;1810.8551315894697;877.9561021948876;909.3134683314188;946.3564775952281;973.0366936732584;1032.7142707329274;16767.496975090766;17064.402745903833;17171.914140429217;17465.7380139298;17507.824921750875;803.2770506544157;883.3860466975391;916.4180745226696;958.4632605740808;1008.2438171371472;8625.870611940825;9263.054758500655;9376.343655944154;9515.762105947406;9723.854142187873;518151;526282;539916;540224;540299;60970.39029609647;60983.39016609866;63992.36007639957;78526;78630.6068469654;1151.867535233459;1152.677250369881;1159.889810468021;1161.9825702614467;1179.204037274836;15275.159866207021;15463.226838658247;15487.457938972082;15809.481327286512;15910.363585456915;32480.51279230766;33184.336313274085;33199.502007469426;33371;33595.32809343751;43146;44207.55792442163;45685.54314456813;47107.764461177474;48369.274460882414;9229.630814767603;9277.907220927704;9460;9513.952430237805;9588.520573971413;11080.5567777288;11336.943315283701;11411.315321080761;11412.429378530875;11420.714482138013;13690.31548422555;14532.63668408256;14636.195009274166;14786.89098317609;14956.850431495981;13983.300834958414;14131.576052718436;14523.346449410014;14625.707485850437;14708.485203017843;14513.201773902536;14756.409743610564;14767.556973290819;15161.241937903282;15214.619634509227;9509.286803489627;9522.95238523803;9557.426554406758;9566.33035687523;9734.075262850018;14043.36804843764;14092.788608171091;14480.565583032527;14582.854171458574;15118.94167408271 19:04:28 INFO - 2015-11-24 19:04:28,419 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1369.3564024908292;1392.6587985943445;1417.510958719242;1418.0286503744935;1539.7459419195834;6286.779962701301;6983.0921980142575;7238.674259658308;7278.235785242553;7283.453740969422;7020.613866237355;7065.682044308006;7134.965430012658;7148.714051437936;7155.356017958383;1888.8961107139103;1919.2035305348263;2178.498945242591;2214.424249695083;2219.0901730290584;2179.727534058243;2218.7115674962315;2229.063793206849;2229.9665505017438;2235.7764223577674;2150.4408853697996;2178.793014663612;2231.0518029837335;2231.7321921369457;2234.575430668176;1892.2052737850067;1930.6428310762553;1934.0136530369543;1934.4003358958628;2189.102467988125;1839.9724004139948;2157.5037741319584;2207.834412419075;2211.82305415568;2214.579229946305;7179.712811487534;7319.41444684424;7323.780286591411;7325.4139668826365;7337.046183996046;4237.7669228192435;4308.246056940055;4310.094880075188;4338.739675619473;4341.978290108529;2907.578401131845;2923.1961210667237;2975.1372102090368;2993.4162838246552;3002.24943764059;1002.7793885345212;1011.3729487717662;1027.6249169053242;1029.6241871716845;1054.1250761867632;9797.510124493818;9803.950980245125;9837.606495740161;9837.704868853874;9838.803223935482;4010.8596199133476;4022.3709397693397;4062.1266427718183;4074.388841673713;4077.918441631151;8427.620757065946;8456.06983231844;8459.534725590152;8463.957680211559;8470.788230294169;4373.300271956472;4389.934150987738;4397.274449715825;4399.49405818335;4404.779761011937;4020.8793736187963;4032.2741906457118;4033.274010678106;4044.595540445932;4047.8785636430953;3530.5763308402643;3563.7149028077692;3569.3575156471566;3584.2114734758834;3603.333383324041;1139.9088072954144;1154.2843437068902;1162.4129814443652;1162.7093226693328;1163.057923082306;1316.8156458095875;1331.374254100571;1335.4391155714623;1350.0886901341555;1354.4514471639002;6759.594424334556;6784.118064651564;6832.0776695146515;6870.725170993204;6875.140607424072;486.8612445453029;492.6403725280565;493.73091665042637;496.43654452196296;503.5593855376546;5120.769565369493;5229.633925625246;5256.08018596744;5327.813526526553;5610.13042978339;8262.504249745034;8514.063453020162;8533.743987680255;8608.78478038054;8625.69810056645;8315.293200078062;8527.147285271547;8608.311335093178;8673.956630216808;8674.566271686517;6762.289959554274;6834.692438840264;6845.589264644137;6856.657167141523;6880.036794848726;6656.30108838565;6835.213950395768;6847.657617119224;6852.554583952027;6870.175578930361;7542.622868856645;7626.8855967159425;7680.615969201406;7726.884096738441;7877.527348359117;7846.136924938366;8056;8067.83864322722;8092.393070519617;8174.346052315919;6610.6694665267505;6678.465722742072;6682.799516014527;6684.766033189009;6694.196696396463;7054.717811287491;7130.465215108991;7144.1784194818265;7180.3896668781645;7184.1019872515935;6711;6757.087793147862;6777.491688123312;6824.965875170592;6858.554193977376;6584;6661.100751398693;6677.76627818024;6713.093732346071;6756.560823546453;6624.006399039989;6719.563228390139;6721.563098398784;6730.629815360202;6736.7642132525125;6138.846528836815;6144.907826382697;6186.659733714689;6198.380161983766;6199.504039676905;6152.600080994721;6167.629942203482;6181.721822517908;6182.814515564541;6206.813795586139 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: |4;prototype.html;322.71117349971775;322.71278562079743;325.0978534566576;327.986880524779;332.38176990797115;6009.248843894513;6024.4276793704585;6056.818295451132;6066.636001839893;6071.514278857692;5711.571632127597;5828.504577110951;5868.119782032697;5986.281646202462;5991.041433370661;4583.908321833561;4607.216773148572;4610.170169369512;4619.076184763044;4620.884477888046;4436.44544431946;4593.219152744042;4601.907961840761;4604.171249175147;4608.953910460886;4479.910401791978;4491.483479399878;4580.633549316063;4581.61056310214;4581.79381927814;4546.590806827385;4549.977250113761;4561.087782443491;4564.566366195239;4564.84023059193;4003.73975691582;4004.1190937993597;4014.638682518558;4015.558288588253;4020.2361551305157;5575.776968921238;5684;5689.487946084831;5695.202671625934;5704.857378565569;5337.012652659231;5337.813176538842;5345.893082138336;5345.973270133663;5347.7058761768085;6356.841078973016;6357.586756860789;6365.554411191219;6367.458766004849;6368.395002474757;4462.933056004163;4465.620422264112;4466.419365482499;4466.8436604719;4483.125790470859;6328.71520781566;6504.739810407578;6579.815633186025;6618.867622647568;6640.7343706251695;4405.867823965286;4427.358033085185;4436.334549817489;4436.933445998313;4437.245668236407;4461.754603496774;4483.820647174141;4489.9775501122285;4495.527969563214;4498.820047198141;5240.292560504359;5242.633015688865;5242.951409718072;5256.369235691665;5260.579153667697;6283.246010478681;6394.264659564116;6414.583052101599;6432.131662225634;6440.227172739301;1583.9683206335808;1603.9333842994279;1604.606871316535;1604.7994000749907;1605.4541455905048;1040.942748148844;1065.0946695308926;1067.412922892406;1070.6573896353248;1086.9239153259196;6184.536159788034;6202.379762023852;6203.720832562545;6204.658743769136;6206.658633775186;6633.170853643294;6634.601923884582;6642.867142657216;6645.401913827682;6648.434883034899;6004.519638428916;6064.514838812885;6146.569740118238;6176.474999624992;6247.500199983991;3583.5341405617105;3598.1004748812798;3603.22530655905;3604.351216780952;3606.8737594184076;6866.793996180123;6869.416099631488;6871.381575658214;6892.104026476626;6892.896606550906;6653.068570400148;6795.966020169966;6810.863782724317;6811.318868113248;6820.249772525019;6873.965630171817;6880.174379074543;6883.105196324445;6893.827654308582;6894.758683446055;6528.510361722795;6649.601023938386;6650.401463868275;6658;6659.53383263167;6557.278699342973;6560.376764207393;6570.11303474044;6572.244191917991;6588.978708300221;6874.793756187322;6874.8625027500175;6876.346747059021;6887.7244910203035;6889.965550172217;4452.354408610798;4458.420405347284;4466.397036400174;4468.173387923244;4469.597736203757;6482.902756458562;6601.075849381186;6601.108850305147;6615.007748837713;6621.768238111836;6401.519886008475;6402.911505044247;6427.132337134614;6430.164078669743;6432.163818703726;6405.487560995016;6411.6794160290865;6412.487001039813;6417.6470294132405;6418.743250269937;6193.969030154821;6343.429091381798;6350.079238510482;6359.20509936258;6359.3640635936035;6422.197225346832;6482.319356467691;6489.480841532761;6498.155239818793;6499.350064993652;6214.533909956862;6306.400891915261;6345.397187267202;6345.8730825384155;6364.013577895434 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: |5;yui.html;735.8896165575164;813.5647428625687;823.9340852731779;844.7507985144385;867.3104881619114;13541.932290338547;13769.242691651954;13775.10461819981;13834.792478112824;13899;13083.215007099558;13271;13386.73226535471;13512.459501619924;13530.323483825794;839.0309192882231;855.8245559660255;857.2884505860121;857.3869683176528;857.438377862501;824.3034635732796;843.5739951324573;851.5571902610639;854.4104567848196;854.9016863060734;883.0418995389978;897.0670502677241;901.9549022548879;902.345799295512;902.5081330674798;886.3441053620306;886.7916039730658;886.9512176830272;887.1439061305871;887.2990337633295;656.2780940964948;656.8489247473108;656.967151642416;658.2200092889941;659.0904551718597;12519.749605007848;12580.245185288912;12639.49442022318;12642.494300228072;12646.873531264662;10725.785484290349;10826.837597436044;11215.654121505471;11269.042131418839;11369.658910232623;9915.553800079013;9917.603295868157;9933.801323973552;10016.38519654771;10018.3488073275;3657.890263292079;3667;3683.2633473305445;3725.6460636239512;3826.2730081284467;49442;50004.99990000216;50103.49896501025;50119.24821127649;50176.49823501791;9838.803223935482;9844;10017.14854237398;10262.43556604394;10321.690349289533;18031.098445077692;18293;18299.81700182981;18301.90849045773;18310.450686479428;7390.445716571278;7585.848283034308;7602.923970760332;7655.923440765521;7702.229777022253;4294.76378799169;4368.650507959355;4412.3160910059;4417.116576684612;4432.955670443319;3085.2132706160232;3137.748980081588;3141.79578327408;3142.591463109781;3145.465270903952;531.7314756048248;541.4396100036444;547.797314993455;550.2901257377938;559.7173427419209;604.3654163128697;633.2211380002623;637.8022812928007;641.3202005873788;657.5134400543564;8226.917730822735;8293.543855087908;8299;8336.8332633347;8466.029460032085;546.7020473841766;617.7930393318198;636.2746469025315;637.9489640828723;649.8602800397937;3726.925461490755;3965.504311961005;4089.1376036774377;4211.473565804275;4331.870043898688;54512.18231726527;54611.453885460636;54689.453105469234;55231;55326.44673553293;44667.77666111714;45100.3234951476;46892.76553617278;50004.499954999985;50615.49384506108;43819.78090109529;45737;46377.53622463732;47363;47393.28910066352;8311.25198732117;8911.55442227873;8967.461952282623;9010.234130099016;9024.86462703073;29793.404131917672;31488.527672085394;33892.83053584815;34142.14644633805;34788.65211347854;41520.37719434271;42274.577254227064;42407;43935.56064439315;44315;10984.615538456115;11095.334279943228;11143.944280278547;11223.775524489301;11266.493007814504;12111.576094836639;12389.93805030969;12524.874751252371;12716;12825.679358016125;11250.324980501196;11562.479688413874;11588.072954163816;11942.34317112567;11946.76106477883;11101.278416902875;11414.372209528556;11493.2529385593;11630.418479076181;11849.585264515701;10941.671749847517;11180.88819111831;11331.206815522833;11440.084793216689;11687.76624467523;9235.676751313671;9281.164695177196;9386.48374339418;9503.382280151767;9571.33000689945;8742.256908162877;9201.53992300369;9343.04545251985;9356;10008.349457285252 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,921 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:04:28 INFO - 2015-11-24 19:04:28,420 DEBUG : BROWSER_OUTPUT: __startTimestamp1448420668412__endTimestamp 19:04:28 INFO - 2015-11-24 19:04:28,421 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,421 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:04:28 INFO - 2015-11-24 19:04:28,421 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:04:28 INFO - 2015-11-24 19:04:28,421 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,421 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5348.54 Median:5517.92 stddev:605.19 (11.0%) stddev-sans-first:582.00 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: Values: 3152.2 3202.7 3242.8 3250.1 3272.0 5148.2 5281.7 5354.6 5382.2 5404.8 5229.9 5316.2 5366.1 5440.6 5445.0 4896.9 4954.3 4970.8 4976.1 4980.3 4749.8 4998.6 5010.6 5051.6 5105.4 4772.1 4796.5 4815.2 4868.3 4887.8 4824.7 4827.5 4861.1 4866.6 4866.8 4671.5 4793.5 4795.8 4808.2 4826.4 5113.3 5178.6 5179.7 5194.7 5216.4 5793.8 5800.7 5801.8 5802.1 5806.9 5546.8 5708.1 5828.8 5833.4 5833.4 5296.6 5354.5 5389.8 5392.2 5407.6 5921.4 5923.4 5926.5 5942.9 5951.8 5517.9 5673.2 5687.1 5695.7 5739.5 5605.6 5785.7 5863.6 5869.6 5924.8 5702.1 5720.1 5724.3 5725.4 5733.6 5450.2 5632.5 5737.3 5740.1 5750.1 4316.8 4378.7 4392.8 4400.7 4409.2 3924.4 3925.9 3975.0 3978.5 3979.7 5329.7 5339.9 5346.4 5347.7 5365.9 5555.1 5596.4 5658.5 5746.9 5871.2 5394.7 5444.0 5449.9 5474.4 5490.0 5044.6 5058.3 5062.2 5064.5 5109.2 5805.6 5916.8 5931.6 5992.6 5996.0 5831.5 5918.4 5928.5 5930.9 5983.9 6022.6 6075.5 6086.8 6090.1 6095.1 5509.0 5620.2 5754.8 5800.3 5819.8 5743.4 5776.7 5784.4 5786.7 5813.4 5640.9 5647.0 5648.3 5653.4 5657.9 5389.1 5512.3 5623.1 5644.7 5746.5 5578.7 5603.5 5604.0 5608.2 5615.6 5669.3 5681.1 5687.6 5697.1 5737.0 5357.1 5486.1 5494.3 5568.7 5621.9 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49569.82 Median:18479.86 stddev:85363.62 (461.9%) stddev-sans-first:85523.79 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: Values: 563.2 602.1 612.3 616.9 618.3 31377.2 32079.5 32152.4 32163.0 39661.4 44157.3 44648.6 44671.1 44712.3 44744.6 8708.7 8871.9 8874.2 8875.1 8877.2 7831.4 7983.9 7985.4 8706.1 8805.9 10629.9 10807.4 10818.5 10832.9 10839.3 10414.8 10417.5 10484.0 10491.2 10524.6 7836.1 7994.0 10291.9 10431.4 10547.5 40473.2 41185.8 41308.8 41325.4 41363.0 3304.3 3361.9 3449.7 3866.0 4046.4 30687.8 30840.4 30844.7 31134.0 32048.0 3880.0 3947.4 3991.1 3996.6 4116.4 268707.0 270910.0 273290.0 273994.0 274096.0 62158.0 62471.4 62502.7 62527.7 62535.4 63488.5 63567.0 63971.4 64147.0 64168.7 14723.2 14874.0 14890.4 14928.6 14931.5 10454.8 10464.8 10511.3 10514.8 10521.8 19195.6 20241.5 20256.0 20394.0 20432.0 7850.9 8087.6 8101.0 8186.2 8204.9 1028.8 1050.3 1068.0 1069.5 1072.5 28969.6 29520.4 29540.0 33597.8 33636.3 1004.7 1014.7 1021.6 1022.2 1084.8 15533.5 15549.7 15592.0 15620.3 15786.5 385115.0 390626.0 390875.0 395716.0 396460.0 55592.7 56013.7 56053.7 56144.7 56230.7 1313.5 1327.2 1333.0 1336.6 1342.9 24368.0 28092.3 28810.6 29221.0 29394.9 49782.3 50361.0 50424.0 50427.0 50502.5 297873.0 298362.0 300707.0 300732.0 300803.0 27442.9 27557.2 27645.3 31960.8 31983.8 43571.0 43766.8 44199.6 44280.8 44405.0 33161.7 33541.5 33733.7 36891.8 37066.0 36666.5 42366.6 43071.8 43442.3 44005.0 37061.6 37507.8 37617.8 37636.0 41913.8 32274.5 32386.5 35274.5 35311.8 37395.3 26146.0 27068.0 27679.4 27822.9 27856.4 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,422 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27959.67 Median:351.06 stddev:86961.58 (24770.8%) stddev-sans-first:87180.82 19:04:28 INFO - 2015-11-24 19:04:28,423 DEBUG : BROWSER_OUTPUT: Values: 349.7 350.6 350.9 351.2 354.4 14238.6 14594.1 16487.8 16725.9 17488.2 12763.9 12801.7 12812.6 14764.4 15340.6 4446.9 5225.7 5377.0 5423.2 5429.0 5354.9 5392.8 5393.3 5393.9 5400.0 5215.4 5343.5 5350.1 5350.3 5355.0 5313.2 5322.6 5331.3 5331.4 5350.8 4650.3 4725.6 4732.0 4766.9 4775.1 20057.2 20080.6 20207.9 20852.8 21051.7 1934.8 2152.6 2197.8 2223.2 2230.4 9304.0 9379.7 9702.2 10143.4 12644.1 1828.8 1836.9 1849.7 1864.3 1878.1 56394.2 59249.4 59516.7 59652.0 60105.0 9986.3 10007.7 10040.5 10041.3 10081.1 11880.2 12139.8 12381.6 13191.4 13199.4 7020.2 7153.9 7176.2 7209.0 7255.0 6926.3 7024.7 7046.3 7110.4 7116.5 4095.5 4109.6 4265.6 4334.2 4365.9 1730.0 1783.4 1792.9 1809.8 1810.9 878.0 909.3 946.4 973.0 1032.7 16767.5 17064.4 17171.9 17465.7 17507.8 803.3 883.4 916.4 958.5 1008.2 8625.9 9263.1 9376.3 9515.8 9723.9 518151.0 526282.0 539916.0 540224.0 540299.0 60970.4 60983.4 63992.4 78526.0 78630.6 1151.9 1152.7 1159.9 1162.0 1179.2 15275.2 15463.2 15487.5 15809.5 15910.4 32480.5 33184.3 33199.5 33371.0 33595.3 43146.0 44207.6 45685.5 47107.8 48369.3 9229.6 9277.9 9460.0 9514.0 9588.5 11080.6 11336.9 11411.3 11412.4 11420.7 13690.3 14532.6 14636.2 14786.9 14956.9 13983.3 14131.6 14523.3 14625.7 14708.5 14513.2 14756.4 14767.6 15161.2 15214.6 9509.3 9523.0 9557.4 9566.3 9734.1 14043.4 14092.8 14480.6 14582.9 15118.9 19:04:28 INFO - 2015-11-24 19:04:28,423 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,423 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5110.64 Median:6184.74 stddev:2626.01 (42.5%) stddev-sans-first:2618.32 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: Values: 1369.4 1392.7 1417.5 1418.0 1539.7 6286.8 6983.1 7238.7 7278.2 7283.5 7020.6 7065.7 7135.0 7148.7 7155.4 1888.9 1919.2 2178.5 2214.4 2219.1 2179.7 2218.7 2229.1 2230.0 2235.8 2150.4 2178.8 2231.1 2231.7 2234.6 1892.2 1930.6 1934.0 1934.4 2189.1 1840.0 2157.5 2207.8 2211.8 2214.6 7179.7 7319.4 7323.8 7325.4 7337.0 4237.8 4308.2 4310.1 4338.7 4342.0 2907.6 2923.2 2975.1 2993.4 3002.2 1002.8 1011.4 1027.6 1029.6 1054.1 9797.5 9804.0 9837.6 9837.7 9838.8 4010.9 4022.4 4062.1 4074.4 4077.9 8427.6 8456.1 8459.5 8464.0 8470.8 4373.3 4389.9 4397.3 4399.5 4404.8 4020.9 4032.3 4033.3 4044.6 4047.9 3530.6 3563.7 3569.4 3584.2 3603.3 1139.9 1154.3 1162.4 1162.7 1163.1 1316.8 1331.4 1335.4 1350.1 1354.5 6759.6 6784.1 6832.1 6870.7 6875.1 486.9 492.6 493.7 496.4 503.6 5120.8 5229.6 5256.1 5327.8 5610.1 8262.5 8514.1 8533.7 8608.8 8625.7 8315.3 8527.1 8608.3 8674.0 8674.6 6762.3 6834.7 6845.6 6856.7 6880.0 6656.3 6835.2 6847.7 6852.6 6870.2 7542.6 7626.9 7680.6 7726.9 7877.5 7846.1 8056.0 8067.8 8092.4 8174.3 6610.7 6678.5 6682.8 6684.8 6694.2 7054.7 7130.5 7144.2 7180.4 7184.1 6711.0 6757.1 6777.5 6825.0 6858.6 6584.0 6661.1 6677.8 6713.1 6756.6 6624.0 6719.6 6721.6 6730.6 6736.8 6138.8 6144.9 6186.7 6198.4 6199.5 6152.6 6167.6 6181.7 6182.8 6206.8 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5342.36 Median:6069.08 stddev:1626.37 (26.8%) stddev-sans-first:1586.70 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: Values: 322.7 322.7 325.1 328.0 332.4 6009.2 6024.4 6056.8 6066.6 6071.5 5711.6 5828.5 5868.1 5986.3 5991.0 4583.9 4607.2 4610.2 4619.1 4620.9 4436.4 4593.2 4601.9 4604.2 4609.0 4479.9 4491.5 4580.6 4581.6 4581.8 4546.6 4550.0 4561.1 4564.6 4564.8 4003.7 4004.1 4014.6 4015.6 4020.2 5575.8 5684.0 5689.5 5695.2 5704.9 5337.0 5337.8 5345.9 5346.0 5347.7 6356.8 6357.6 6365.6 6367.5 6368.4 4462.9 4465.6 4466.4 4466.8 4483.1 6328.7 6504.7 6579.8 6618.9 6640.7 4405.9 4427.4 4436.3 4436.9 4437.2 4461.8 4483.8 4490.0 4495.5 4498.8 5240.3 5242.6 5243.0 5256.4 5260.6 6283.2 6394.3 6414.6 6432.1 6440.2 1584.0 1603.9 1604.6 1604.8 1605.5 1040.9 1065.1 1067.4 1070.7 1086.9 6184.5 6202.4 6203.7 6204.7 6206.7 6633.2 6634.6 6642.9 6645.4 6648.4 6004.5 6064.5 6146.6 6176.5 6247.5 3583.5 3598.1 3603.2 3604.4 3606.9 6866.8 6869.4 6871.4 6892.1 6892.9 6653.1 6796.0 6810.9 6811.3 6820.2 6874.0 6880.2 6883.1 6893.8 6894.8 6528.5 6649.6 6650.4 6658.0 6659.5 6557.3 6560.4 6570.1 6572.2 6589.0 6874.8 6874.9 6876.3 6887.7 6890.0 4452.4 4458.4 4466.4 4468.2 4469.6 6482.9 6601.1 6601.1 6615.0 6621.8 6401.5 6402.9 6427.1 6430.2 6432.2 6405.5 6411.7 6412.5 6417.6 6418.7 6194.0 6343.4 6350.1 6359.2 6359.4 6422.2 6482.3 6489.5 6498.2 6499.4 6214.5 6306.4 6345.4 6345.9 6364.0 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13524.51 Median:46635.15 stddev:15444.09 (33.1%) stddev-sans-first:15457.55 19:04:28 INFO - 2015-11-24 19:04:28,424 DEBUG : BROWSER_OUTPUT: Values: 735.9 813.6 823.9 844.8 867.3 13541.9 13769.2 13775.1 13834.8 13899.0 13083.2 13271.0 13386.7 13512.5 13530.3 839.0 855.8 857.3 857.4 857.4 824.3 843.6 851.6 854.4 854.9 883.0 897.1 902.0 902.3 902.5 886.3 886.8 887.0 887.1 887.3 656.3 656.8 657.0 658.2 659.1 12519.7 12580.2 12639.5 12642.5 12646.9 10725.8 10826.8 11215.7 11269.0 11369.7 9915.6 9917.6 9933.8 10016.4 10018.3 3657.9 3667.0 3683.3 3725.6 3826.3 49442.0 50005.0 50103.5 50119.2 50176.5 9838.8 9844.0 10017.1 10262.4 10321.7 18031.1 18293.0 18299.8 18301.9 18310.5 7390.4 7585.8 7602.9 7655.9 7702.2 4294.8 4368.7 4412.3 4417.1 4433.0 3085.2 3137.7 3141.8 3142.6 3145.5 531.7 541.4 547.8 550.3 559.7 604.4 633.2 637.8 641.3 657.5 8226.9 8293.5 8299.0 8336.8 8466.0 546.7 617.8 636.3 637.9 649.9 3726.9 3965.5 4089.1 4211.5 4331.9 54512.2 54611.5 54689.5 55231.0 55326.4 44667.8 45100.3 46892.8 50004.5 50615.5 43819.8 45737.0 46377.5 47363.0 47393.3 8311.3 8911.6 8967.5 9010.2 9024.9 29793.4 31488.5 33892.8 34142.1 34788.7 41520.4 42274.6 42407.0 43935.6 44315.0 10984.6 11095.3 11143.9 11223.8 11266.5 12111.6 12389.9 12524.9 12716.0 12825.7 11250.3 11562.5 11588.1 11942.3 11946.8 11101.3 11414.4 11493.3 11630.4 11849.6 10941.7 11180.9 11331.2 11440.1 11687.8 9235.7 9281.2 9386.5 9503.4 9571.3 8742.3 9201.5 9343.0 9356.0 10008.3 19:04:28 INFO - 2015-11-24 19:04:28,425 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:04:28 INFO - 2015-11-24 19:04:28,425 DEBUG : BROWSER_OUTPUT: 19:04:29 INFO - 2015-11-24 19:04:29,062 INFO : Browser exited with error code: 0 19:04:29 INFO - 2015-11-24 19:04:29,083 INFO : Completed test dromaeo_css (00:18:33) 19:04:29 INFO - 2015-11-24 19:04:29,083 INFO : Starting test kraken 19:04:29 INFO - 2015-11-24 19:04:29,083 DEBUG : operating with platform_type : linux_ 19:04:29 INFO - 2015-11-24 19:04:29,083 INFO : Initialising browser for kraken test... 19:04:29 INFO - 2015-11-24 19:04:29,089 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK2LCMH/profile http://localhost:47181/getInfo.html 19:04:33 INFO - 2015-11-24 19:04:33,608 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:04:33 INFO - 2015-11-24 19:04:33,608 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:04:33 INFO - 2015-11-24 19:04:33,608 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:04:33 INFO - 2015-11-24 19:04:33,608 DEBUG : BROWSER_OUTPUT: __metrics 19:04:33 INFO - 2015-11-24 19:04:33,733 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:04:35 INFO - 2015-11-24 19:04:35,158 INFO : Browser initialized. 19:04:35 INFO - 2015-11-24 19:04:35,158 INFO : Running cycle 1/1 for kraken test... 19:04:35 INFO - 2015-11-24 19:04:35,158 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK2LCMH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:04:46 INFO - 2015-11-24 19:04:46,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 139845632 19:04:46 INFO - 2015-11-24 19:04:46,921 DEBUG : BROWSER_OUTPUT: 19:04:49 INFO - 2015-11-24 19:04:49,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47181/tests/kraken/driver.html?testName=audio-beat-detection) 19:04:49 INFO - 2015-11-24 19:04:49,954 DEBUG : BROWSER_OUTPUT: RSS: Main: 177119232 19:04:49 INFO - 2015-11-24 19:04:49,954 DEBUG : BROWSER_OUTPUT: 19:04:52 INFO - 2015-11-24 19:04:52,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47181/tests/kraken/driver.html?testName=audio-dft) 19:04:53 INFO - 2015-11-24 19:04:53,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 166891520 19:04:53 INFO - 2015-11-24 19:04:53,117 DEBUG : BROWSER_OUTPUT: 19:04:55 INFO - 2015-11-24 19:04:55,977 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47181/tests/kraken/driver.html?testName=audio-fft) 19:04:56 INFO - 2015-11-24 19:04:56,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 150339584 19:04:56 INFO - 2015-11-24 19:04:56,538 DEBUG : BROWSER_OUTPUT: 19:04:58 INFO - 2015-11-24 19:04:58,530 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47181/tests/kraken/driver.html?testName=audio-oscillator) 19:04:59 INFO - 2015-11-24 19:04:59,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 151908352 19:04:59 INFO - 2015-11-24 19:04:59,098 DEBUG : BROWSER_OUTPUT: 19:05:01 INFO - 2015-11-24 19:05:01,274 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47181/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:05:01 INFO - 2015-11-24 19:05:01,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 155435008 19:05:01 INFO - 2015-11-24 19:05:01,835 DEBUG : BROWSER_OUTPUT: 19:05:04 INFO - 2015-11-24 19:05:04,732 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47181/tests/kraken/driver.html?testName=imaging-darkroom) 19:05:05 INFO - 2015-11-24 19:05:05,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 153956352 19:05:05 INFO - 2015-11-24 19:05:05,306 DEBUG : BROWSER_OUTPUT: 19:05:08 INFO - 2015-11-24 19:05:08,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47181/tests/kraken/driver.html?testName=imaging-desaturate) 19:05:09 INFO - 2015-11-24 19:05:09,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 155209728 19:05:09 INFO - 2015-11-24 19:05:09,227 DEBUG : BROWSER_OUTPUT: 19:05:11 INFO - 2015-11-24 19:05:11,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47181/tests/kraken/driver.html?testName=json-parse-financial) 19:05:12 INFO - 2015-11-24 19:05:12,534 DEBUG : BROWSER_OUTPUT: RSS: Main: 154775552 19:05:12 INFO - 2015-11-24 19:05:12,534 DEBUG : BROWSER_OUTPUT: 19:05:14 INFO - 2015-11-24 19:05:14,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47181/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:05:14 INFO - 2015-11-24 19:05:14,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 165183488 19:05:14 INFO - 2015-11-24 19:05:14,643 DEBUG : BROWSER_OUTPUT: 19:05:16 INFO - 2015-11-24 19:05:16,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:05:17 INFO - 2015-11-24 19:05:17,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 165404672 19:05:17 INFO - 2015-11-24 19:05:17,044 DEBUG : BROWSER_OUTPUT: 19:05:19 INFO - 2015-11-24 19:05:19,075 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:05:19 INFO - 2015-11-24 19:05:19,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 171646976 19:05:19 INFO - 2015-11-24 19:05:19,644 DEBUG : BROWSER_OUTPUT: 19:05:22 INFO - 2015-11-24 19:05:22,045 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:05:22 INFO - 2015-11-24 19:05:22,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 178642944 19:05:22 INFO - 2015-11-24 19:05:22,627 DEBUG : BROWSER_OUTPUT: 19:05:25 INFO - 2015-11-24 19:05:25,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:05:25 INFO - 2015-11-24 19:05:25,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 178548736 19:05:25 INFO - 2015-11-24 19:05:25,748 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,377 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47181/tests/kraken/driver.html?testName=ai-astar) 19:05:27 INFO - 2015-11-24 19:05:27,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 237047808 19:05:27 INFO - 2015-11-24 19:05:27,635 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;103;109;112;112;100;110;110;102 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;123;147;168;130;155;154;126;158;127 19:05:27 INFO - 2015-11-24 19:05:27,636 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;162;162;162;161;162;165;160;161 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;87;90;71;71;70;95;92;72 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;92;135;94;93;94;135;93;93;135 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;109;110;109;109;109;109;109 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;146;146;146;145;146;146;144 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;95;96;96;95;96;95 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;57;57;56;56;57;57;57;59;54 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;69;70;70;70;70;70;69;70 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;84;84;82;92;83;83;93;85 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;116;120;120;118;121;124;119;124;120 19:05:27 INFO - 2015-11-24 19:05:27,637 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;163;146;149;147;137;144;150;140;148 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;65;69;65;64;64;65;65;64 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,932 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: __startTimestamp1448420727629__endTimestamp 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,638 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:110.00 stddev:4.42 (4.0%) stddev-sans-first:4.66 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 103.0 109.0 112.0 112.0 100.0 110.0 110.0 102.0 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.80 Median:152.00 stddev:15.94 (10.5%) stddev-sans-first:16.75 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: Values: 150.0 123.0 147.0 168.0 130.0 155.0 154.0 126.0 158.0 127.0 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.43 (0.9%) stddev-sans-first:1.50 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 162.0 162.0 162.0 161.0 162.0 165.0 160.0 161.0 19:05:27 INFO - 2015-11-24 19:05:27,639 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,640 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.20 Median:79.50 stddev:10.36 (13.0%) stddev-sans-first:10.65 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 87.0 90.0 71.0 71.0 70.0 95.0 92.0 72.0 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:93.00 stddev:20.16 (21.7%) stddev-sans-first:20.93 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: Values: 94.0 92.0 135.0 94.0 93.0 94.0 135.0 93.0 93.0 135.0 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,641 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:109.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 144.0 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,642 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.45 (2.5%) stddev-sans-first:1.32 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: Values: 59.0 57.0 57.0 56.0 56.0 57.0 57.0 57.0 59.0 54.0 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 69.0 70.0 70.0 70.0 70.0 70.0 69.0 70.0 19:05:27 INFO - 2015-11-24 19:05:27,643 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.00 stddev:3.94 (4.7%) stddev-sans-first:4.10 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 84.0 84.0 82.0 92.0 83.0 83.0 93.0 85.0 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:120.50 stddev:2.72 (2.3%) stddev-sans-first:2.59 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: Values: 124.0 116.0 120.0 120.0 118.0 121.0 124.0 119.0 124.0 120.0 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,644 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.20 Median:147.50 stddev:7.48 (5.1%) stddev-sans-first:7.32 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: Values: 138.0 163.0 146.0 149.0 147.0 137.0 144.0 150.0 140.0 148.0 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:1.49 (2.3%) stddev-sans-first:1.56 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 65.0 69.0 65.0 64.0 64.0 65.0 65.0 64.0 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:05:27 INFO - 2015-11-24 19:05:27,645 DEBUG : BROWSER_OUTPUT: 19:05:28 INFO - 2015-11-24 19:05:28,462 INFO : Browser exited with error code: 0 19:05:28 INFO - 2015-11-24 19:05:28,514 INFO : Completed test kraken (00:00:59) 19:05:28 INFO - 2015-11-24 19:05:28,514 INFO : Starting test v8_7 19:05:28 INFO - 2015-11-24 19:05:28,514 DEBUG : operating with platform_type : linux_ 19:05:28 INFO - 2015-11-24 19:05:28,514 INFO : Initialising browser for v8_7 test... 19:05:28 INFO - 2015-11-24 19:05:28,520 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4wAQ0h/profile http://localhost:47181/getInfo.html 19:05:32 INFO - 2015-11-24 19:05:32,490 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:05:32 INFO - 2015-11-24 19:05:32,491 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:05:32 INFO - 2015-11-24 19:05:32,491 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:05:32 INFO - 2015-11-24 19:05:32,491 DEBUG : BROWSER_OUTPUT: __metrics 19:05:32 INFO - 2015-11-24 19:05:32,688 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:05:34 INFO - 2015-11-24 19:05:34,040 INFO : Browser initialized. 19:05:34 INFO - 2015-11-24 19:05:34,040 INFO : Running cycle 1/1 for v8_7 test... 19:05:34 INFO - 2015-11-24 19:05:34,040 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4wAQ0h/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:05:45 INFO - 2015-11-24 19:05:45,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 139214848 19:05:45 INFO - 2015-11-24 19:05:45,757 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:47181/tests/v8_7/run.html (next: http://localhost:47181/tests/v8_7/run.html) 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: RSS: Main: 614424576 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:06:07 INFO - 2015-11-24 19:06:07,464 DEBUG : BROWSER_OUTPUT: |0;Richards;164.66326362588507 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;208.7246921310791 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.88543140028287 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1108.6474501108648 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |5;Earley;799.3605115907275 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 19:06:07 INFO - 2015-11-24 19:06:07,465 DEBUG : BROWSER_OUTPUT: |7;RegExp;25450 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: |8;Splay;646.7598475222363 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: __startTimestamp1448420767463__endTimestamp 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,466 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.66 Median:164.66 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: Values: 164.7 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,467 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:208.72 Median:208.72 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: Values: 208.7 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.89 Median:282.89 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: Values: 282.9 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,468 DEBUG : BROWSER_OUTPUT: Values: 5143.6 19:06:07 INFO - 2015-11-24 19:06:07,469 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,469 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1108.65 Median:1108.65 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,469 DEBUG : BROWSER_OUTPUT: Values: 1108.6 19:06:07 INFO - 2015-11-24 19:06:07,469 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,469 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:799.36 Median:799.36 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: Values: 799.4 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: Values: 12268.3 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,470 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25450.00 Median:25450.00 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: Values: 25450.0 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:646.76 Median:646.76 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: Values: 646.8 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 19:06:07 INFO - 2015-11-24 19:06:07,471 DEBUG : BROWSER_OUTPUT: Values: 4826.9 19:06:07 INFO - 2015-11-24 19:06:07,472 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:06:07 INFO - 2015-11-24 19:06:07,472 DEBUG : BROWSER_OUTPUT: 19:06:08 INFO - 2015-11-24 19:06:08,309 INFO : Browser exited with error code: 0 19:06:08 INFO - 2015-11-24 19:06:08,322 INFO : Completed test v8_7 (00:00:39) 19:06:08 INFO - 2015-11-24 19:06:08,435 INFO : Completed test suite (00:20:12) 19:06:08 INFO - 2015-11-24 19:06:08,436 DEBUG : Working with test: dromaeo_css 19:06:08 INFO - 2015-11-24 19:06:08,436 DEBUG : Generating results file: dromaeo_css 19:06:08 INFO - 2015-11-24 19:06:08,437 DEBUG : Working with test: kraken 19:06:08 INFO - 2015-11-24 19:06:08,437 DEBUG : Generating results file: kraken 19:06:08 INFO - 2015-11-24 19:06:08,437 INFO : javascript benchmark 19:06:08 INFO - 2015-11-24 19:06:08,437 DEBUG : Working with test: v8_7 19:06:08 INFO - 2015-11-24 19:06:08,438 DEBUG : Generating results file: v8_7 19:06:08 INFO - 2015-11-24 19:06:08,438 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:06:08 INFO - 2015-11-24 19:06:08,506 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 19:06:08 INFO - 2015-11-24 19:06:08,506 DEBUG : process_Request line: dromaeo_css 6925.98 graph.html#tests=[[72,1,35]] 19:06:08 INFO - 2015-11-24 19:06:08,506 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:06:08 INFO - 2015-11-24 19:06:08,539 DEBUG : process_Request line: kraken 1492.70 graph.html#tests=[[232,1,35]] 19:06:08 INFO - 2015-11-24 19:06:08,539 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:06:08 INFO - 2015-11-24 19:06:08,582 DEBUG : process_Request line: v8_7 20696.50 graph.html#tests=[[230,1,35]] 19:06:08 INFO - 2015-11-24 19:06:08,587 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3152.2277042124683, 3202.695743904328, 3242.789218700784, 3250.106220789283, 3272.0, 5148.150555158404, 5281.6830990140525, 5354.625176237665, 5382.2464854920345, 5404.756785944637, 5229.947700523003, 5316.175992721134, 5366.141417373221, 5440.564754819615, 5444.972775136119, 4896.877578060541, 4954.3063971044085, 4970.776315065831, 4976.054549635575, 4980.302757613937, 4749.833755818547, 4998.600111991032, 5010.649254552182, 5051.595872330205, 5105.3873535175835, 4772.117158325721, 4796.52034796521, 4815.22956326986, 4868.269759536062, 4887.780049897763, 4824.710517368969, 4827.51724827518, 4861.052094841507, 4866.6106711463, 4866.756662166913, 4671.462781780105, 4793.520647935214, 4795.808167673289, 4808.206645903457, 4826.444958829745, 5113.258577506278, 5178.637495375326, 5179.715115668637, 5194.662346947436, 5216.426193118754, 5793.768249270024, 5800.680962547058, 5801.82594522165, 5802.100674395476, 5806.896689628957, 5546.77812887484, 5708.086706127, 5828.766849326022, 5833.387494313079, 5833.416658334175, 5296.602754793367, 5354.4645535446925, 5389.83830485086, 5392.245085688007, 5407.648502847303, 5921.437463440966, 5923.378045305267, 5926.525877929756, 5942.940570594239, 5951.791687291011, 5517.917231241535, 5673.177389278531, 5687.118496633028, 5695.686737229492, 5739.483446489836, 5605.579581531401, 5785.652860828363, 5863.648181109147, 5869.61847479921, 5924.7926322578505, 5702.059160238514, 5720.056190728566, 5724.25584673998, 5725.427457254241, 5733.598648094593, 5450.236966824647, 5632.493075623213, 5737.311522617312, 5740.1102829061565, 5750.137479378043, 4316.827326906889, 4378.671599630041, 4392.780360981938, 4400.73595584266, 4409.184300904343, 3924.430957511202, 3925.9018524537114, 3974.960250397517, 3978.5026871641044, 3979.721419500665, 5329.7068661224, 5339.946600534023, 5346.43862394443, 5347.732613369394, 5365.919511207335, 5555.111182210827, 5596.440355964371, 5658.547316214693, 5746.942530574641, 5871.207387002785, 5394.703291319015, 5444.0, 5449.918251226235, 5474.42518535556, 5490.0, 5044.571211447068, 5058.291839142523, 5062.190049592121, 5064.468230835783, 5109.182530795055, 5805.593608447452, 5916.822495325147, 5931.6441013539325, 5992.610480318765, 5996.0, 5831.504322132666, 5918.378570250062, 5928.49607783326, 5930.9110363343725, 5983.9401605983385, 6022.608530445507, 6075.513958883191, 6086.817395478142, 6090.056041313604, 6095.146679464967, 5508.9724551376985, 5620.156976453564, 5754.769809207753, 5800.274965629296, 5819.79630712923, 5743.396943320891, 5776.740046697824, 5784.392638772868, 5786.6528008317955, 5813.418658134153, 5640.85897852557, 5647.040003199548, 5648.293963254593, 5653.434656534314, 5657.943420565906, 5389.056915039931, 5512.255845460972, 5623.072193088258, 5644.745986430703, 5746.454086861741, 5578.748956296895, 5603.495685388335, 5604.047311956896, 5608.158776183605, 5615.606907516435, 5669.31968163823, 5681.062624667049, 5687.573431992534, 5697.088465845445, 5737.024705800108, 5357.062514059946, 5486.1222204447095, 5494.313210848644, 5568.6658800472105, 5621.8594535136945], "unit": "ms", "name": "dojo.html", "value": 5308.944404584807}, {"lowerIsBetter": false, "replicates": [563.2143160291394, 602.138941313921, 612.3080918562024, 616.8858761129194, 618.3167599802218, 31377.215569610744, 32079.51880721791, 32152.35695286093, 32163.0, 39661.405078923766, 44157.337639935344, 44648.553514464846, 44671.10657786842, 44712.32931506022, 44744.552554474445, 8708.651653933835, 8871.866921996176, 8874.156955089256, 8875.112488751105, 8877.201051905327, 7831.373490120776, 7983.920160798376, 7985.441019128663, 8706.0858609846, 8805.911940880605, 10629.893701063007, 10807.4055926924, 10818.513166907469, 10832.945835270813, 10839.295445796037, 10414.843777343345, 10417.479126043705, 10484.0, 10491.213159013028, 10524.631637892637, 7836.138024817265, 7993.96003019987, 10291.94854025725, 10431.426271555061, 10547.472626368652, 40473.19053618911, 41185.794071029755, 41308.79345603253, 41325.380119298236, 41363.0, 3304.256542277992, 3361.882334118319, 3449.6550344965553, 3866.033491627093, 4046.413270075852, 30687.846560767277, 30840.383192336027, 30844.691553084405, 31133.976291540977, 32048.0, 3880.049387899983, 3947.4473573699706, 3991.1419044905197, 3996.6003399660094, 4116.423700681907, 268707.0, 270910.0, 273290.0, 273994.0, 274096.0, 62158.0, 62471.375286247465, 62502.68748656318, 62527.6873615629, 62535.37464625386, 63488.50813205226, 63567.0, 63971.360286397474, 64147.0, 64168.67915660392, 14723.190224537539, 14874.0, 14890.404383824742, 14928.552143435714, 14931.477398291008, 10454.843177352346, 10464.790704185874, 10511.316764410289, 10514.842277365846, 10521.84217236742, 19195.61608767817, 20241.493962651053, 20256.0, 20393.98030098489, 20432.0, 7850.921490785133, 8087.595620218965, 8101.0, 8186.222308880766, 8204.917950820534, 1028.7890982348601, 1050.2700623067049, 1067.951942162589, 1069.4652673663168, 1072.5217158363153, 28969.56545651817, 29520.409591808475, 29540.0, 33597.83201084028, 33636.32727345439, 1004.683524689735, 1014.6600888702368, 1021.6066814276409, 1022.2486472442748, 1084.766775143348, 15533.45632902843, 15549.689006219813, 15592.0, 15620.297086631128, 15786.526404207892, 385115.0, 390626.0, 390875.0, 395716.0, 396460.0, 55592.72203638956, 56013.71993140008, 56053.719731401085, 56144.719276404176, 56230.71884640551, 1313.4614807928704, 1327.2169420042221, 1332.9533466328826, 1336.6123824091098, 1342.8657134286493, 24368.0, 28092.297692557033, 28810.567841482814, 29221.0, 29394.853025734734, 49782.253266200314, 50361.0, 50424.0, 50427.0, 50502.494975051246, 297873.0, 298362.0, 300707.0, 300732.0, 300803.0, 27442.862785685942, 27557.173284801487, 27645.30886727848, 31960.84019579887, 31983.840080799448, 43571.0, 43766.78116609396, 44199.558004419545, 44280.778596106815, 44405.0, 33161.66838331586, 33541.49687754637, 33733.66266337403, 36891.8155409232, 37066.0, 36666.45000325051, 42366.57633423626, 43071.78464107785, 43442.34836477392, 44005.0, 37061.62938370582, 37507.81246093752, 37617.81191094027, 37636.0, 41913.79043104765, 32274.515882262254, 32386.514202286515, 35274.47088293729, 35311.82344088263, 37395.2520949574, 26146.0, 27068.0, 27679.44641107126, 27822.86088569544, 27856.44287114287], "unit": "ms", "name": "ext.html", "value": 19199.169298556033}, {"lowerIsBetter": false, "replicates": [349.67480243373655, 350.58805903063893, 350.9087637214324, 351.22029095408203, 354.36214813336005, 14238.572842814732, 14594.124352538855, 16487.83512164878, 16725.916370418145, 17488.21303041363, 12763.936180319108, 12801.743965120691, 12812.551560695378, 14764.409423623043, 15340.616484587863, 4446.888827779299, 5225.6864588124645, 5377.005254027998, 5423.159410291391, 5428.972855135738, 5354.946450535484, 5392.811251606195, 5393.298871146745, 5393.865153371158, 5399.9730001349935, 5215.374155101393, 5343.492368225013, 5350.143976963686, 5350.250964864922, 5355.0, 5313.22958171065, 5322.574194064504, 5331.306930099062, 5331.360236771593, 5350.812721554766, 4650.325702773112, 4725.645576581735, 4732.02993386355, 4766.904661906742, 4775.092732380836, 20057.1977120915, 20080.598388032304, 20207.898960505103, 20852.791472085235, 21051.68422473664, 1934.845212383006, 2152.5802468518646, 2197.7802219778055, 2223.244097007025, 2230.408941630469, 9303.953480232623, 9379.718608441757, 9702.175315098226, 10143.442110683909, 12644.114911956167, 1828.7531183290353, 1836.9265229390808, 1849.7040473524305, 1864.3474783825552, 1878.1250464575776, 56394.154087688716, 59249.40750592525, 59516.70241648764, 59652.0, 60105.0, 9986.251031172545, 10007.749806254757, 10040.497975101216, 10041.347312424816, 10081.143102836195, 11880.168388212742, 12139.757204855854, 12381.62855114348, 13191.40638671243, 13199.40602672882, 7020.192677842114, 7153.856922861513, 7176.246494118147, 7208.963955180296, 7254.96372518134, 6926.341997510229, 7024.719011239596, 7046.330598593126, 7110.395616372666, 7116.466265030143, 4095.508538975342, 4109.630133287958, 4265.637420819203, 4334.21984042869, 4365.858765116957, 1729.991350043267, 1783.3669047488215, 1792.865535084874, 1809.7737782777153, 1810.8551315894697, 877.9561021948876, 909.3134683314188, 946.3564775952281, 973.0366936732584, 1032.7142707329274, 16767.496975090766, 17064.402745903833, 17171.914140429217, 17465.7380139298, 17507.824921750875, 803.2770506544157, 883.3860466975391, 916.4180745226696, 958.4632605740808, 1008.2438171371472, 8625.870611940825, 9263.054758500655, 9376.343655944154, 9515.762105947406, 9723.854142187873, 518151.0, 526282.0, 539916.0, 540224.0, 540299.0, 60970.39029609647, 60983.39016609866, 63992.36007639957, 78526.0, 78630.6068469654, 1151.867535233459, 1152.677250369881, 1159.889810468021, 1161.9825702614467, 1179.204037274836, 15275.159866207021, 15463.226838658247, 15487.457938972082, 15809.481327286512, 15910.363585456915, 32480.51279230766, 33184.336313274085, 33199.502007469426, 33371.0, 33595.32809343751, 43146.0, 44207.55792442163, 45685.54314456813, 47107.764461177474, 48369.274460882414, 9229.630814767603, 9277.907220927704, 9460.0, 9513.952430237805, 9588.520573971413, 11080.5567777288, 11336.943315283701, 11411.315321080761, 11412.429378530875, 11420.714482138013, 13690.31548422555, 14532.63668408256, 14636.195009274166, 14786.89098317609, 14956.850431495981, 13983.300834958414, 14131.576052718436, 14523.346449410014, 14625.707485850437, 14708.485203017843, 14513.201773902536, 14756.409743610564, 14767.556973290819, 15161.241937903282, 15214.619634509227, 9509.286803489627, 9522.95238523803, 9557.426554406758, 9566.33035687523, 9734.075262850018, 14043.36804843764, 14092.788608171091, 14480.565583032527, 14582.854171458574, 15118.94167408271], "unit": "ms", "name": "jquery.html", "value": 8598.05745878292}, {"lowerIsBetter": false, "replicates": [1369.3564024908292, 1392.6587985943445, 1417.510958719242, 1418.0286503744935, 1539.7459419195834, 6286.779962701301, 6983.0921980142575, 7238.674259658308, 7278.235785242553, 7283.453740969422, 7020.613866237355, 7065.682044308006, 7134.965430012658, 7148.714051437936, 7155.356017958383, 1888.8961107139103, 1919.2035305348263, 2178.498945242591, 2214.424249695083, 2219.0901730290584, 2179.727534058243, 2218.7115674962315, 2229.063793206849, 2229.9665505017438, 2235.7764223577674, 2150.4408853697996, 2178.793014663612, 2231.0518029837335, 2231.7321921369457, 2234.575430668176, 1892.2052737850067, 1930.6428310762553, 1934.0136530369543, 1934.4003358958628, 2189.102467988125, 1839.9724004139948, 2157.5037741319584, 2207.834412419075, 2211.82305415568, 2214.579229946305, 7179.712811487534, 7319.41444684424, 7323.780286591411, 7325.4139668826365, 7337.046183996046, 4237.7669228192435, 4308.246056940055, 4310.094880075188, 4338.739675619473, 4341.978290108529, 2907.578401131845, 2923.1961210667237, 2975.1372102090368, 2993.4162838246552, 3002.24943764059, 1002.7793885345212, 1011.3729487717662, 1027.6249169053242, 1029.6241871716845, 1054.1250761867632, 9797.510124493818, 9803.950980245125, 9837.606495740161, 9837.704868853874, 9838.803223935482, 4010.8596199133476, 4022.3709397693397, 4062.1266427718183, 4074.388841673713, 4077.918441631151, 8427.620757065946, 8456.06983231844, 8459.534725590152, 8463.957680211559, 8470.788230294169, 4373.300271956472, 4389.934150987738, 4397.274449715825, 4399.49405818335, 4404.779761011937, 4020.8793736187963, 4032.2741906457118, 4033.274010678106, 4044.595540445932, 4047.8785636430953, 3530.5763308402643, 3563.7149028077692, 3569.3575156471566, 3584.2114734758834, 3603.333383324041, 1139.9088072954144, 1154.2843437068902, 1162.4129814443652, 1162.7093226693328, 1163.057923082306, 1316.8156458095875, 1331.374254100571, 1335.4391155714623, 1350.0886901341555, 1354.4514471639002, 6759.594424334556, 6784.118064651564, 6832.0776695146515, 6870.725170993204, 6875.140607424072, 486.8612445453029, 492.6403725280565, 493.73091665042637, 496.43654452196296, 503.5593855376546, 5120.769565369493, 5229.633925625246, 5256.08018596744, 5327.813526526553, 5610.13042978339, 8262.504249745034, 8514.063453020162, 8533.743987680255, 8608.78478038054, 8625.69810056645, 8315.293200078062, 8527.147285271547, 8608.311335093178, 8673.956630216808, 8674.566271686517, 6762.289959554274, 6834.692438840264, 6845.589264644137, 6856.657167141523, 6880.036794848726, 6656.30108838565, 6835.213950395768, 6847.657617119224, 6852.554583952027, 6870.175578930361, 7542.622868856645, 7626.8855967159425, 7680.615969201406, 7726.884096738441, 7877.527348359117, 7846.136924938366, 8056.0, 8067.83864322722, 8092.393070519617, 8174.346052315919, 6610.6694665267505, 6678.465722742072, 6682.799516014527, 6684.766033189009, 6694.196696396463, 7054.717811287491, 7130.465215108991, 7144.1784194818265, 7180.3896668781645, 7184.1019872515935, 6711.0, 6757.087793147862, 6777.491688123312, 6824.965875170592, 6858.554193977376, 6584.0, 6661.100751398693, 6677.76627818024, 6713.093732346071, 6756.560823546453, 6624.006399039989, 6719.563228390139, 6721.563098398784, 6730.629815360202, 6736.7642132525125, 6138.846528836815, 6144.907826382697, 6186.659733714689, 6198.380161983766, 6199.504039676905, 6152.600080994721, 6167.629942203482, 6181.721822517908, 6182.814515564541, 6206.813795586139], "unit": "ms", "name": "mootools.html", "value": 4163.357734496603}, {"lowerIsBetter": false, "replicates": [322.71117349971775, 322.71278562079743, 325.0978534566576, 327.986880524779, 332.38176990797115, 6009.248843894513, 6024.4276793704585, 6056.818295451132, 6066.636001839893, 6071.514278857692, 5711.571632127597, 5828.504577110951, 5868.119782032697, 5986.281646202462, 5991.041433370661, 4583.908321833561, 4607.216773148572, 4610.170169369512, 4619.076184763044, 4620.884477888046, 4436.44544431946, 4593.219152744042, 4601.907961840761, 4604.171249175147, 4608.953910460886, 4479.910401791978, 4491.483479399878, 4580.633549316063, 4581.61056310214, 4581.79381927814, 4546.590806827385, 4549.977250113761, 4561.087782443491, 4564.566366195239, 4564.84023059193, 4003.73975691582, 4004.1190937993597, 4014.638682518558, 4015.558288588253, 4020.2361551305157, 5575.776968921238, 5684.0, 5689.487946084831, 5695.202671625934, 5704.857378565569, 5337.012652659231, 5337.813176538842, 5345.893082138336, 5345.973270133663, 5347.7058761768085, 6356.841078973016, 6357.586756860789, 6365.554411191219, 6367.458766004849, 6368.395002474757, 4462.933056004163, 4465.620422264112, 4466.419365482499, 4466.8436604719, 4483.125790470859, 6328.71520781566, 6504.739810407578, 6579.815633186025, 6618.867622647568, 6640.7343706251695, 4405.867823965286, 4427.358033085185, 4436.334549817489, 4436.933445998313, 4437.245668236407, 4461.754603496774, 4483.820647174141, 4489.9775501122285, 4495.527969563214, 4498.820047198141, 5240.292560504359, 5242.633015688865, 5242.951409718072, 5256.369235691665, 5260.579153667697, 6283.246010478681, 6394.264659564116, 6414.583052101599, 6432.131662225634, 6440.227172739301, 1583.9683206335808, 1603.9333842994279, 1604.606871316535, 1604.7994000749907, 1605.4541455905048, 1040.942748148844, 1065.0946695308926, 1067.412922892406, 1070.6573896353248, 1086.9239153259196, 6184.536159788034, 6202.379762023852, 6203.720832562545, 6204.658743769136, 6206.658633775186, 6633.170853643294, 6634.601923884582, 6642.867142657216, 6645.401913827682, 6648.434883034899, 6004.519638428916, 6064.514838812885, 6146.569740118238, 6176.474999624992, 6247.500199983991, 3583.5341405617105, 3598.1004748812798, 3603.22530655905, 3604.351216780952, 3606.8737594184076, 6866.793996180123, 6869.416099631488, 6871.381575658214, 6892.104026476626, 6892.896606550906, 6653.068570400148, 6795.966020169966, 6810.863782724317, 6811.318868113248, 6820.249772525019, 6873.965630171817, 6880.174379074543, 6883.105196324445, 6893.827654308582, 6894.758683446055, 6528.510361722795, 6649.601023938386, 6650.401463868275, 6658.0, 6659.53383263167, 6557.278699342973, 6560.376764207393, 6570.11303474044, 6572.244191917991, 6588.978708300221, 6874.793756187322, 6874.8625027500175, 6876.346747059021, 6887.7244910203035, 6889.965550172217, 4452.354408610798, 4458.420405347284, 4466.397036400174, 4468.173387923244, 4469.597736203757, 6482.902756458562, 6601.075849381186, 6601.108850305147, 6615.007748837713, 6621.768238111836, 6401.519886008475, 6402.911505044247, 6427.132337134614, 6430.164078669743, 6432.163818703726, 6405.487560995016, 6411.6794160290865, 6412.487001039813, 6417.6470294132405, 6418.743250269937, 6193.969030154821, 6343.429091381798, 6350.079238510482, 6359.20509936258, 6359.3640635936035, 6422.197225346832, 6482.319356467691, 6489.480841532761, 6498.155239818793, 6499.350064993652, 6214.533909956862, 6306.400891915261, 6345.397187267202, 6345.8730825384155, 6364.013577895434], "unit": "ms", "name": "prototype.html", "value": 4808.163518952851}, {"lowerIsBetter": false, "replicates": [735.8896165575164, 813.5647428625687, 823.9340852731779, 844.7507985144385, 867.3104881619114, 13541.932290338547, 13769.242691651954, 13775.10461819981, 13834.792478112824, 13899.0, 13083.215007099558, 13271.0, 13386.73226535471, 13512.459501619924, 13530.323483825794, 839.0309192882231, 855.8245559660255, 857.2884505860121, 857.3869683176528, 857.438377862501, 824.3034635732796, 843.5739951324573, 851.5571902610639, 854.4104567848196, 854.9016863060734, 883.0418995389978, 897.0670502677241, 901.9549022548879, 902.345799295512, 902.5081330674798, 886.3441053620306, 886.7916039730658, 886.9512176830272, 887.1439061305871, 887.2990337633295, 656.2780940964948, 656.8489247473108, 656.967151642416, 658.2200092889941, 659.0904551718597, 12519.749605007848, 12580.245185288912, 12639.49442022318, 12642.494300228072, 12646.873531264662, 10725.785484290349, 10826.837597436044, 11215.654121505471, 11269.042131418839, 11369.658910232623, 9915.553800079013, 9917.603295868157, 9933.801323973552, 10016.38519654771, 10018.3488073275, 3657.890263292079, 3667.0, 3683.2633473305445, 3725.6460636239512, 3826.2730081284467, 49442.0, 50004.99990000216, 50103.49896501025, 50119.24821127649, 50176.49823501791, 9838.803223935482, 9844.0, 10017.14854237398, 10262.43556604394, 10321.690349289533, 18031.098445077692, 18293.0, 18299.81700182981, 18301.90849045773, 18310.450686479428, 7390.445716571278, 7585.848283034308, 7602.923970760332, 7655.923440765521, 7702.229777022253, 4294.76378799169, 4368.650507959355, 4412.3160910059, 4417.116576684612, 4432.955670443319, 3085.2132706160232, 3137.748980081588, 3141.79578327408, 3142.591463109781, 3145.465270903952, 531.7314756048248, 541.4396100036444, 547.797314993455, 550.2901257377938, 559.7173427419209, 604.3654163128697, 633.2211380002623, 637.8022812928007, 641.3202005873788, 657.5134400543564, 8226.917730822735, 8293.543855087908, 8299.0, 8336.8332633347, 8466.029460032085, 546.7020473841766, 617.7930393318198, 636.2746469025315, 637.9489640828723, 649.8602800397937, 3726.925461490755, 3965.504311961005, 4089.1376036774377, 4211.473565804275, 4331.870043898688, 54512.18231726527, 54611.453885460636, 54689.453105469234, 55231.0, 55326.44673553293, 44667.77666111714, 45100.3234951476, 46892.76553617278, 50004.499954999985, 50615.49384506108, 43819.78090109529, 45737.0, 46377.53622463732, 47363.0, 47393.28910066352, 8311.25198732117, 8911.55442227873, 8967.461952282623, 9010.234130099016, 9024.86462703073, 29793.404131917672, 31488.527672085394, 33892.83053584815, 34142.14644633805, 34788.65211347854, 41520.37719434271, 42274.577254227064, 42407.0, 43935.56064439315, 44315.0, 10984.615538456115, 11095.334279943228, 11143.944280278547, 11223.775524489301, 11266.493007814504, 12111.576094836639, 12389.93805030969, 12524.874751252371, 12716.0, 12825.679358016125, 11250.324980501196, 11562.479688413874, 11588.072954163816, 11942.34317112567, 11946.76106477883, 11101.278416902875, 11414.372209528556, 11493.2529385593, 11630.418479076181, 11849.585264515701, 10941.671749847517, 11180.88819111831, 11331.206815522833, 11440.084793216689, 11687.76624467523, 9235.676751313671, 9281.164695177196, 9386.48374339418, 9503.382280151767, 9571.33000689945, 8742.256908162877, 9201.53992300369, 9343.04545251985, 9356.0, 10008.349457285252], "unit": "ms", "name": "yui.html", "value": 6291.174274920262}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 103.0, 109.0, 112.0, 112.0, 100.0, 110.0, 110.0, 102.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [150.0, 123.0, 147.0, 168.0, 130.0, 155.0, 154.0, 126.0, 158.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 162.0, 162.0, 161.0, 162.0, 165.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.6}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 87.0, 90.0, 71.0, 71.0, 70.0, 95.0, 92.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 79.2}, {"lowerIsBetter": true, "replicates": [94.0, 92.0, 135.0, 94.0, 93.0, 94.0, 135.0, 93.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 57.0, 56.0, 56.0, 57.0, 57.0, 57.0, 59.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 69.0, 70.0, 70.0, 70.0, 70.0, 70.0, 69.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 84.0, 84.0, 82.0, 92.0, 83.0, 83.0, 93.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.2}, {"lowerIsBetter": true, "replicates": [124.0, 116.0, 120.0, 120.0, 118.0, 121.0, 124.0, 119.0, 124.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.6}, {"lowerIsBetter": true, "replicates": [138.0, 163.0, 146.0, 149.0, 147.0, 137.0, 144.0, 150.0, 140.0, 148.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.2}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 69.0, 65.0, 64.0, 64.0, 65.0, 65.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.66326362588507], "unit": "score", "name": "Richards", "value": 214.38904599999995}, {"lowerIsBetter": false, "replicates": [208.7246921310791], "unit": "score", "name": "DeltaBlue", "value": 316.77133800000007}, {"lowerIsBetter": false, "replicates": [282.88543140028287], "unit": "score", "name": "Encrypt", "value": 940.9498350000002}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [1108.6474501108648], "unit": "score", "name": "RayTrace", "value": 667.4700779999998}, {"lowerIsBetter": false, "replicates": [799.3605115907275], "unit": "score", "name": "Earley", "value": 833.7452129999997}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [25450.0], "unit": "score", "name": "RegExp", "value": 35.79508840864438}, {"lowerIsBetter": false, "replicates": [646.7598475222363], "unit": "score", "name": "Splay", "value": 125.99885461689593}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7"}]} 19:06:08 INFO - RETURN: dromaeo_css: 6925.98 19:06:08 INFO - RETURN: kraken: 1492.70 19:06:08 INFO - RETURN: v8_7: 20696.50 19:06:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20696.50"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1492.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6925.98"}}} 19:06:08 INFO - Return code: 0 19:06:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:06:08 INFO - # TBPL SUCCESS # 19:06:08 INFO - Running post-action listener: _resource_record_post_action 19:06:08 INFO - Running post-run listener: _resource_record_post_run 19:06:09 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 286720; Write bytes: 271548416; Read time: 108; Write time: 186320 19:06:09 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 712704; Read time: 0; Write time: 60 19:06:09 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 286720; Write bytes: 270835712; Read time: 108; Write time: 186260 19:06:09 INFO - Running post-run listener: _upload_blobber_files 19:06:09 INFO - Blob upload gear active. 19:06:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:06:09 INFO - Copying logs to upload dir... 19:06:09 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.100576 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 19:06:09.665187) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 19:06:09.668581) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010024 build_url: 'https://queue.taskcluster.net/v1/task/Y64MClBPRzqkPeeBebZ_ww/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-11-24 19:06:09.720993) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:06:09.721415) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7f67ac1ceef7774224e327f2000001f6-1448417664.731501-142352380 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 19:06:09.771332) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 19:06:09.771697) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 19:06:09.772047) ========= ========= Total master_lag: 0.26 =========