builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1452258350.56 results: success (0) buildid: 20160108023007 builduid: 192604ca3f044d5b81b93e06c8ac12e4 revision: e12dc3542ba00415e1b2cb316141e9d170a23270 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.558696) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.559122) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.559410) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006469 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.587282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.587570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.656108) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.656366) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.715448) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.715729) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.716076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.716360) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-08 05:05:51-- 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% 43.4M=0s 2016-01-08 05:05:51 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.895300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.895596) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018124 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.945255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:50.945604) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e12dc3542ba00415e1b2cb316141e9d170a23270 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e12dc3542ba00415e1b2cb316141e9d170a23270 --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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-08 05:05:51,517 truncating revision to first 12 chars 2016-01-08 05:05:51,517 Setting DEBUG logging. 2016-01-08 05:05:51,517 attempt 1/10 2016-01-08 05:05:51,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e12dc3542ba0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-08 05:05:51,841 unpacking tar archive at: fx-team-e12dc3542ba0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589532 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:51.547952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:51.548258) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:51.558118) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:51.558447) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 05:05:51.558880) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-01-08 05:05:51.559181) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:05:52 INFO - MultiFileLogger online at 20160108 05:05:52 in /builds/slave/test-pgo 05:05:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:05:52 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:05:52 INFO - {'append_to_log': False, 05:05:52 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:05:52 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:05:52 INFO - 'branch': 'Fx-Team', 05:05:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:52 INFO - 'config_files': ('talos/linux_config.py',), 05:05:52 INFO - 'default_actions': ('clobber', 05:05:52 INFO - 'read-buildbot-config', 05:05:52 INFO - 'download-and-extract', 05:05:52 INFO - 'populate-webroot', 05:05:52 INFO - 'create-virtualenv', 05:05:52 INFO - 'install', 05:05:52 INFO - 'run-tests'), 05:05:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:52 INFO - 'download_minidump_stackwalk': True, 05:05:52 INFO - 'download_symbols': 'ondemand', 05:05:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:52 INFO - 'installer_path': 'installer.exe', 05:05:52 INFO - 'log_level': 'info', 05:05:52 INFO - 'log_name': 'talos', 05:05:52 INFO - 'log_to_console': True, 05:05:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:05:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:05:52 INFO - 'opt_config_files': (), 05:05:52 INFO - 'pip_index': False, 05:05:52 INFO - 'sps_profile': False, 05:05:52 INFO - 'sps_profile_interval': 0, 05:05:52 INFO - 'suite': 'dromaeojs', 05:05:52 INFO - 'system_bits': '32', 05:05:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:52 INFO - 'title': 'talos-linux64-ix-034', 05:05:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:52 INFO - 'use_talos_json': True, 05:05:52 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:05:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:52 INFO - 'work_dir': 'build'} 05:05:52 INFO - ##### 05:05:52 INFO - ##### Running clobber step. 05:05:52 INFO - ##### 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: clobber 05:05:52 INFO - rmtree: /builds/slave/test-pgo/build 05:05:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:05:52 INFO - Running post-action listener: _resource_record_post_action 05:05:52 INFO - ##### 05:05:52 INFO - ##### Running read-buildbot-config step. 05:05:52 INFO - ##### 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: read_buildbot_config 05:05:52 INFO - Using buildbot properties: 05:05:52 INFO - { 05:05:52 INFO - "project": "", 05:05:52 INFO - "product": "firefox", 05:05:52 INFO - "script_repo_revision": "production", 05:05:52 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:05:52 INFO - "repository": "", 05:05:52 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:05:52 INFO - "buildid": "20160108023007", 05:05:52 INFO - "pgo_build": "True", 05:05:52 INFO - "basedir": "/builds/slave/test-pgo", 05:05:52 INFO - "buildnumber": 573, 05:05:52 INFO - "slavename": "talos-linux64-ix-034", 05:05:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:05:52 INFO - "platform": "ubuntu64_hw", 05:05:52 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:05:52 INFO - "branch": "fx-team", 05:05:52 INFO - "repo_path": "integration/fx-team", 05:05:52 INFO - "revision": "e12dc3542ba00415e1b2cb316141e9d170a23270", 05:05:52 INFO - "stage_platform": "linux64-pgo", 05:05:52 INFO - "builduid": "192604ca3f044d5b81b93e06c8ac12e4", 05:05:52 INFO - "slavebuilddir": "test" 05:05:52 INFO - } 05:05:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:05:52 INFO - Running post-action listener: _resource_record_post_action 05:05:52 INFO - ##### 05:05:52 INFO - ##### Running download-and-extract step. 05:05:52 INFO - ##### 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: download_and_extract 05:05:52 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:52 INFO - https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:05:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/test_packages.json 05:05:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/test_packages.json 05:05:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:05:53 INFO - Downloaded 1302 bytes. 05:05:53 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:05:53 INFO - Using the following test package requirements: 05:05:53 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:05:53 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:05:53 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'jsshell-linux-x86_64.zip'], 05:05:53 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:05:53 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:05:53 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:05:53 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:05:53 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:05:53 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:05:53 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:05:53 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:05:53 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:53 INFO - https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:05:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:05:54 INFO - Downloaded 24018302 bytes. 05:05:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:05:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:05:56 INFO - Return code: 0 05:05:56 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:56 INFO - https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:05:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:05:57 INFO - Downloaded 24018302 bytes. 05:05:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:05:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:05:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:05:58 INFO - Return code: 0 05:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:58 INFO - https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:05:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:05:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:05:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:05:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:06:00 INFO - Downloaded 11132285 bytes. 05:06:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:06:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:06:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:06:00 INFO - Return code: 0 05:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:00 INFO - https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:06:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:06:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:06:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:06:02 INFO - Downloaded 55899443 bytes. 05:06:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:06:02 INFO - mkdir: /builds/slave/test-pgo/properties 05:06:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:06:02 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:06:02 INFO - Contents: 05:06:02 INFO - build_url:https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:06:02 INFO - Running post-action listener: _resource_record_post_action 05:06:02 INFO - Running post-action listener: set_extra_try_arguments 05:06:02 INFO - ##### 05:06:02 INFO - ##### Running populate-webroot step. 05:06:02 INFO - ##### 05:06:02 INFO - Running pre-action listener: _resource_record_pre_action 05:06:02 INFO - Running main action method: populate_webroot 05:06:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:02 INFO - '--tppagecycles', 05:06:02 INFO - '7'], 05:06:02 INFO - 'tests': ['tsvgm']}}, 05:06:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:06:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:06:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:06:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['damp', 'tps']}, 05:06:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['damp', 'tps']}, 05:06:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:06:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:02 INFO - 'other': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'other_l64': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'other_nol64': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore']}, 05:06:02 INFO - 'svgr': {'tests': ['tsvgx', 05:06:02 INFO - 'tsvgr_opacity', 05:06:02 INFO - 'tart', 05:06:02 INFO - 'tscrollx', 05:06:02 INFO - 'cart']}, 05:06:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tsvgx', 05:06:02 INFO - 'tsvgr_opacity', 05:06:02 INFO - 'tart', 05:06:02 INFO - 'tscrollx', 05:06:02 INFO - 'cart']}, 05:06:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['tp5o']}, 05:06:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tp5o']}, 05:06:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--xperf_path', 05:06:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:02 INFO - 'tests': ['tp5n']}, 05:06:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s', 05:06:02 INFO - '--xperf_path', 05:06:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:02 INFO - 'tests': ['tp5n']}}, 05:06:02 INFO - 'talos.zip': {'path': '', 05:06:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:02 INFO - Running post-action listener: _resource_record_post_action 05:06:02 INFO - ##### 05:06:02 INFO - ##### Running create-virtualenv step. 05:06:02 INFO - ##### 05:06:02 INFO - Running pre-action listener: _resource_record_pre_action 05:06:02 INFO - Running main action method: create_virtualenv 05:06:02 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:06:02 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 05:06:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:06:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:02 INFO - Using real prefix '/usr' 05:06:02 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:06:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:05 INFO - Installing pip.................done. 05:06:05 INFO - Return code: 0 05:06:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:05 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:05 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 05:06:05 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 05:06:05 INFO - Using env: {'DISPLAY': ':0', 05:06:05 INFO - 'HOME': '/home/cltbld', 05:06:05 INFO - 'LANG': 'en_US.UTF-8', 05:06:05 INFO - 'LANGUAGE': 'en_US:en', 05:06:05 INFO - 'LOGNAME': 'cltbld', 05:06:05 INFO - 'MAIL': '/var/mail/cltbld', 05:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:05 INFO - 'MOZ_NO_REMOTE': '1', 05:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:05 INFO - 'NO_EM_RESTART': '1', 05:06:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:05 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:05 INFO - 'SHELL': '/bin/bash', 05:06:05 INFO - 'SHLVL': '1', 05:06:05 INFO - 'TERM': 'linux', 05:06:05 INFO - 'TMOUT': '86400', 05:06:05 INFO - 'USER': 'cltbld', 05:06:05 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:05 INFO - '_': '/tools/buildbot/bin/python'} 05:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:06:05 INFO - Cleaning up... 05:06:05 INFO - Return code: 0 05:06:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:05 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:05 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 05:06:05 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 05:06:05 INFO - Using env: {'DISPLAY': ':0', 05:06:05 INFO - 'HOME': '/home/cltbld', 05:06:05 INFO - 'LANG': 'en_US.UTF-8', 05:06:05 INFO - 'LANGUAGE': 'en_US:en', 05:06:05 INFO - 'LOGNAME': 'cltbld', 05:06:05 INFO - 'MAIL': '/var/mail/cltbld', 05:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:05 INFO - 'MOZ_NO_REMOTE': '1', 05:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:05 INFO - 'NO_EM_RESTART': '1', 05:06:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:05 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:05 INFO - 'SHELL': '/bin/bash', 05:06:05 INFO - 'SHLVL': '1', 05:06:05 INFO - 'TERM': 'linux', 05:06:05 INFO - 'TMOUT': '86400', 05:06:05 INFO - 'USER': 'cltbld', 05:06:05 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:05 INFO - '_': '/tools/buildbot/bin/python'} 05:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:05 INFO - Downloading/unpacking psutil>=0.7.1 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:07 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:06:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:06:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:07 INFO - Installing collected packages: psutil 05:06:07 INFO - Running setup.py install for psutil 05:06:07 INFO - building 'psutil._psutil_linux' extension 05:06:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:06:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:06:07 INFO - building 'psutil._psutil_posix' extension 05:06:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:06:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:06:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:08 INFO - Successfully installed psutil 05:06:08 INFO - Cleaning up... 05:06:08 INFO - Return code: 0 05:06:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:08 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:08 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 05:06:08 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 05:06:08 INFO - Using env: {'DISPLAY': ':0', 05:06:08 INFO - 'HOME': '/home/cltbld', 05:06:08 INFO - 'LANG': 'en_US.UTF-8', 05:06:08 INFO - 'LANGUAGE': 'en_US:en', 05:06:08 INFO - 'LOGNAME': 'cltbld', 05:06:08 INFO - 'MAIL': '/var/mail/cltbld', 05:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:08 INFO - 'MOZ_NO_REMOTE': '1', 05:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:08 INFO - 'NO_EM_RESTART': '1', 05:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:08 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:08 INFO - 'SHELL': '/bin/bash', 05:06:08 INFO - 'SHLVL': '1', 05:06:08 INFO - 'TERM': 'linux', 05:06:08 INFO - 'TMOUT': '86400', 05:06:08 INFO - 'USER': 'cltbld', 05:06:08 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:08 INFO - '_': '/tools/buildbot/bin/python'} 05:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:06:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:06:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:06:10 INFO - Installing collected packages: mozsystemmonitor 05:06:10 INFO - Running setup.py install for mozsystemmonitor 05:06:10 INFO - Successfully installed mozsystemmonitor 05:06:10 INFO - Cleaning up... 05:06:10 INFO - Return code: 0 05:06:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:10 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:10 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 05:06:10 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 05:06:10 INFO - Using env: {'DISPLAY': ':0', 05:06:10 INFO - 'HOME': '/home/cltbld', 05:06:10 INFO - 'LANG': 'en_US.UTF-8', 05:06:10 INFO - 'LANGUAGE': 'en_US:en', 05:06:10 INFO - 'LOGNAME': 'cltbld', 05:06:10 INFO - 'MAIL': '/var/mail/cltbld', 05:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:10 INFO - 'MOZ_NO_REMOTE': '1', 05:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:10 INFO - 'NO_EM_RESTART': '1', 05:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:10 INFO - 'SHELL': '/bin/bash', 05:06:10 INFO - 'SHLVL': '1', 05:06:10 INFO - 'TERM': 'linux', 05:06:10 INFO - 'TMOUT': '86400', 05:06:10 INFO - 'USER': 'cltbld', 05:06:10 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:10 INFO - '_': '/tools/buildbot/bin/python'} 05:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:10 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:12 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:06:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:06:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:06:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:12 INFO - Downloading docopt-0.6.1.tar.gz 05:06:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:06:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:12 INFO - Running setup.py install for blobuploader 05:06:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:13 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:06:13 INFO - Running setup.py install for requests 05:06:13 INFO - Running setup.py install for docopt 05:06:13 INFO - Successfully installed blobuploader requests docopt 05:06:13 INFO - Cleaning up... 05:06:13 INFO - Return code: 0 05:06:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:13 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:13 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 05:06:13 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 05:06:13 INFO - Using env: {'DISPLAY': ':0', 05:06:13 INFO - 'HOME': '/home/cltbld', 05:06:13 INFO - 'LANG': 'en_US.UTF-8', 05:06:13 INFO - 'LANGUAGE': 'en_US:en', 05:06:13 INFO - 'LOGNAME': 'cltbld', 05:06:13 INFO - 'MAIL': '/var/mail/cltbld', 05:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:13 INFO - 'MOZ_NO_REMOTE': '1', 05:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:13 INFO - 'NO_EM_RESTART': '1', 05:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:13 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:13 INFO - 'SHELL': '/bin/bash', 05:06:13 INFO - 'SHLVL': '1', 05:06:13 INFO - 'TERM': 'linux', 05:06:13 INFO - 'TMOUT': '86400', 05:06:13 INFO - 'USER': 'cltbld', 05:06:13 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:13 INFO - '_': '/tools/buildbot/bin/python'} 05:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:06:13 INFO - Running setup.py (path:/tmp/pip-C0RW2a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:06:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:06:13 INFO - Running setup.py (path:/tmp/pip-8bdaZ7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:06:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:06:13 INFO - Running setup.py (path:/tmp/pip-TTLasY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:06:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:06:13 INFO - Running setup.py (path:/tmp/pip-lTqzIq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:06:14 INFO - Running setup.py (path:/tmp/pip-C1WZ0l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:06:14 INFO - Running setup.py (path:/tmp/pip-CgAEUX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:06:14 INFO - Running setup.py (path:/tmp/pip-01IAj4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:06:14 INFO - Running setup.py (path:/tmp/pip-QjiVSX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:06:14 INFO - Running setup.py (path:/tmp/pip-d3ct0l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:06:14 INFO - Running setup.py (path:/tmp/pip-mXk5dq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:06:14 INFO - Running setup.py (path:/tmp/pip-CmYYxD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:06:14 INFO - Running setup.py (path:/tmp/pip-LJdevp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:06:14 INFO - Running setup.py (path:/tmp/pip-b1Gy2f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:06:14 INFO - Running setup.py (path:/tmp/pip-LcExG8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:06:14 INFO - Running setup.py (path:/tmp/pip-W9MPWO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:06:14 INFO - Running setup.py (path:/tmp/pip-08BMVN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:06:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:06:14 INFO - Running setup.py (path:/tmp/pip-hxXa3z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:06:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:14 INFO - Running setup.py install for manifestparser 05:06:15 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Running setup.py install for mozcrash 05:06:15 INFO - Running setup.py install for mozdebug 05:06:15 INFO - Running setup.py install for mozdevice 05:06:15 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Running setup.py install for mozfile 05:06:15 INFO - Running setup.py install for mozhttpd 05:06:15 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Running setup.py install for mozinfo 05:06:15 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Running setup.py install for mozInstall 05:06:15 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:06:15 INFO - Running setup.py install for mozleak 05:06:15 INFO - Running setup.py install for mozlog 05:06:15 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Running setup.py install for moznetwork 05:06:16 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Running setup.py install for mozprocess 05:06:16 INFO - Running setup.py install for mozprofile 05:06:16 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Running setup.py install for mozrunner 05:06:16 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Running setup.py install for mozscreenshot 05:06:16 INFO - Running setup.py install for moztest 05:06:16 INFO - Running setup.py install for mozversion 05:06:16 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:06:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:16 INFO - Cleaning up... 05:06:16 INFO - Return code: 0 05:06:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:06: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')]} 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:16 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:16 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 05:06:16 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 05:06:16 INFO - Using env: {'DISPLAY': ':0', 05:06:16 INFO - 'HOME': '/home/cltbld', 05:06:16 INFO - 'LANG': 'en_US.UTF-8', 05:06:16 INFO - 'LANGUAGE': 'en_US:en', 05:06:16 INFO - 'LOGNAME': 'cltbld', 05:06:16 INFO - 'MAIL': '/var/mail/cltbld', 05:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:16 INFO - 'MOZ_NO_REMOTE': '1', 05:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:16 INFO - 'NO_EM_RESTART': '1', 05:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:16 INFO - 'SHELL': '/bin/bash', 05:06:16 INFO - 'SHLVL': '1', 05:06:16 INFO - 'TERM': 'linux', 05:06:16 INFO - 'TMOUT': '86400', 05:06:16 INFO - 'USER': 'cltbld', 05:06:16 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:16 INFO - '_': '/tools/buildbot/bin/python'} 05:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:06:16 INFO - Running setup.py (path:/tmp/pip-q08zFo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:06:17 INFO - Running setup.py (path:/tmp/pip-_3zUa_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:06:17 INFO - Running setup.py (path:/tmp/pip-IrBjDN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:06:17 INFO - Running setup.py (path:/tmp/pip-XB3sDe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:06:17 INFO - Running setup.py (path:/tmp/pip-JTXPaP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:06:17 INFO - Running setup.py (path:/tmp/pip-GBLxrH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:06:17 INFO - Running setup.py (path:/tmp/pip-L5XYG4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:06:17 INFO - Running setup.py (path:/tmp/pip-G7Hzgo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:06:17 INFO - Running setup.py (path:/tmp/pip-Pkxgin-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:06:17 INFO - Running setup.py (path:/tmp/pip-_iPbKj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:06:17 INFO - Running setup.py (path:/tmp/pip-qgXFRu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:06:17 INFO - Running setup.py (path:/tmp/pip-d5P8Rx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:06:17 INFO - Running setup.py (path:/tmp/pip-Gj4_1o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:06:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:06:17 INFO - Running setup.py (path:/tmp/pip-3ETOIh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:06:17 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)) 05:06:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:06:17 INFO - Running setup.py (path:/tmp/pip-bl5kxb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:06:18 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)) 05:06:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:06:18 INFO - Running setup.py (path:/tmp/pip-wyADUK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:06:18 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)) 05:06:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:06:18 INFO - Running setup.py (path:/tmp/pip-FRIEXT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 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)) 05:06:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:19 INFO - Downloading blessings-1.5.1.tar.gz 05:06:19 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 05:06:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:19 INFO - Installing collected packages: blessings 05:06:19 INFO - Running setup.py install for blessings 05:06:20 INFO - Successfully installed blessings 05:06:20 INFO - Cleaning up... 05:06:20 INFO - Return code: 0 05:06:20 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:06:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:06:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:06:20 INFO - Reading from file tmpfile_stdout 05:06:20 INFO - Current package versions: 05:06:20 INFO - argparse == 1.2.1 05:06:20 INFO - blessings == 1.5.1 05:06:20 INFO - blobuploader == 1.2.4 05:06:20 INFO - docopt == 0.6.1 05:06:20 INFO - manifestparser == 1.1 05:06:20 INFO - mozInstall == 1.12 05:06:20 INFO - mozcrash == 0.16 05:06:20 INFO - mozdebug == 0.1 05:06:20 INFO - mozdevice == 0.47 05:06:20 INFO - mozfile == 1.2 05:06:20 INFO - mozhttpd == 0.7 05:06:20 INFO - mozinfo == 0.9 05:06:20 INFO - mozleak == 0.1 05:06:20 INFO - mozlog == 3.1 05:06:20 INFO - moznetwork == 0.27 05:06:20 INFO - mozprocess == 0.22 05:06:20 INFO - mozprofile == 0.28 05:06:20 INFO - mozrunner == 6.11 05:06:20 INFO - mozscreenshot == 0.1 05:06:20 INFO - mozsystemmonitor == 0.0 05:06:20 INFO - moztest == 0.7 05:06:20 INFO - mozversion == 1.4 05:06:20 INFO - psutil == 3.1.1 05:06:20 INFO - requests == 1.2.3 05:06:20 INFO - wsgiref == 0.1.2 05:06:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:20 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:20 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 05:06:20 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 05:06:20 INFO - Using env: {'DISPLAY': ':0', 05:06:20 INFO - 'HOME': '/home/cltbld', 05:06:20 INFO - 'LANG': 'en_US.UTF-8', 05:06:20 INFO - 'LANGUAGE': 'en_US:en', 05:06:20 INFO - 'LOGNAME': 'cltbld', 05:06:20 INFO - 'MAIL': '/var/mail/cltbld', 05:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:20 INFO - 'MOZ_NO_REMOTE': '1', 05:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:20 INFO - 'NO_EM_RESTART': '1', 05:06:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:20 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:20 INFO - 'SHELL': '/bin/bash', 05:06:20 INFO - 'SHLVL': '1', 05:06:20 INFO - 'TERM': 'linux', 05:06:20 INFO - 'TMOUT': '86400', 05:06:20 INFO - 'USER': 'cltbld', 05:06:20 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:20 INFO - '_': '/tools/buildbot/bin/python'} 05:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 05:06:20 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 2)) 05:06:20 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 3)) 05:06:20 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 4)) 05:06:20 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 5)) 05:06:20 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 6)) 05:06:20 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 7)) 05:06:20 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 8)) 05:06:20 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 9)) 05:06:20 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:06:20 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 4)) 05:06:20 INFO - Cleaning up... 05:06:20 INFO - Return code: 0 05:06:20 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:20 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 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06:20 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 05:06:20 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 05:06:20 INFO - Using env: {'DISPLAY': ':0', 05:06:20 INFO - 'HOME': '/home/cltbld', 05:06:20 INFO - 'LANG': 'en_US.UTF-8', 05:06:20 INFO - 'LANGUAGE': 'en_US:en', 05:06:20 INFO - 'LOGNAME': 'cltbld', 05:06:20 INFO - 'MAIL': '/var/mail/cltbld', 05:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:20 INFO - 'MOZ_NO_REMOTE': '1', 05:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:20 INFO - 'NO_EM_RESTART': '1', 05:06:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:20 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:20 INFO - 'SHELL': '/bin/bash', 05:06:20 INFO - 'SHLVL': '1', 05:06:20 INFO - 'TERM': 'linux', 05:06:20 INFO - 'TMOUT': '86400', 05:06:20 INFO - 'USER': 'cltbld', 05:06:20 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:20 INFO - '_': '/tools/buildbot/bin/python'} 05:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:20 INFO - Downloading/unpacking jsonschema 05:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:06:22 INFO - Downloading/unpacking functools32 (from jsonschema) 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06:22 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:06:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:22 INFO - warning: no files found matching '*.txt' 05:06:22 INFO - no previously-included directories found matching 'build' 05:06:22 INFO - no previously-included directories found matching 'dist' 05:06:22 INFO - no previously-included directories found matching '.git*' 05:06:22 INFO - Installing collected packages: jsonschema, functools32 05:06:22 INFO - Running setup.py install for functools32 05:06:22 INFO - warning: no files found matching '*.txt' 05:06:22 INFO - no previously-included directories found matching 'build' 05:06:22 INFO - no previously-included directories found matching 'dist' 05:06:22 INFO - no previously-included directories found matching '.git*' 05:06:22 INFO - Successfully installed jsonschema functools32 05:06:22 INFO - Cleaning up... 05:06:22 INFO - Return code: 0 05:06:22 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:06: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')]} 05:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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')]} 05:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f28bbad6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1641480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, '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': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', '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 05:06: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', 'flake8'] in /builds/slave/test-pgo/build 05:06: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 flake8 05:06:22 INFO - Using env: {'DISPLAY': ':0', 05:06:22 INFO - 'HOME': '/home/cltbld', 05:06:22 INFO - 'LANG': 'en_US.UTF-8', 05:06:22 INFO - 'LANGUAGE': 'en_US:en', 05:06:22 INFO - 'LOGNAME': 'cltbld', 05:06:22 INFO - 'MAIL': '/var/mail/cltbld', 05:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:22 INFO - 'MOZ_NO_REMOTE': '1', 05:06:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:22 INFO - 'NO_EM_RESTART': '1', 05:06:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:22 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:22 INFO - 'SHELL': '/bin/bash', 05:06:22 INFO - 'SHLVL': '1', 05:06:22 INFO - 'TERM': 'linux', 05:06:22 INFO - 'TMOUT': '86400', 05:06:22 INFO - 'USER': 'cltbld', 05:06:22 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:22 INFO - '_': '/tools/buildbot/bin/python'} 05:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:23 INFO - Downloading/unpacking flake8 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:06:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:06:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:06:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:06:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:25 INFO - no previously-included directories found matching 'docs/_build' 05:06:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:25 INFO - Running setup.py install for pep8 05:06:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:25 INFO - no previously-included directories found matching 'docs/_build' 05:06:25 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:06:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:25 INFO - Cleaning up... 05:06:25 INFO - Return code: 0 05:06:25 INFO - Running post-action listener: _resource_record_post_action 05:06:25 INFO - Running post-action listener: _start_resource_monitoring 05:06:25 INFO - Starting resource monitoring. 05:06:25 INFO - ##### 05:06:25 INFO - ##### Running install step. 05:06:25 INFO - ##### 05:06:25 INFO - Running pre-action listener: _resource_record_pre_action 05:06:25 INFO - Running main action method: install 05:06:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:06:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:06:25 INFO - Reading from file tmpfile_stdout 05:06:25 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:06:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:06:26 INFO - Reading from file tmpfile_stdout 05:06:26 INFO - Output received: 05:06:26 INFO - Usage: mozinstall [options] installer 05:06:26 INFO - Options: 05:06:26 INFO - -h, --help show this help message and exit 05:06:26 INFO - -d DEST, --destination=DEST 05:06:26 INFO - Directory to install application into. [default: 05:06:26 INFO - "/builds/slave/test-pgo"] 05:06:26 INFO - --app=APP Application being installed. [default: firefox] 05:06:26 INFO - mkdir: /builds/slave/test-pgo/build/application 05:06:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:06:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:06:42 INFO - Reading from file tmpfile_stdout 05:06:42 INFO - Output received: 05:06:42 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:06:42 INFO - Running post-action listener: _resource_record_post_action 05:06:42 INFO - ##### 05:06:42 INFO - ##### Running run-tests step. 05:06:42 INFO - ##### 05:06:42 INFO - Running pre-action listener: _resource_record_pre_action 05:06:42 INFO - Running main action method: run_tests 05:06:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:06:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:06:42 INFO - Python 2.7.3 05:06:42 INFO - Return code: 0 05:06:42 INFO - grabbing minidump binary from tooltool 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:42 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 0x16d0660>, '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 0x16d1560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d1a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:06:42 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 05:06:42 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 05:06:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:42 INFO - Return code: 0 05:06:42 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:06:42 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:06:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:06:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:06:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:06:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:06:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:06:43 INFO - Return code: 0 05:06:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 05:06:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 05:06:43 INFO - Using env: {'DISPLAY': ':0', 05:06:43 INFO - 'HOME': '/home/cltbld', 05:06:43 INFO - 'LANG': 'en_US.UTF-8', 05:06:43 INFO - 'LANGUAGE': 'en_US:en', 05:06:43 INFO - 'LOGNAME': 'cltbld', 05:06:43 INFO - 'MAIL': '/var/mail/cltbld', 05:06:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:06:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:43 INFO - 'MOZ_NO_REMOTE': '1', 05:06:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:43 INFO - 'NO_EM_RESTART': '1', 05:06:43 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:06:43 INFO - 'PWD': '/builds/slave/test-pgo', 05:06:43 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:06:43 INFO - 'SHELL': '/bin/bash', 05:06:43 INFO - 'SHLVL': '1', 05:06:43 INFO - 'TERM': 'linux', 05:06:43 INFO - 'TMOUT': '86400', 05:06:43 INFO - 'USER': 'cltbld', 05:06:43 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114', 05:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:43 INFO - '_': '/tools/buildbot/bin/python'} 05:06:43 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:06:43 INFO - mozversion application_buildid: 20160108023007 05:06:43 INFO - mozversion application_changeset: e12dc3542ba00415e1b2cb316141e9d170a23270 05:06:43 INFO - mozversion application_display_name: Nightly 05:06:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:43 INFO - mozversion application_name: Firefox 05:06:43 INFO - mozversion application_remotingname: firefox 05:06:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:43 INFO - mozversion application_vendor: Mozilla 05:06:43 INFO - mozversion application_version: 46.0a1 05:06:43 INFO - mozversion platform_buildid: 20160108023007 05:06:43 INFO - mozversion platform_changeset: e12dc3542ba00415e1b2cb316141e9d170a23270 05:06:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:43 INFO - mozversion platform_version: 46.0a1 05:06:43 INFO - using testdate: 1452258403 05:06:43 INFO - actual date: 1452258403 05:06:43 INFO - starting webserver on 'localhost:48429' 05:06:43 INFO - SUITE-START | Running 3 tests 05:06:43 INFO - TEST-START | dromaeo_css 05:06:43 INFO - operating with platform_type : linux_ 05:06:43 INFO - Initialising browser for dromaeo_css test... 05:06:43 INFO - TEST-INFO | started process 23844 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKKn0Pp/profile http://localhost:48429/getInfo.html) 05:06:47 INFO - PROCESS | 23844 | __metrics Screen width/height:1600/1200 05:06:47 INFO - PROCESS | 23844 | colorDepth:24 05:06:47 INFO - PROCESS | 23844 | Browser inner width/height: 1024/697 05:06:47 INFO - PROCESS | 23844 | __metrics 05:06:48 INFO - PROCESS | 23844 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:06:48 INFO - PROCESS | 23844 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:48 INFO - TEST-INFO | 23844: exit 0 05:06:48 INFO - Browser initialized. 05:06:48 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:48 INFO - TEST-INFO | started process 23935 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKKn0Pp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:00 INFO - PROCESS | 23935 | RSS: Main: 150896640 05:07:00 INFO - PROCESS | 23935 | 05:07:52 INFO - PROCESS | 23935 | 1452258472243 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:10:00 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48429/tests/dromaeo/cssquery-ext.html) 05:10:01 INFO - PROCESS | 23935 | RSS: Main: 285835264 05:10:01 INFO - PROCESS | 23935 | 05:13:04 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-ext.html (next: http://localhost:48429/tests/dromaeo/cssquery-jquery.html) 05:13:04 INFO - PROCESS | 23935 | RSS: Main: 244727808 05:13:04 INFO - PROCESS | 23935 | 05:16:06 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48429/tests/dromaeo/cssquery-mootools.html) 05:16:07 INFO - PROCESS | 23935 | RSS: Main: 233037824 05:16:07 INFO - PROCESS | 23935 | 05:19:09 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48429/tests/dromaeo/cssquery-prototype.html) 05:19:09 INFO - PROCESS | 23935 | RSS: Main: 217403392 05:19:09 INFO - PROCESS | 23935 | 05:22:12 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48429/tests/dromaeo/cssquery-yui.html) 05:22:13 INFO - PROCESS | 23935 | RSS: Main: 219312128 05:22:13 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | Cycle 1(1): loaded http://localhost:48429/tests/dromaeo/cssquery-yui.html (next: http://localhost:48429/tests/dromaeo/cssquery-dojo.html) 05:25:15 INFO - PROCESS | 23935 | RSS: Main: 287109120 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | __start_tp_report 05:25:15 INFO - PROCESS | 23935 | _x_x_mozilla_page_load 05:25:15 INFO - PROCESS | 23935 | _x_x_mozilla_page_load_details 05:25:15 INFO - PROCESS | 23935 | |i|pagename|runs| 05:25:15 INFO - PROCESS | 23935 | |0;dojo.html;3069.677683843196;3117.703818137279;3152.1646763607646;3153.59003329567;3156.968430315696;4986.501349865011;5069.518395752402;5074.568661663763;5075.898482030358;5085.288059671644;5086.237064440326;5090.440051594331;5106.234064890268;5205.089109405848;5226.08543504887;4673.719576825393;4683.976580117094;4694.366260554815;4712.458067322251;4816.012717392926;4364.585364390377;4718.103560323545;4764.428268607772;4765.261384485393;4822.469528351879;4609.953900460986;4631.930521042187;4680.087382960324;4717.221658426355;4730.290456431529;4704.95295047052;4710.764461776932;4717.033008233304;4736.384270044872;4765.928511072337;4674.766261686902;4679.9298010529865;4681.0871879983415;4702.882427939329;4715.198416269208;4878.780454879504;4973.900521989576;4975.104481193383;4980.302757613937;5027.346444962168;5389.649672771257;5442.292501974757;5464.480874316973;5464.508194262496;5468.48049435303;5444.673319600836;5477.835664930059;5485.040117979338;5514.724263786795;5534.252875861787;5108.7445627718835;5112;5120.15517439622;5121.206213036985;5165.070287348313;5438.7280635968045;5509.256250406224;5551.1673249012165;5568.665880047129;5628.746706398222;5297.947020529746;5438.945610543844;5441.8095366662765;5442.673439593637;5487;5460.399356070829;5496.258005169331;5499.340079190442;5506.421825708323;5511.559075273968;5424.1321388577635;5478.863028424321;5485.341758988867;5516.117421212587;5550.47270509301;5430.972845135749;5511.779528818883;5512.283403157644;5533.529649979716;5558.666480011212;4285.8285668573535;4292.055747735493;4292.678049146327;4299.1831552005015;4332.220200363901;3803.4675145479655;3839.212961342918;3857.3828187490426;3873.1479074603544;3888.572257051722;5077.111505486525;5114.079465696136;5124.718140502308;5151.381834179922;5189.325387699575;5512.062949298629;5512.586556008316;5513.2005859150195;5516.7517461714315;5534.7786088556;5045.445001049881;5136.922946155811;5139.614528910347;5139.871503212375;5150.381954165449;4657.46439159501;4677.672562920631;4711.929321060187;4729.196036673774;4743.976280118577;5657.066584013592;5663;5669.177969194444;5671.17767923657;5671.631343962629;5422.29510163692;5459.2902922619805;5473.8357849264585;5477.397486276427;5529.170624406372;5572.442755724557;5666.801661941812;5691.857703557444;5693.4875861172695;5778.191053252632;5455.236266922713;5468.5351745100525;5470.316210473691;5474.370447398441;5479.397266300624;5487.067198576331;5511.5039646432015;5525.585581081516;5539.14143307788;5604.299462567179;5316.548093411949;5411.594130440154;5420.891582168256;5420.891582168413;5436.891262174655;5340.332458442695;5405.0270951227585;5407.756650950795;5413.675179489243;5456.399796022513;5231.319928409434;5306.204069389622;5306.708131052829;5317.388500322512;5351.902859913786;5322.574194064543;5362.51737343641;5377.27406800077;5394.32570928634;5430.755615997368;5277.736113194402;5286.36563612369;5314.415414304346;5315.04329220744;5316.760745766373 05:25:15 INFO - PROCESS | 23935 | |1;ext.html;525.0863497514325;525.5900397689801;534.6551474299077;556.6743455078779;556.983290501285;27981;28530;36087.458688119696;36736.26527469449;36824.447633285454;38690.419643705296;39319.41020884682;39359;39367.60632393675;39423.21153576927;7402.925970740277;8361.33109351252;8549.529775862326;8562.400631955765;8563.715442683584;7499.962500187491;7695.115061767884;9855.753606159868;10065.245106617032;10119.139873110795;9798.559064842064;9969.700908972705;9993.500324983757;9993.800123997516;10001.699949001542;7502;7714.575698336589;9808.90191098087;9907.653232136912;9908.603655853793;9821.705348839547;10124.69625911224;10134.949325253327;10142.797144057078;10144.442055686934;36547.81726091379;37016.07459813499;37087.4436883447;37090.814545927096;37119.814400928095;3285.901422957315;3399.6430374810534;3455.9654403455893;3677.669009789105;4001;28321;28567.142985710256;29052.564211536843;30592.2351941201;30710.846445767853;3849.30712471755;3859.826307816127;3868.9107881743826;3893.669038131734;3896.181801821621;281228;282391.99999999796;282471;283295;283833;50776.74611626918;52152;57440.13839792406;57783.42216577865;57855.42144578585;53675.73162134164;54083;54122.45877541253;54462.45537544653;54489.45510544844;14860.554183374516;15020.399184032516;15034;15045.924770376078;15050.698986020438;11642.76714465706;11680.649580512598;11683;11686.532538698357;11740.647780566425;18559.164837582342;19089.809101909083;19117.235310587697;19161.32935347284;19194.328198512983;7257.637118144072;7460.179380268166;7600.923990760132;7620.276073772982;7705.499142555716;925.5603588295505;939.2063706168277;941.1858742188095;961.9038096190325;964.5611246882652;24998.50002999966;27119;27120.1863944082;28549;29509.557356639667;1032;1036.7926414716935;1042.2912419554775;1138.9886101139048;1153.7865494883306;13762.862371376359;13765.036447448783;13972.231527265894;13991.440342386191;14017.579472615838;313246;316637;317245;322269;323317;49694;50506.74746626243;50518.24222636663;50551.24173137406;50563.49436505661;1311.8032295155804;1326.1512631915582;1333.1601091312605;1371.91082579634;1373.4849431463201;23842.523149537257;26875.328116797238;27009.86495067512;28248.15255542337;28648.85675571609;44429.55570444383;45650.54349456463;45710.314345285515;45745.31382029339;45747.542524575656;243503;246405;247964;248965;295297;25173.87413062923;30606.693933061277;30670.233244168183;31212;31247.53128703026;36917.815410922776;39592.40611390889;40126.79936600298;40550.18899621932;40670.79664601776;33413.83293083519;33789.83105084459;34006.82996585001;34195.658043419244;34515.309693806485;34811.47782783311;34813.303733925684;34900.47649285212;34959.30081398409;35269.64730352664;33364.499532507514;33678.83160584181;33689.326213475106;34004.1498962518;34116.829415852764;28619.570706439004;29028.419431610826;30512.237194070327;30633.23416914595;31049.379012419173;23404.765952340258;23425.531489370456;23471.41321466977;23765.762342376354;23798.643020355055 05:25:15 INFO - PROCESS | 23935 | |2;jquery.html;355.1299316674149;355.9341521818464;357.3050416939053;357.8353957179699;360.27404779369573;13454.865451345484;13772.104813187161;16067.919660401696;16648;17854.107294635247;13480.52818151365;14172.362243699032;14435.92782036091;14636.634084147901;14643.19462429566;5191.688498690071;5422.457754224566;5427.810026649068;5428.945710542903;5437.102878025121;3982.084120652252;4173.332266837307;5265.9473405266035;5384.273123128386;5700.173474846145;5656.123300888369;5799.623024503394;5896.321922978869;5896.410358964091;5906.88186236275;5704.258446401962;5746.224259724925;5750.223719797816;5750.367459579443;5754.654720716729;4912.852614421573;5064.088464076464;5074.543291103819;5121.103806833789;5121.615878809104;19373.225070997145;19379.4186174415;19499.902500487548;19692.785382674643;19701.21195152192;2122.0450797141193;2170.5441857210003;2195.121951219509;2202.6806113113507;2330.475642980333;12359.134860559765;12545.247285162919;12561;12606.810897836449;13119.803202951964;1826.1325870211542;1841.9815801841944;1856.675081860669;1904.438190733723;1915.5115445561432;51277.48722512765;51568.74215628898;53736;54062.45937540653;54207.728961354944;9226.677066302647;9434.245260379153;9598.280128990353;9608.615655373847;9645.08371704687;12259.632211033684;12410.014397696325;12434.937825310815;12457.314847683465;12506;7322.1579518355;7385.778426647209;7416.629168541552;7430.294122058396;7506.812329691801;7151.606661633556;7191.316824901626;7215.206327303992;7220.3140701633265;7235.927640723526;5672.262605861211;5765.308163020381;5767.22142510764;5770.047942089592;5813.94186058134;1629.5274370432678;1693.1280390598852;1702.753100800377;1770.3361239535175;1773.689604319239;906.8761497538958;921.5898924978454;947.2989987409405;1023.1201166996377;1068.3429690740138;15838.76241856373;15971;16348.918255408884;16454.177291135395;16697.833021669627;821.9313900456149;948.8813898262717;958.2142643032646;975.8097171051577;993.8509223616516;8196.5491897945;8615.91384086151;8814.779630509289;8848.292136629054;9022.774430639156;507628;507866;513485;528493;530225;42920.57079429228;44285;60749.34522262452;66577.66844663079;70972.64513677398;1087.5758454202867;1141.5148561861176;1141.6517962021662;1141.6860363399965;1158.6697791129657;14227.644308891946;14566.927165364104;14723.263836808332;15085.396584136512;15161.393544258548;20173;30237.63930623083;30933.0720078398;32127.196820079684;33563.66436335605;37611;37684.43473347957;38043.61956380401;38470;38523.22953540969;6483.610983341015;6718.160229971254;6901.827454313682;7052.471064670068;7281.453890958093;7654.74830800526;9696.22430205596;10296;10584.153267738717;10825.350478971286;7540.509866858637;9363.250939924927;9442.244620430212;9460.621575137193;9598.760030999281;9039.412438191497;9444.386114902509;9678.51607419612;9696.5151742414;9945.701628951143;7354.558726476428;7460.29127232912;9193.865474227303;9350.579223934803;9579.808403831745;6977.406920411619;7095.432365410858;7111.075560177143;7114.644267786486;7244.637768111679;7993.200679931961;9364.157225849705;9496.287778416508;9692.321537492307;9773.218142548436 05:25:15 INFO - PROCESS | 23935 | |3;mootools.html;1296;1334.3861823561165;1359.347513193667;1412.8869690424767;1681.537577166279;6691.765788197414;7242.420606351492;7352.632368381586;7382.483226174157;7396.667149978257;7094.396976257011;7235.6382180891005;7249.528780629269;7271;7304.086989126359;1962.4112766170163;2001.479615300018;2004.2984955265613;2215.7451893032307;2245.057076028064;2015.2644284836076;2237.0827960536185;2290.0725206291395;2295.735990361105;2298.4368829636765;2158.3740715192653;2180.6510958246686;2225.5771403433378;2228.8774117423536;2233.0286325448483;1934.1586409911733;1974.4767636576319;2163.6862654915026;2169.9566008679817;2178.7930146635963;2223.7665045170347;2273.8521996070367;2274.3177046885867;2274.6701728249477;2276.4195130241847;7070.151581810164;7118.252583478712;7119.608421536814;7132.821679458003;7169;4392.692511524195;4484.865454036384;4509.977450112761;4524.8416305429155;4529.818807247706;2743.533599288126;2977.434287485371;2981.1503721439285;2981.38881529286;2991.9551206731917;953.5470651440579;974.2060220919927;995.4574756757585;996.7956568903357;1027.5992362978445;10023.298369114165;10257.128144107759;10286.331388459801;10292.279540432173;10319.122874555671;4067.5525692173837;4152.505098164658;4178.39413285072;4208.621224089847;4211.178820130107;8846.46921184731;8867.423617464845;8889.599968001456;8894.555272236363;8898.288136949028;4582.175208462509;4595.058013107372;4600.6549508786975;4601.263797792337;4601.930971035437;4130.6282434580435;4145.336746120666;4160.105577300835;4169.457970463881;4172.3532852407925;3379.5437615921537;3411.164264755101;3421.1447138215444;3424.0583839444353;3459.498372735939;1101.4988180378068;1145.8395824584566;1147.1109889835277;1147.1568397228032;1154.7401834587151;1261.5332327039055;1261.7539579781856;1279.7952327627536;1282.8332316798942;1315.861834507363;6890.379865812101;6921.965390173017;6942.861142777117;7043.894341584881;7045.612491313027;1094.600470828142;1095.6165342130191;1101.1906248907046;1117.474786850179;1145.3185354713933;4603.792829322689;4689.882752931136;5186.870328241824;5444.8094316698725;5490;8644.00593931706;8699.565021748887;8809.42738721994;8826.205641492297;8826.514541700268;8589.48463092204;8907.821843563092;8917.598708058153;8947.731568052968;8958.552072396355;6906.171259448898;6913.170419549686;6934.133233345932;6999;7005.719771209197;6641.800745977628;6766.052752614736;6790.864182716219;6910.412614927586;6919.827004324932;7661.923380766121;7980.321672657889;8001.559914204775;8042.718504852302;8091.635876385459;8113.229243221885;8291.875621865798;8302.83394332122;8475.57622118904;8506.191911768372;6524.314946930694;6543.803685889431;6574.572652777554;6604.3725846044545;6610.570312929644;7027.543209691354;7155.606441645759;7200.531965422231;7223.566586004857;7242.529235599669;6653.334666533308;6773.62745049027;6824.7952561423235;6988.825279368057;7042.6126563039525;6642.368974947372;6823.38589526945;6868.656567171722;6942.2363540011565;7007.824804379931;6870.690818913061;6901.378875901193;6906.205786334636;6907.240203577703;6909.2745261746795;6305.180326557518;6310;6339.619622822645;6457;6487.3836985486305;6466.773662921775;6505.902411463927;6507.479401647952;6515.250746164252;6516.73933042673 05:25:15 INFO - PROCESS | 23935 | |4;prototype.html;329.4728434504793;333.64466842812385;336.7070648535773;341.4690156806167;342.3973806101263;5778.393268706788;5839.708014599269;5854.297484301879;5865.58940874139;5889.911651325234;5685.971570142154;5748.166515855199;5807.796727114552;5815.302163740346;5852.385499522553;4555.521670224628;4567.634589232854;4567.885802854922;4573.314002899559;4580.4045474088325;4337.9566204338025;4426.313921342197;4539;4542.386777785006;4558.589726924576;4361.890952726176;4473.351364052211;4485.439320084989;4495.865124046284;4509.075639493913;4469.217886869817;4478.484974227957;4485.932711009337;4490.124425736983;4491.528389519119;3943.132510847609;3949.664278536328;3961.742486738353;3962.4254483100076;3965.940510892339;5449.073657478239;5556.888862222773;5556.972215138939;5559.138333558305;5560.719175686365;5221.738913054332;5227.137522308814;5239.266502689627;5241.816536421207;5249.055170069386;6237.7816776413065;6245.937540624626;6250.531210159256;6251.718672659696;6257.311695713468;4134.855280065213;4171.124063946544;4228.133232687292;4273.594008569181;4363.869083927487;6385.5210859185745;6451.354864513558;6462.515311351667;6485.286618471964;6538.934610653881;4415.911681766347;4424.137293227854;4425.712328698624;4426.203283409017;4427.247367947457;4495.303227999665;4497.370368148462;4497.88755281114;4503.7522936238165;4504.234280172379;5001.474845141281;5157.716325602053;5161.974190129025;5163.225516172604;5188.455212202664;6197.25632924043;6245.094461303085;6245.313015568284;6248.250209974832;6256.280527739277;1575.5430925031612;1600.1919030889326;1600.343859017797;1600.3758534171682;1603.0461875184246;1035.699647102347;1060.3637817309552;1062.8193207154802;1063.0432610650475;1082.9729256768644;5909.970450147722;5920.31916329619;5921.585489015813;5924.31870334917;5929.822105336761;6335.9862422013175;6414.518911081687;6415.326390728999;6415.903761443582;6417.294097649255;5905.498032667186;5912.113183022581;5913;5915.645061296336;5916.26046744157;3388.237646529511;3479.460683594047;3484.041888480688;3487.232808782064;3492.4586689062726;6749.0888730020815;6760.121184246115;6770.69531871067;6773.593584384953;6775.525713200127;6633.038209459602;6634.800955971328;6636.5354425190735;6637.369449902348;6643.534952553371;6571.901421478682;6696.229933557605;6748.089007983859;6751.831204219738;6752.966235168891;6463.935360646334;6465.224173099258;6465.771503414337;6466.127072845294;6473.546851720334;6119.357467465852;6193.938060619336;6285.779997700059;6380;6389.105525226379;6670.366315200048;6704.765333213314;6705;6708.731650733916;6719.227288861648;4426.402435671143;4460.910781784281;4468.709533880287;4475.843345482893;4476.843310484118;6259.874802504016;6441.3880681335195;6447.516436267205;6461.19235095613;6461.903071454026;6258.280297765815;6278.0896769967185;6307.779227727007;6310.400511951358;6310.800947820083;6214.316425193135;6261.530385221217;6264.9976003839165;6276.215473065867;6294.937050629435;5983.820485385445;6157.599756015845;6188.845278868064;6196.47329976957;6197.070439433941;6332.430081292706;6353.396427339396;6354.26925903527;6369.840753981188;6374.776882809079;6211.378862113933;6218.782342617987;6225.62646241227;6225.968870155621;6228.972219583718 05:25:15 INFO - PROCESS | 23935 | |5;yui.html;757.821911850715;776.6194564663313;801.8757092650637;843;860.1183786618711;12965.286909219989;13161.144525605829;13173.934130329359;13178.406971686272;13180.340982950838;12948.611541653765;12957.80563291549;12960.80558791619;13002.154859934122;13008.739825203515;651.0299653516274;666.5900471210196;786.6499407763547;805.0500409516783;816.1145157504116;799.6281728996016;806.6974884418344;809.0695699945081;815.9224873636995;818.0061225610882;845.121074082953;850.9361797865154;851.459323329687;851.974440766778;852.1180578101666;672.4183581202274;685.2941470285614;835.1690068381998;844.4173520270994;852.2287329966329;626.5269721360389;626.8526896179394;627.4917316973266;627.8336240896166;628.205320269859;12951.02867284948;12978.740425191538;12992.740145197138;13010.869891301061;13020.739585208243;11590.942045289805;11803.350815705133;12027.338496382696;12121.93939030308;12258.938705306506;9985.550650220684;9988.400695958266;9995.650152244709;10007.59969601215;10077.294589378746;3510.052285882797;3583.229605634801;3619.330423871566;3625.7643253188457;3651.233241019389;42248.78875605633;43402.56597434017;43456.56543434557;43625.563744362466;43642.12715745699;9426.340156189;9639.228861691048;9659.618205586208;9864.556094975744;9919.603215871284;16531.586710332096;16602.418915337905;16608.086555239337;16636;16688.83311166897;7492.400607951351;7533.20901305366;7682.116556596063;7789.454738168274;7793.532388056735;4343.782810859444;4368.235558777201;4421.182081314967;4477.350784136282;4484.148011877733;2979.1360505453176;3013.698630136969;3020.2751339678757;3024.4102400031784;3025.8184508929535;550.7549140632381;558.5447859994092;559.1612581128308;573.7160105747681;581.2472847662287;475.555355742382;490.70312460960605;520.7344254430268;582.9329627092853;632.8924082905917;8795.604197811113;9044.81910361789;9068.274538036942;9378.390404623678;9424.323369794933;508.3493128795148;569.8489900176456;594.0169020271458;612.7487730030755;636.6562056489537;4098.7950602470355;4156.542780294165;4166;4277.486701595766;4338.457692788402;53558;53805.46194538005;53926;53954.460455395725;53968;48380.032399352516;49556.50443495591;49811.50188498069;49930.50069499259;50414.747926260134;43742.78128609336;43945.560544394786;44033;44509.33236001527;45242.32136517955;7977.760667179994;8518.14818518168;8615.741527754177;8706.347023973101;8877.911220887709;34060.14849628779;34171.82914085413;34221.31557368889;35141;35625.46561801627;41064.58935410608;41328.38007429951;41512.377314339705;43402.56597433985;43672.34491482567;10645.574177032831;10648.148148148284;10666;10847.837282440612;11187.832182517106;11485.82771258415;11835.28988260707;11846.229995050295;11875.406229688653;11978.880211198126;10824.945875270574;11192.440377981231;11316.660500185008;11424;11748.647540573797;10634.787304253718;10672.73318167021;10861.348319100563;11170.553177873118;11229.71925701864;10893;10942.781144377228;11040.834387484034;11222.214444988773;11277.661670149908;9019.368644195105;9163.120284640803;9186.448813071238;9275.86086208694;9320.02139775341;8553.914460855562;9069.01204337625;9291.581878815605;9379.577918993526;9573.138417542455 05:25:15 INFO - PROCESS | 23935 | __end_tp_report 05:25:15 INFO - PROCESS | 23935 | __start_cc_report 05:25:15 INFO - PROCESS | 23935 | _x_x_mozilla_cycle_collect,1121 05:25:15 INFO - PROCESS | 23935 | __end_cc_report 05:25:15 INFO - PROCESS | 23935 | __startTimestamp1452259515881__endTimestamp 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | ------- Summary: start ------- 05:25:15 INFO - PROCESS | 23935 | Number of tests: 6 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#0] dojo.html Cycles:165 Average:5107.61 Median:5315.04 stddev:547.14 (10.3%) stddev-sans-first:524.94 05:25:15 INFO - PROCESS | 23935 | Values: 3069.7 3117.7 3152.2 3153.6 3157.0 4986.5 5069.5 5074.6 5075.9 5085.3 5086.2 5090.4 5106.2 5205.1 5226.1 4673.7 4684.0 4694.4 4712.5 4816.0 4364.6 4718.1 4764.4 4765.3 4822.5 4610.0 4631.9 4680.1 4717.2 4730.3 4705.0 4710.8 4717.0 4736.4 4765.9 4674.8 4679.9 4681.1 4702.9 4715.2 4878.8 4973.9 4975.1 4980.3 5027.3 5389.6 5442.3 5464.5 5464.5 5468.5 5444.7 5477.8 5485.0 5514.7 5534.3 5108.7 5112.0 5120.2 5121.2 5165.1 5438.7 5509.3 5551.2 5568.7 5628.7 5297.9 5438.9 5441.8 5442.7 5487.0 5460.4 5496.3 5499.3 5506.4 5511.6 5424.1 5478.9 5485.3 5516.1 5550.5 5431.0 5511.8 5512.3 5533.5 5558.7 4285.8 4292.1 4292.7 4299.2 4332.2 3803.5 3839.2 3857.4 3873.1 3888.6 5077.1 5114.1 5124.7 5151.4 5189.3 5512.1 5512.6 5513.2 5516.8 5534.8 5045.4 5136.9 5139.6 5139.9 5150.4 4657.5 4677.7 4711.9 4729.2 4744.0 5657.1 5663.0 5669.2 5671.2 5671.6 5422.3 5459.3 5473.8 5477.4 5529.2 5572.4 5666.8 5691.9 5693.5 5778.2 5455.2 5468.5 5470.3 5474.4 5479.4 5487.1 5511.5 5525.6 5539.1 5604.3 5316.5 5411.6 5420.9 5420.9 5436.9 5340.3 5405.0 5407.8 5413.7 5456.4 5231.3 5306.2 5306.7 5317.4 5351.9 5322.6 5362.5 5377.3 5394.3 5430.8 5277.7 5286.4 5314.4 5315.0 5316.8 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#1] ext.html Cycles:180 Average:44624.89 Median:18701.90 stddev:75053.78 (401.3%) stddev-sans-first:75191.29 05:25:15 INFO - PROCESS | 23935 | Values: 525.1 525.6 534.7 556.7 557.0 27981.0 28530.0 36087.5 36736.3 36824.4 38690.4 39319.4 39359.0 39367.6 39423.2 7402.9 8361.3 8549.5 8562.4 8563.7 7500.0 7695.1 9855.8 10065.2 10119.1 9798.6 9969.7 9993.5 9993.8 10001.7 7502.0 7714.6 9808.9 9907.7 9908.6 9821.7 10124.7 10134.9 10142.8 10144.4 36547.8 37016.1 37087.4 37090.8 37119.8 3285.9 3399.6 3456.0 3677.7 4001.0 28321.0 28567.1 29052.6 30592.2 30710.8 3849.3 3859.8 3868.9 3893.7 3896.2 281228.0 282392.0 282471.0 283295.0 283833.0 50776.7 52152.0 57440.1 57783.4 57855.4 53675.7 54083.0 54122.5 54462.5 54489.5 14860.6 15020.4 15034.0 15045.9 15050.7 11642.8 11680.6 11683.0 11686.5 11740.6 18559.2 19089.8 19117.2 19161.3 19194.3 7257.6 7460.2 7600.9 7620.3 7705.5 925.6 939.2 941.2 961.9 964.6 24998.5 27119.0 27120.2 28549.0 29509.6 1032.0 1036.8 1042.3 1139.0 1153.8 13762.9 13765.0 13972.2 13991.4 14017.6 313246.0 316637.0 317245.0 322269.0 323317.0 49694.0 50506.7 50518.2 50551.2 50563.5 1311.8 1326.2 1333.2 1371.9 1373.5 23842.5 26875.3 27009.9 28248.2 28648.9 44429.6 45650.5 45710.3 45745.3 45747.5 243503.0 246405.0 247964.0 248965.0 295297.0 25173.9 30606.7 30670.2 31212.0 31247.5 36917.8 39592.4 40126.8 40550.2 40670.8 33413.8 33789.8 34006.8 34195.7 34515.3 34811.5 34813.3 34900.5 34959.3 35269.6 33364.5 33678.8 33689.3 34004.1 34116.8 28619.6 29028.4 30512.2 30633.2 31049.4 23404.8 23425.5 23471.4 23765.8 23798.6 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#2] jquery.html Cycles:180 Average:25985.99 Median:266879.47 stddev:84363.56 (31.6%) stddev-sans-first:84578.27 05:25:15 INFO - PROCESS | 23935 | Values: 355.1 355.9 357.3 357.8 360.3 13454.9 13772.1 16067.9 16648.0 17854.1 13480.5 14172.4 14435.9 14636.6 14643.2 5191.7 5422.5 5427.8 5428.9 5437.1 3982.1 4173.3 5265.9 5384.3 5700.2 5656.1 5799.6 5896.3 5896.4 5906.9 5704.3 5746.2 5750.2 5750.4 5754.7 4912.9 5064.1 5074.5 5121.1 5121.6 19373.2 19379.4 19499.9 19692.8 19701.2 2122.0 2170.5 2195.1 2202.7 2330.5 12359.1 12545.2 12561.0 12606.8 13119.8 1826.1 1842.0 1856.7 1904.4 1915.5 51277.5 51568.7 53736.0 54062.5 54207.7 9226.7 9434.2 9598.3 9608.6 9645.1 12259.6 12410.0 12434.9 12457.3 12506.0 7322.2 7385.8 7416.6 7430.3 7506.8 7151.6 7191.3 7215.2 7220.3 7235.9 5672.3 5765.3 5767.2 5770.0 5813.9 1629.5 1693.1 1702.8 1770.3 1773.7 906.9 921.6 947.3 1023.1 1068.3 15838.8 15971.0 16348.9 16454.2 16697.8 821.9 948.9 958.2 975.8 993.9 8196.5 8615.9 8814.8 8848.3 9022.8 507628.0 507866.0 513485.0 528493.0 530225.0 42920.6 44285.0 60749.3 66577.7 70972.6 1087.6 1141.5 1141.7 1141.7 1158.7 14227.6 14566.9 14723.3 15085.4 15161.4 20173.0 30237.6 30933.1 32127.2 33563.7 37611.0 37684.4 38043.6 38470.0 38523.2 6483.6 6718.2 6901.8 7052.5 7281.5 7654.7 9696.2 10296.0 10584.2 10825.4 7540.5 9363.3 9442.2 9460.6 9598.8 9039.4 9444.4 9678.5 9696.5 9945.7 7354.6 7460.3 9193.9 9350.6 9579.8 6977.4 7095.4 7111.1 7114.6 7244.6 7993.2 9364.2 9496.3 9692.3 9773.2 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#3] mootools.html Cycles:180 Average:5227.99 Median:6477.08 stddev:2687.23 (41.5%) stddev-sans-first:2678.51 05:25:15 INFO - PROCESS | 23935 | Values: 1296.0 1334.4 1359.3 1412.9 1681.5 6691.8 7242.4 7352.6 7382.5 7396.7 7094.4 7235.6 7249.5 7271.0 7304.1 1962.4 2001.5 2004.3 2215.7 2245.1 2015.3 2237.1 2290.1 2295.7 2298.4 2158.4 2180.7 2225.6 2228.9 2233.0 1934.2 1974.5 2163.7 2170.0 2178.8 2223.8 2273.9 2274.3 2274.7 2276.4 7070.2 7118.3 7119.6 7132.8 7169.0 4392.7 4484.9 4510.0 4524.8 4529.8 2743.5 2977.4 2981.2 2981.4 2992.0 953.5 974.2 995.5 996.8 1027.6 10023.3 10257.1 10286.3 10292.3 10319.1 4067.6 4152.5 4178.4 4208.6 4211.2 8846.5 8867.4 8889.6 8894.6 8898.3 4582.2 4595.1 4600.7 4601.3 4601.9 4130.6 4145.3 4160.1 4169.5 4172.4 3379.5 3411.2 3421.1 3424.1 3459.5 1101.5 1145.8 1147.1 1147.2 1154.7 1261.5 1261.8 1279.8 1282.8 1315.9 6890.4 6922.0 6942.9 7043.9 7045.6 1094.6 1095.6 1101.2 1117.5 1145.3 4603.8 4689.9 5186.9 5444.8 5490.0 8644.0 8699.6 8809.4 8826.2 8826.5 8589.5 8907.8 8917.6 8947.7 8958.6 6906.2 6913.2 6934.1 6999.0 7005.7 6641.8 6766.1 6790.9 6910.4 6919.8 7661.9 7980.3 8001.6 8042.7 8091.6 8113.2 8291.9 8302.8 8475.6 8506.2 6524.3 6543.8 6574.6 6604.4 6610.6 7027.5 7155.6 7200.5 7223.6 7242.5 6653.3 6773.6 6824.8 6988.8 7042.6 6642.4 6823.4 6868.7 6942.2 7007.8 6870.7 6901.4 6906.2 6907.2 6909.3 6305.2 6310.0 6339.6 6457.0 6487.4 6466.8 6505.9 6507.5 6515.3 6516.7 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#4] prototype.html Cycles:180 Average:5222.13 Median:5907.73 stddev:1575.80 (26.7%) stddev-sans-first:1536.83 05:25:15 INFO - PROCESS | 23935 | Values: 329.5 333.6 336.7 341.5 342.4 5778.4 5839.7 5854.3 5865.6 5889.9 5686.0 5748.2 5807.8 5815.3 5852.4 4555.5 4567.6 4567.9 4573.3 4580.4 4338.0 4426.3 4539.0 4542.4 4558.6 4361.9 4473.4 4485.4 4495.9 4509.1 4469.2 4478.5 4485.9 4490.1 4491.5 3943.1 3949.7 3961.7 3962.4 3965.9 5449.1 5556.9 5557.0 5559.1 5560.7 5221.7 5227.1 5239.3 5241.8 5249.1 6237.8 6245.9 6250.5 6251.7 6257.3 4134.9 4171.1 4228.1 4273.6 4363.9 6385.5 6451.4 6462.5 6485.3 6538.9 4415.9 4424.1 4425.7 4426.2 4427.2 4495.3 4497.4 4497.9 4503.8 4504.2 5001.5 5157.7 5162.0 5163.2 5188.5 6197.3 6245.1 6245.3 6248.3 6256.3 1575.5 1600.2 1600.3 1600.4 1603.0 1035.7 1060.4 1062.8 1063.0 1083.0 5910.0 5920.3 5921.6 5924.3 5929.8 6336.0 6414.5 6415.3 6415.9 6417.3 5905.5 5912.1 5913.0 5915.6 5916.3 3388.2 3479.5 3484.0 3487.2 3492.5 6749.1 6760.1 6770.7 6773.6 6775.5 6633.0 6634.8 6636.5 6637.4 6643.5 6571.9 6696.2 6748.1 6751.8 6753.0 6463.9 6465.2 6465.8 6466.1 6473.5 6119.4 6193.9 6285.8 6380.0 6389.1 6670.4 6704.8 6705.0 6708.7 6719.2 4426.4 4460.9 4468.7 4475.8 4476.8 6259.9 6441.4 6447.5 6461.2 6461.9 6258.3 6278.1 6307.8 6310.4 6310.8 6214.3 6261.5 6265.0 6276.2 6294.9 5983.8 6157.6 6188.8 6196.5 6197.1 6332.4 6353.4 6354.3 6369.8 6374.8 6211.4 6218.8 6225.6 6226.0 6229.0 05:25:15 INFO - PROCESS | 23935 | 05:25:15 INFO - PROCESS | 23935 | [#5] yui.html Cycles:180 Average:13233.35 Median:24863.23 stddev:15015.32 (60.4%) stddev-sans-first:15028.21 05:25:15 INFO - PROCESS | 23935 | Values: 757.8 776.6 801.9 843.0 860.1 12965.3 13161.1 13173.9 13178.4 13180.3 12948.6 12957.8 12960.8 13002.2 13008.7 651.0 666.6 786.6 805.1 816.1 799.6 806.7 809.1 815.9 818.0 845.1 850.9 851.5 852.0 852.1 672.4 685.3 835.2 844.4 852.2 626.5 626.9 627.5 627.8 628.2 12951.0 12978.7 12992.7 13010.9 13020.7 11590.9 11803.4 12027.3 12121.9 12258.9 9985.6 9988.4 9995.7 10007.6 10077.3 3510.1 3583.2 3619.3 3625.8 3651.2 42248.8 43402.6 43456.6 43625.6 43642.1 9426.3 9639.2 9659.6 9864.6 9919.6 16531.6 16602.4 16608.1 16636.0 16688.8 7492.4 7533.2 7682.1 7789.5 7793.5 4343.8 4368.2 4421.2 4477.4 4484.1 2979.1 3013.7 3020.3 3024.4 3025.8 550.8 558.5 559.2 573.7 581.2 475.6 490.7 520.7 582.9 632.9 8795.6 9044.8 9068.3 9378.4 9424.3 508.3 569.8 594.0 612.7 636.7 4098.8 4156.5 4166.0 4277.5 4338.5 53558.0 53805.5 53926.0 53954.5 53968.0 48380.0 49556.5 49811.5 49930.5 50414.7 43742.8 43945.6 44033.0 44509.3 45242.3 7977.8 8518.1 8615.7 8706.3 8877.9 34060.1 34171.8 34221.3 35141.0 35625.5 41064.6 41328.4 41512.4 43402.6 43672.3 10645.6 10648.1 10666.0 10847.8 11187.8 11485.8 11835.3 11846.2 11875.4 11978.9 10824.9 11192.4 11316.7 11424.0 11748.6 10634.8 10672.7 10861.3 11170.6 11229.7 10893.0 10942.8 11040.8 11222.2 11277.7 9019.4 9163.1 9186.4 9275.9 9320.0 8553.9 9069.0 9291.6 9379.6 9573.1 05:25:15 INFO - PROCESS | 23935 | -------- Summary: end -------- 05:25:15 INFO - PROCESS | 23935 | 05:25:16 INFO - TEST-INFO | 23935: exit 0 05:25:16 INFO - TEST-OK | dromaeo_css | took 1113266ms 05:25:16 INFO - TEST-START | kraken 05:25:16 INFO - operating with platform_type : linux_ 05:25:16 INFO - Initialising browser for kraken test... 05:25:16 INFO - TEST-INFO | started process 24127 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnC25A0/profile http://localhost:48429/getInfo.html) 05:25:20 INFO - PROCESS | 24127 | __metrics Screen width/height:1600/1200 05:25:20 INFO - PROCESS | 24127 | colorDepth:24 05:25:20 INFO - PROCESS | 24127 | Browser inner width/height: 1024/697 05:25:20 INFO - PROCESS | 24127 | __metrics 05:25:20 INFO - PROCESS | 24127 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:25:20 INFO - PROCESS | 24127 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:21 INFO - TEST-INFO | 24127: exit 0 05:25:21 INFO - Browser initialized. 05:25:21 INFO - Running cycle 1/1 for kraken test... 05:25:21 INFO - TEST-INFO | started process 24210 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnC25A0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:33 INFO - PROCESS | 24210 | RSS: Main: 149954560 05:25:33 INFO - PROCESS | 24210 | 05:25:36 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48429/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:36 INFO - PROCESS | 24210 | RSS: Main: 188276736 05:25:36 INFO - PROCESS | 24210 | 05:25:39 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48429/tests/kraken/driver.html?testName=audio-dft) 05:25:40 INFO - PROCESS | 24210 | RSS: Main: 177889280 05:25:40 INFO - PROCESS | 24210 | 05:25:42 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48429/tests/kraken/driver.html?testName=audio-fft) 05:25:43 INFO - PROCESS | 24210 | RSS: Main: 161427456 05:25:43 INFO - PROCESS | 24210 | 05:25:45 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48429/tests/kraken/driver.html?testName=audio-oscillator) 05:25:46 INFO - PROCESS | 24210 | RSS: Main: 163512320 05:25:46 INFO - PROCESS | 24210 | 05:25:48 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48429/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:48 INFO - PROCESS | 24210 | RSS: Main: 166596608 05:25:48 INFO - PROCESS | 24210 | 05:25:51 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48429/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:52 INFO - PROCESS | 24210 | RSS: Main: 165040128 05:25:52 INFO - PROCESS | 24210 | 05:25:55 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48429/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:56 INFO - PROCESS | 24210 | RSS: Main: 166653952 05:25:56 INFO - PROCESS | 24210 | 05:25:59 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48429/tests/kraken/driver.html?testName=json-parse-financial) 05:25:59 INFO - PROCESS | 24210 | RSS: Main: 166989824 05:25:59 INFO - PROCESS | 24210 | 05:26:01 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48429/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:01 INFO - PROCESS | 24210 | RSS: Main: 177119232 05:26:01 INFO - PROCESS | 24210 | 05:26:03 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:04 INFO - PROCESS | 24210 | RSS: Main: 175980544 05:26:04 INFO - PROCESS | 24210 | 05:26:06 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:06 INFO - PROCESS | 24210 | RSS: Main: 180604928 05:26:06 INFO - PROCESS | 24210 | 05:26:09 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:09 INFO - PROCESS | 24210 | RSS: Main: 188354560 05:26:09 INFO - PROCESS | 24210 | 05:26:12 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:12 INFO - PROCESS | 24210 | RSS: Main: 188186624 05:26:12 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | Cycle 1(1): loaded http://localhost:48429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48429/tests/kraken/driver.html?testName=ai-astar) 05:26:14 INFO - PROCESS | 24210 | RSS: Main: 247283712 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | __start_tp_report 05:26:14 INFO - PROCESS | 24210 | _x_x_mozilla_page_load 05:26:14 INFO - PROCESS | 24210 | _x_x_mozilla_page_load_details 05:26:14 INFO - PROCESS | 24210 | |i|pagename|runs| 05:26:14 INFO - PROCESS | 24210 | |0;ai-astar;110;110;104;109;110;109;99;111;110;110 05:26:14 INFO - PROCESS | 24210 | |1;audio-beat-detection;160;126;149;175;132;153;148;134;165;134 05:26:14 INFO - PROCESS | 24210 | |2;audio-dft;161;161;162;160;162;161;161;162;162;162 05:26:14 INFO - PROCESS | 24210 | |3;audio-fft;71;72;80;97;70;70;71;99;95;70 05:26:14 INFO - PROCESS | 24210 | |4;audio-oscillator;98;97;142;96;97;97;143;97;97;136 05:26:14 INFO - PROCESS | 24210 | |5;imaging-gaussian-blur;110;110;110;109;109;109;109;110;110;110 05:26:14 INFO - PROCESS | 24210 | |6;imaging-darkroom;146;145;146;147;145;146;146;145;147;145 05:26:14 INFO - PROCESS | 24210 | |7;imaging-desaturate;95;96;95;95;96;95;95;96;96;95 05:26:14 INFO - PROCESS | 24210 | |8;json-parse-financial;61;58;62;59;59;58;59;66;57;56 05:26:14 INFO - PROCESS | 24210 | |9;json-stringify-tinderbox;67;67;68;69;68;68;67;67;67;68 05:26:14 INFO - PROCESS | 24210 | |10;stanford-crypto-aes;84;85;86;86;86;85;88;87;87;86 05:26:14 INFO - PROCESS | 24210 | |11;stanford-crypto-ccm;116;123;120;116;124;123;123;116;117;117 05:26:14 INFO - PROCESS | 24210 | |12;stanford-crypto-pbkdf2;140;146;156;150;154;141;164;150;150;142 05:26:14 INFO - PROCESS | 24210 | |13;stanford-crypto-sha256-iterative;69;67;68;69;68;71;69;69;68;68 05:26:14 INFO - PROCESS | 24210 | __end_tp_report 05:26:14 INFO - PROCESS | 24210 | __start_cc_report 05:26:14 INFO - PROCESS | 24210 | _x_x_mozilla_cycle_collect,995 05:26:14 INFO - PROCESS | 24210 | __end_cc_report 05:26:14 INFO - PROCESS | 24210 | __startTimestamp1452259574938__endTimestamp 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | ------- Summary: start ------- 05:26:14 INFO - PROCESS | 24210 | Number of tests: 14 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#0] ai-astar Cycles:10 Average:108.20 Median:110.00 stddev:3.77 (3.4%) stddev-sans-first:3.94 05:26:14 INFO - PROCESS | 24210 | Values: 110.0 110.0 104.0 109.0 110.0 109.0 99.0 111.0 110.0 110.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:151.00 stddev:16.05 (10.6%) stddev-sans-first:16.38 05:26:14 INFO - PROCESS | 24210 | Values: 160.0 126.0 149.0 175.0 132.0 153.0 148.0 134.0 165.0 134.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 05:26:14 INFO - PROCESS | 24210 | Values: 161.0 161.0 162.0 160.0 162.0 161.0 161.0 162.0 162.0 162.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#3] audio-fft Cycles:10 Average:79.50 Median:76.00 stddev:12.47 (16.4%) stddev-sans-first:12.84 05:26:14 INFO - PROCESS | 24210 | Values: 71.0 72.0 80.0 97.0 70.0 70.0 71.0 99.0 95.0 70.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#4] audio-oscillator Cycles:10 Average:110.00 Median:97.00 stddev:21.01 (21.7%) stddev-sans-first:21.83 05:26:14 INFO - PROCESS | 24210 | Values: 98.0 97.0 142.0 96.0 97.0 97.0 143.0 97.0 97.0 136.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:26:14 INFO - PROCESS | 24210 | Values: 110.0 110.0 110.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 05:26:14 INFO - PROCESS | 24210 | Values: 146.0 145.0 146.0 147.0 145.0 146.0 146.0 145.0 147.0 145.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:26:14 INFO - PROCESS | 24210 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.00 stddev:2.88 (4.9%) stddev-sans-first:3.00 05:26:14 INFO - PROCESS | 24210 | Values: 61.0 58.0 62.0 59.0 59.0 58.0 59.0 66.0 57.0 56.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 05:26:14 INFO - PROCESS | 24210 | Values: 67.0 67.0 68.0 69.0 68.0 68.0 67.0 67.0 67.0 68.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:1.15 (1.3%) stddev-sans-first:0.97 05:26:14 INFO - PROCESS | 24210 | Values: 84.0 85.0 86.0 86.0 86.0 85.0 88.0 87.0 87.0 86.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:121.50 stddev:3.44 (2.8%) stddev-sans-first:3.41 05:26:14 INFO - PROCESS | 24210 | Values: 116.0 123.0 120.0 116.0 124.0 123.0 123.0 116.0 117.0 117.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.30 Median:150.00 stddev:7.48 (5.0%) stddev-sans-first:7.14 05:26:14 INFO - PROCESS | 24210 | Values: 140.0 146.0 156.0 150.0 154.0 141.0 164.0 150.0 150.0 142.0 05:26:14 INFO - PROCESS | 24210 | 05:26:14 INFO - PROCESS | 24210 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 05:26:14 INFO - PROCESS | 24210 | Values: 69.0 67.0 68.0 69.0 68.0 71.0 69.0 69.0 68.0 68.0 05:26:14 INFO - PROCESS | 24210 | -------- Summary: end -------- 05:26:14 INFO - PROCESS | 24210 | 05:26:15 INFO - TEST-INFO | 24210: exit 0 05:26:15 INFO - TEST-OK | kraken | took 59213ms 05:26:15 INFO - TEST-START | v8_7 05:26:15 INFO - operating with platform_type : linux_ 05:26:15 INFO - Initialising browser for v8_7 test... 05:26:15 INFO - TEST-INFO | started process 24460 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVZ7_Li/profile http://localhost:48429/getInfo.html) 05:26:20 INFO - PROCESS | 24460 | __metrics Screen width/height:1600/1200 05:26:20 INFO - PROCESS | 24460 | colorDepth:24 05:26:20 INFO - PROCESS | 24460 | Browser inner width/height: 1024/697 05:26:20 INFO - PROCESS | 24460 | __metrics 05:26:20 INFO - PROCESS | 24460 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:26:20 INFO - PROCESS | 24460 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:26:21 INFO - TEST-INFO | 24460: exit 0 05:26:21 INFO - Browser initialized. 05:26:21 INFO - Running cycle 1/1 for v8_7 test... 05:26:21 INFO - TEST-INFO | started process 24541 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVZ7_Li/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:33 INFO - PROCESS | 24541 | RSS: Main: 150519808 05:26:33 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | Cycle 1(1): loaded http://localhost:48429/tests/v8_7/run.html (next: http://localhost:48429/tests/v8_7/run.html) 05:26:55 INFO - PROCESS | 24541 | RSS: Main: 565948416 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | __start_tp_report 05:26:55 INFO - PROCESS | 24541 | _x_x_mozilla_page_load 05:26:55 INFO - PROCESS | 24541 | _x_x_mozilla_page_load_details 05:26:55 INFO - PROCESS | 24541 | |i|pagename|runs| 05:26:55 INFO - PROCESS | 24541 | |0;Richards;166.91704223001167 05:26:55 INFO - PROCESS | 24541 | |1;DeltaBlue;191.46084625694044 05:26:55 INFO - PROCESS | 24541 | |2;Encrypt;281.37310073157005 05:26:55 INFO - PROCESS | 24541 | |3;Decrypt;5251.308900523561 05:26:55 INFO - PROCESS | 24541 | |4;RayTrace;1992.03187250996 05:26:55 INFO - PROCESS | 24541 | |5;Earley;777.000777000777 05:26:55 INFO - PROCESS | 24541 | |6;Boyer;12207.317073170732 05:26:55 INFO - PROCESS | 24541 | |7;RegExp;41000 05:26:55 INFO - PROCESS | 24541 | |8;Splay;564.3340857787811 05:26:55 INFO - PROCESS | 24541 | |9;NavierStokes;4826.923076923077 05:26:55 INFO - PROCESS | 24541 | __end_tp_report 05:26:55 INFO - PROCESS | 24541 | __startTimestamp1452259615921__endTimestamp 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | ------- Summary: start ------- 05:26:55 INFO - PROCESS | 24541 | Number of tests: 10 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#0] Richards Cycles:1 Average:166.92 Median:166.92 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 166.9 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#1] DeltaBlue Cycles:1 Average:191.46 Median:191.46 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 191.5 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#2] Encrypt Cycles:1 Average:281.37 Median:281.37 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 281.4 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#3] Decrypt Cycles:1 Average:5251.31 Median:5251.31 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 5251.3 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#4] RayTrace Cycles:1 Average:1992.03 Median:1992.03 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 1992.0 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#5] Earley Cycles:1 Average:777.00 Median:777.00 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 777.0 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 12207.3 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#7] RegExp Cycles:1 Average:41000.00 Median:41000.00 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 41000.0 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#8] Splay Cycles:1 Average:564.33 Median:564.33 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 564.3 05:26:55 INFO - PROCESS | 24541 | 05:26:55 INFO - PROCESS | 24541 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 05:26:55 INFO - PROCESS | 24541 | Values: 4826.9 05:26:55 INFO - PROCESS | 24541 | -------- Summary: end -------- 05:26:55 INFO - PROCESS | 24541 | 05:26:56 INFO - TEST-INFO | 24541: exit 0 05:26:56 INFO - TEST-OK | v8_7 | took 41019ms 05:26:56 INFO - SUITE-END | took 1213s 05:26:57 INFO - Completed test suite (00:20:13) 05:26:57 INFO - Working with test: dromaeo_css 05:26:57 INFO - Generating results file: dromaeo_css 05:26:57 INFO - Working with test: kraken 05:26:57 INFO - Generating results file: kraken 05:26:57 INFO - javascript benchmark 05:26:57 INFO - Working with test: v8_7 05:26:57 INFO - Generating results file: v8_7 05:26:57 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:26:57 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 05:26:57 INFO - process_Request line: dromaeo_css 6696.81 graph.html#tests=[[72,64,35]] 05:26:57 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:26:57 INFO - process_Request line: kraken 1508.00 graph.html#tests=[[232,64,35]] 05:26:57 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:26:57 INFO - process_Request line: v8_7 19060.98 graph.html#tests=[[230,64,35]] 05:26:57 INFO - RETURN: dromaeo_css: 6696.81 05:26:57 INFO - RETURN: kraken: 1508.00 05:26:57 INFO - RETURN: v8_7: 19060.98 05:26:57 INFO - javascript benchmark 05:26:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3069.677683843196, 3117.703818137279, 3152.1646763607646, 3153.59003329567, 3156.968430315696, 4986.501349865011, 5069.518395752402, 5074.568661663763, 5075.898482030358, 5085.288059671644, 5086.237064440326, 5090.440051594331, 5106.234064890268, 5205.089109405848, 5226.08543504887, 4673.719576825393, 4683.976580117094, 4694.366260554815, 4712.458067322251, 4816.012717392926, 4364.585364390377, 4718.103560323545, 4764.428268607772, 4765.261384485393, 4822.469528351879, 4609.953900460986, 4631.930521042187, 4680.087382960324, 4717.221658426355, 4730.290456431529, 4704.95295047052, 4710.764461776932, 4717.033008233304, 4736.384270044872, 4765.928511072337, 4674.766261686902, 4679.9298010529865, 4681.0871879983415, 4702.882427939329, 4715.198416269208, 4878.780454879504, 4973.900521989576, 4975.104481193383, 4980.302757613937, 5027.346444962168, 5389.649672771257, 5442.292501974757, 5464.480874316973, 5464.508194262496, 5468.48049435303, 5444.673319600836, 5477.835664930059, 5485.040117979338, 5514.724263786795, 5534.252875861787, 5108.7445627718835, 5112.0, 5120.15517439622, 5121.206213036985, 5165.070287348313, 5438.7280635968045, 5509.256250406224, 5551.1673249012165, 5568.665880047129, 5628.746706398222, 5297.947020529746, 5438.945610543844, 5441.8095366662765, 5442.673439593637, 5487.0, 5460.399356070829, 5496.258005169331, 5499.340079190442, 5506.421825708323, 5511.559075273968, 5424.1321388577635, 5478.863028424321, 5485.341758988867, 5516.117421212587, 5550.47270509301, 5430.972845135749, 5511.779528818883, 5512.283403157644, 5533.529649979716, 5558.666480011212, 4285.8285668573535, 4292.055747735493, 4292.678049146327, 4299.1831552005015, 4332.220200363901, 3803.4675145479655, 3839.212961342918, 3857.3828187490426, 3873.1479074603544, 3888.572257051722, 5077.111505486525, 5114.079465696136, 5124.718140502308, 5151.381834179922, 5189.325387699575, 5512.062949298629, 5512.586556008316, 5513.2005859150195, 5516.7517461714315, 5534.7786088556, 5045.445001049881, 5136.922946155811, 5139.614528910347, 5139.871503212375, 5150.381954165449, 4657.46439159501, 4677.672562920631, 4711.929321060187, 4729.196036673774, 4743.976280118577, 5657.066584013592, 5663.0, 5669.177969194444, 5671.17767923657, 5671.631343962629, 5422.29510163692, 5459.2902922619805, 5473.8357849264585, 5477.397486276427, 5529.170624406372, 5572.442755724557, 5666.801661941812, 5691.857703557444, 5693.4875861172695, 5778.191053252632, 5455.236266922713, 5468.5351745100525, 5470.316210473691, 5474.370447398441, 5479.397266300624, 5487.067198576331, 5511.5039646432015, 5525.585581081516, 5539.14143307788, 5604.299462567179, 5316.548093411949, 5411.594130440154, 5420.891582168256, 5420.891582168413, 5436.891262174655, 5340.332458442695, 5405.0270951227585, 5407.756650950795, 5413.675179489243, 5456.399796022513, 5231.319928409434, 5306.204069389622, 5306.708131052829, 5317.388500322512, 5351.902859913786, 5322.574194064543, 5362.51737343641, 5377.27406800077, 5394.32570928634, 5430.755615997368, 5277.736113194402, 5286.36563612369, 5314.415414304346, 5315.04329220744, 5316.760745766373], "unit": "score", "name": "dojo.html", "value": 5073.605306896062}, {"lowerIsBetter": false, "replicates": [525.0863497514325, 525.5900397689801, 534.6551474299077, 556.6743455078779, 556.983290501285, 27981.0, 28530.0, 36087.458688119696, 36736.26527469449, 36824.447633285454, 38690.419643705296, 39319.41020884682, 39359.0, 39367.60632393675, 39423.21153576927, 7402.925970740277, 8361.33109351252, 8549.529775862326, 8562.400631955765, 8563.715442683584, 7499.962500187491, 7695.115061767884, 9855.753606159868, 10065.245106617032, 10119.139873110795, 9798.559064842064, 9969.700908972705, 9993.500324983757, 9993.800123997516, 10001.699949001542, 7502.0, 7714.575698336589, 9808.90191098087, 9907.653232136912, 9908.603655853793, 9821.705348839547, 10124.69625911224, 10134.949325253327, 10142.797144057078, 10144.442055686934, 36547.81726091379, 37016.07459813499, 37087.4436883447, 37090.814545927096, 37119.814400928095, 3285.901422957315, 3399.6430374810534, 3455.9654403455893, 3677.669009789105, 4001.0, 28321.0, 28567.142985710256, 29052.564211536843, 30592.2351941201, 30710.846445767853, 3849.30712471755, 3859.826307816127, 3868.9107881743826, 3893.669038131734, 3896.181801821621, 281228.0, 282391.99999999796, 282471.0, 283295.0, 283833.0, 50776.74611626918, 52152.0, 57440.13839792406, 57783.42216577865, 57855.42144578585, 53675.73162134164, 54083.0, 54122.45877541253, 54462.45537544653, 54489.45510544844, 14860.554183374516, 15020.399184032516, 15034.0, 15045.924770376078, 15050.698986020438, 11642.76714465706, 11680.649580512598, 11683.0, 11686.532538698357, 11740.647780566425, 18559.164837582342, 19089.809101909083, 19117.235310587697, 19161.32935347284, 19194.328198512983, 7257.637118144072, 7460.179380268166, 7600.923990760132, 7620.276073772982, 7705.499142555716, 925.5603588295505, 939.2063706168277, 941.1858742188095, 961.9038096190325, 964.5611246882652, 24998.50002999966, 27119.0, 27120.1863944082, 28549.0, 29509.557356639667, 1032.0, 1036.7926414716935, 1042.2912419554775, 1138.9886101139048, 1153.7865494883306, 13762.862371376359, 13765.036447448783, 13972.231527265894, 13991.440342386191, 14017.579472615838, 313246.0, 316637.0, 317245.0, 322269.0, 323317.0, 49694.0, 50506.74746626243, 50518.24222636663, 50551.24173137406, 50563.49436505661, 1311.8032295155804, 1326.1512631915582, 1333.1601091312605, 1371.91082579634, 1373.4849431463201, 23842.523149537257, 26875.328116797238, 27009.86495067512, 28248.15255542337, 28648.85675571609, 44429.55570444383, 45650.54349456463, 45710.314345285515, 45745.31382029339, 45747.542524575656, 243503.0, 246405.0, 247964.0, 248965.0, 295297.0, 25173.87413062923, 30606.693933061277, 30670.233244168183, 31212.0, 31247.53128703026, 36917.815410922776, 39592.40611390889, 40126.79936600298, 40550.18899621932, 40670.79664601776, 33413.83293083519, 33789.83105084459, 34006.82996585001, 34195.658043419244, 34515.309693806485, 34811.47782783311, 34813.303733925684, 34900.47649285212, 34959.30081398409, 35269.64730352664, 33364.499532507514, 33678.83160584181, 33689.326213475106, 34004.1498962518, 34116.829415852764, 28619.570706439004, 29028.419431610826, 30512.237194070327, 30633.23416914595, 31049.379012419173, 23404.765952340258, 23425.531489370456, 23471.41321466977, 23765.762342376354, 23798.643020355055], "unit": "score", "name": "ext.html", "value": 17928.373841237517}, {"lowerIsBetter": false, "replicates": [355.1299316674149, 355.9341521818464, 357.3050416939053, 357.8353957179699, 360.27404779369573, 13454.865451345484, 13772.104813187161, 16067.919660401696, 16648.0, 17854.107294635247, 13480.52818151365, 14172.362243699032, 14435.92782036091, 14636.634084147901, 14643.19462429566, 5191.688498690071, 5422.457754224566, 5427.810026649068, 5428.945710542903, 5437.102878025121, 3982.084120652252, 4173.332266837307, 5265.9473405266035, 5384.273123128386, 5700.173474846145, 5656.123300888369, 5799.623024503394, 5896.321922978869, 5896.410358964091, 5906.88186236275, 5704.258446401962, 5746.224259724925, 5750.223719797816, 5750.367459579443, 5754.654720716729, 4912.852614421573, 5064.088464076464, 5074.543291103819, 5121.103806833789, 5121.615878809104, 19373.225070997145, 19379.4186174415, 19499.902500487548, 19692.785382674643, 19701.21195152192, 2122.0450797141193, 2170.5441857210003, 2195.121951219509, 2202.6806113113507, 2330.475642980333, 12359.134860559765, 12545.247285162919, 12561.0, 12606.810897836449, 13119.803202951964, 1826.1325870211542, 1841.9815801841944, 1856.675081860669, 1904.438190733723, 1915.5115445561432, 51277.48722512765, 51568.74215628898, 53736.0, 54062.45937540653, 54207.728961354944, 9226.677066302647, 9434.245260379153, 9598.280128990353, 9608.615655373847, 9645.08371704687, 12259.632211033684, 12410.014397696325, 12434.937825310815, 12457.314847683465, 12506.0, 7322.1579518355, 7385.778426647209, 7416.629168541552, 7430.294122058396, 7506.812329691801, 7151.606661633556, 7191.316824901626, 7215.206327303992, 7220.3140701633265, 7235.927640723526, 5672.262605861211, 5765.308163020381, 5767.22142510764, 5770.047942089592, 5813.94186058134, 1629.5274370432678, 1693.1280390598852, 1702.753100800377, 1770.3361239535175, 1773.689604319239, 906.8761497538958, 921.5898924978454, 947.2989987409405, 1023.1201166996377, 1068.3429690740138, 15838.76241856373, 15971.0, 16348.918255408884, 16454.177291135395, 16697.833021669627, 821.9313900456149, 948.8813898262717, 958.2142643032646, 975.8097171051577, 993.8509223616516, 8196.5491897945, 8615.91384086151, 8814.779630509289, 8848.292136629054, 9022.774430639156, 507628.0, 507866.0, 513485.0, 528493.0, 530225.0, 42920.57079429228, 44285.0, 60749.34522262452, 66577.66844663079, 70972.64513677398, 1087.5758454202867, 1141.5148561861176, 1141.6517962021662, 1141.6860363399965, 1158.6697791129657, 14227.644308891946, 14566.927165364104, 14723.263836808332, 15085.396584136512, 15161.393544258548, 20173.0, 30237.63930623083, 30933.0720078398, 32127.196820079684, 33563.66436335605, 37611.0, 37684.43473347957, 38043.61956380401, 38470.0, 38523.22953540969, 6483.610983341015, 6718.160229971254, 6901.827454313682, 7052.471064670068, 7281.453890958093, 7654.74830800526, 9696.22430205596, 10296.0, 10584.153267738717, 10825.350478971286, 7540.509866858637, 9363.250939924927, 9442.244620430212, 9460.621575137193, 9598.760030999281, 9039.412438191497, 9444.386114902509, 9678.51607419612, 9696.5151742414, 9945.701628951143, 7354.558726476428, 7460.29127232912, 9193.865474227303, 9350.579223934803, 9579.808403831745, 6977.406920411619, 7095.432365410858, 7111.075560177143, 7114.644267786486, 7244.637768111679, 7993.200679931961, 9364.157225849705, 9496.287778416508, 9692.321537492307, 9773.218142548436], "unit": "score", "name": "jquery.html", "value": 7970.165375327098}, {"lowerIsBetter": false, "replicates": [1296.0, 1334.3861823561165, 1359.347513193667, 1412.8869690424767, 1681.537577166279, 6691.765788197414, 7242.420606351492, 7352.632368381586, 7382.483226174157, 7396.667149978257, 7094.396976257011, 7235.6382180891005, 7249.528780629269, 7271.0, 7304.086989126359, 1962.4112766170163, 2001.479615300018, 2004.2984955265613, 2215.7451893032307, 2245.057076028064, 2015.2644284836076, 2237.0827960536185, 2290.0725206291395, 2295.735990361105, 2298.4368829636765, 2158.3740715192653, 2180.6510958246686, 2225.5771403433378, 2228.8774117423536, 2233.0286325448483, 1934.1586409911733, 1974.4767636576319, 2163.6862654915026, 2169.9566008679817, 2178.7930146635963, 2223.7665045170347, 2273.8521996070367, 2274.3177046885867, 2274.6701728249477, 2276.4195130241847, 7070.151581810164, 7118.252583478712, 7119.608421536814, 7132.821679458003, 7169.0, 4392.692511524195, 4484.865454036384, 4509.977450112761, 4524.8416305429155, 4529.818807247706, 2743.533599288126, 2977.434287485371, 2981.1503721439285, 2981.38881529286, 2991.9551206731917, 953.5470651440579, 974.2060220919927, 995.4574756757585, 996.7956568903357, 1027.5992362978445, 10023.298369114165, 10257.128144107759, 10286.331388459801, 10292.279540432173, 10319.122874555671, 4067.5525692173837, 4152.505098164658, 4178.39413285072, 4208.621224089847, 4211.178820130107, 8846.46921184731, 8867.423617464845, 8889.599968001456, 8894.555272236363, 8898.288136949028, 4582.175208462509, 4595.058013107372, 4600.6549508786975, 4601.263797792337, 4601.930971035437, 4130.6282434580435, 4145.336746120666, 4160.105577300835, 4169.457970463881, 4172.3532852407925, 3379.5437615921537, 3411.164264755101, 3421.1447138215444, 3424.0583839444353, 3459.498372735939, 1101.4988180378068, 1145.8395824584566, 1147.1109889835277, 1147.1568397228032, 1154.7401834587151, 1261.5332327039055, 1261.7539579781856, 1279.7952327627536, 1282.8332316798942, 1315.861834507363, 6890.379865812101, 6921.965390173017, 6942.861142777117, 7043.894341584881, 7045.612491313027, 1094.600470828142, 1095.6165342130191, 1101.1906248907046, 1117.474786850179, 1145.3185354713933, 4603.792829322689, 4689.882752931136, 5186.870328241824, 5444.8094316698725, 5490.0, 8644.00593931706, 8699.565021748887, 8809.42738721994, 8826.205641492297, 8826.514541700268, 8589.48463092204, 8907.821843563092, 8917.598708058153, 8947.731568052968, 8958.552072396355, 6906.171259448898, 6913.170419549686, 6934.133233345932, 6999.0, 7005.719771209197, 6641.800745977628, 6766.052752614736, 6790.864182716219, 6910.412614927586, 6919.827004324932, 7661.923380766121, 7980.321672657889, 8001.559914204775, 8042.718504852302, 8091.635876385459, 8113.229243221885, 8291.875621865798, 8302.83394332122, 8475.57622118904, 8506.191911768372, 6524.314946930694, 6543.803685889431, 6574.572652777554, 6604.3725846044545, 6610.570312929644, 7027.543209691354, 7155.606441645759, 7200.531965422231, 7223.566586004857, 7242.529235599669, 6653.334666533308, 6773.62745049027, 6824.7952561423235, 6988.825279368057, 7042.6126563039525, 6642.368974947372, 6823.38589526945, 6868.656567171722, 6942.2363540011565, 7007.824804379931, 6870.690818913061, 6901.378875901193, 6906.205786334636, 6907.240203577703, 6909.2745261746795, 6305.180326557518, 6310.0, 6339.619622822645, 6457.0, 6487.3836985486305, 6466.773662921775, 6505.902411463927, 6507.479401647952, 6515.250746164252, 6516.73933042673], "unit": "score", "name": "mootools.html", "value": 4315.950442235882}, {"lowerIsBetter": false, "replicates": [329.4728434504793, 333.64466842812385, 336.7070648535773, 341.4690156806167, 342.3973806101263, 5778.393268706788, 5839.708014599269, 5854.297484301879, 5865.58940874139, 5889.911651325234, 5685.971570142154, 5748.166515855199, 5807.796727114552, 5815.302163740346, 5852.385499522553, 4555.521670224628, 4567.634589232854, 4567.885802854922, 4573.314002899559, 4580.4045474088325, 4337.9566204338025, 4426.313921342197, 4539.0, 4542.386777785006, 4558.589726924576, 4361.890952726176, 4473.351364052211, 4485.439320084989, 4495.865124046284, 4509.075639493913, 4469.217886869817, 4478.484974227957, 4485.932711009337, 4490.124425736983, 4491.528389519119, 3943.132510847609, 3949.664278536328, 3961.742486738353, 3962.4254483100076, 3965.940510892339, 5449.073657478239, 5556.888862222773, 5556.972215138939, 5559.138333558305, 5560.719175686365, 5221.738913054332, 5227.137522308814, 5239.266502689627, 5241.816536421207, 5249.055170069386, 6237.7816776413065, 6245.937540624626, 6250.531210159256, 6251.718672659696, 6257.311695713468, 4134.855280065213, 4171.124063946544, 4228.133232687292, 4273.594008569181, 4363.869083927487, 6385.5210859185745, 6451.354864513558, 6462.515311351667, 6485.286618471964, 6538.934610653881, 4415.911681766347, 4424.137293227854, 4425.712328698624, 4426.203283409017, 4427.247367947457, 4495.303227999665, 4497.370368148462, 4497.88755281114, 4503.7522936238165, 4504.234280172379, 5001.474845141281, 5157.716325602053, 5161.974190129025, 5163.225516172604, 5188.455212202664, 6197.25632924043, 6245.094461303085, 6245.313015568284, 6248.250209974832, 6256.280527739277, 1575.5430925031612, 1600.1919030889326, 1600.343859017797, 1600.3758534171682, 1603.0461875184246, 1035.699647102347, 1060.3637817309552, 1062.8193207154802, 1063.0432610650475, 1082.9729256768644, 5909.970450147722, 5920.31916329619, 5921.585489015813, 5924.31870334917, 5929.822105336761, 6335.9862422013175, 6414.518911081687, 6415.326390728999, 6415.903761443582, 6417.294097649255, 5905.498032667186, 5912.113183022581, 5913.0, 5915.645061296336, 5916.26046744157, 3388.237646529511, 3479.460683594047, 3484.041888480688, 3487.232808782064, 3492.4586689062726, 6749.0888730020815, 6760.121184246115, 6770.69531871067, 6773.593584384953, 6775.525713200127, 6633.038209459602, 6634.800955971328, 6636.5354425190735, 6637.369449902348, 6643.534952553371, 6571.901421478682, 6696.229933557605, 6748.089007983859, 6751.831204219738, 6752.966235168891, 6463.935360646334, 6465.224173099258, 6465.771503414337, 6466.127072845294, 6473.546851720334, 6119.357467465852, 6193.938060619336, 6285.779997700059, 6380.0, 6389.105525226379, 6670.366315200048, 6704.765333213314, 6705.0, 6708.731650733916, 6719.227288861648, 4426.402435671143, 4460.910781784281, 4468.709533880287, 4475.843345482893, 4476.843310484118, 6259.874802504016, 6441.3880681335195, 6447.516436267205, 6461.19235095613, 6461.903071454026, 6258.280297765815, 6278.0896769967185, 6307.779227727007, 6310.400511951358, 6310.800947820083, 6214.316425193135, 6261.530385221217, 6264.9976003839165, 6276.215473065867, 6294.937050629435, 5983.820485385445, 6157.599756015845, 6188.845278868064, 6196.47329976957, 6197.070439433941, 6332.430081292706, 6353.396427339396, 6354.26925903527, 6369.840753981188, 6374.776882809079, 6211.378862113933, 6218.782342617987, 6225.62646241227, 6225.968870155621, 6228.972219583718], "unit": "score", "name": "prototype.html", "value": 4712.550922087132}, {"lowerIsBetter": false, "replicates": [757.821911850715, 776.6194564663313, 801.8757092650637, 843.0, 860.1183786618711, 12965.286909219989, 13161.144525605829, 13173.934130329359, 13178.406971686272, 13180.340982950838, 12948.611541653765, 12957.80563291549, 12960.80558791619, 13002.154859934122, 13008.739825203515, 651.0299653516274, 666.5900471210196, 786.6499407763547, 805.0500409516783, 816.1145157504116, 799.6281728996016, 806.6974884418344, 809.0695699945081, 815.9224873636995, 818.0061225610882, 845.121074082953, 850.9361797865154, 851.459323329687, 851.974440766778, 852.1180578101666, 672.4183581202274, 685.2941470285614, 835.1690068381998, 844.4173520270994, 852.2287329966329, 626.5269721360389, 626.8526896179394, 627.4917316973266, 627.8336240896166, 628.205320269859, 12951.02867284948, 12978.740425191538, 12992.740145197138, 13010.869891301061, 13020.739585208243, 11590.942045289805, 11803.350815705133, 12027.338496382696, 12121.93939030308, 12258.938705306506, 9985.550650220684, 9988.400695958266, 9995.650152244709, 10007.59969601215, 10077.294589378746, 3510.052285882797, 3583.229605634801, 3619.330423871566, 3625.7643253188457, 3651.233241019389, 42248.78875605633, 43402.56597434017, 43456.56543434557, 43625.563744362466, 43642.12715745699, 9426.340156189, 9639.228861691048, 9659.618205586208, 9864.556094975744, 9919.603215871284, 16531.586710332096, 16602.418915337905, 16608.086555239337, 16636.0, 16688.83311166897, 7492.400607951351, 7533.20901305366, 7682.116556596063, 7789.454738168274, 7793.532388056735, 4343.782810859444, 4368.235558777201, 4421.182081314967, 4477.350784136282, 4484.148011877733, 2979.1360505453176, 3013.698630136969, 3020.2751339678757, 3024.4102400031784, 3025.8184508929535, 550.7549140632381, 558.5447859994092, 559.1612581128308, 573.7160105747681, 581.2472847662287, 475.555355742382, 490.70312460960605, 520.7344254430268, 582.9329627092853, 632.8924082905917, 8795.604197811113, 9044.81910361789, 9068.274538036942, 9378.390404623678, 9424.323369794933, 508.3493128795148, 569.8489900176456, 594.0169020271458, 612.7487730030755, 636.6562056489537, 4098.7950602470355, 4156.542780294165, 4166.0, 4277.486701595766, 4338.457692788402, 53558.0, 53805.46194538005, 53926.0, 53954.460455395725, 53968.0, 48380.032399352516, 49556.50443495591, 49811.50188498069, 49930.50069499259, 50414.747926260134, 43742.78128609336, 43945.560544394786, 44033.0, 44509.33236001527, 45242.32136517955, 7977.760667179994, 8518.14818518168, 8615.741527754177, 8706.347023973101, 8877.911220887709, 34060.14849628779, 34171.82914085413, 34221.31557368889, 35141.0, 35625.46561801627, 41064.58935410608, 41328.38007429951, 41512.377314339705, 43402.56597433985, 43672.34491482567, 10645.574177032831, 10648.148148148284, 10666.0, 10847.837282440612, 11187.832182517106, 11485.82771258415, 11835.28988260707, 11846.229995050295, 11875.406229688653, 11978.880211198126, 10824.945875270574, 11192.440377981231, 11316.660500185008, 11424.0, 11748.647540573797, 10634.787304253718, 10672.73318167021, 10861.348319100563, 11170.553177873118, 11229.71925701864, 10893.0, 10942.781144377228, 11040.834387484034, 11222.214444988773, 11277.661670149908, 9019.368644195105, 9163.120284640803, 9186.448813071238, 9275.86086208694, 9320.02139775341, 8553.914460855562, 9069.01204337625, 9291.581878815605, 9379.577918993526, 9573.138417542455], "unit": "score", "name": "yui.html", "value": 6116.618445032619}], "name": "dromaeo_css", "value": 6696.809802371691}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 109.0, 110.0, 109.0, 99.0, 111.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.2}, {"lowerIsBetter": true, "replicates": [160.0, 126.0, 149.0, 175.0, 132.0, 153.0, 148.0, 134.0, 165.0, 134.0], "unit": "score", "name": "audio-beat-detection", "value": 147.6}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 162.0, 160.0, 162.0, 161.0, 161.0, 162.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.4}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 80.0, 97.0, 70.0, 70.0, 71.0, 99.0, 95.0, 70.0], "unit": "score", "name": "audio-fft", "value": 79.5}, {"lowerIsBetter": true, "replicates": [98.0, 97.0, 142.0, 96.0, 97.0, 97.0, 143.0, 97.0, 97.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 110.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 147.0, 145.0, 146.0, 146.0, 145.0, 147.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [61.0, 58.0, 62.0, 59.0, 59.0, 58.0, 59.0, 66.0, 57.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 69.0, 68.0, 68.0, 67.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.6}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 86.0, 86.0, 86.0, 85.0, 88.0, 87.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.0}, {"lowerIsBetter": true, "replicates": [116.0, 123.0, 120.0, 116.0, 124.0, 123.0, 123.0, 116.0, 117.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.5}, {"lowerIsBetter": true, "replicates": [140.0, 146.0, 156.0, 150.0, 154.0, 141.0, 164.0, 150.0, 150.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.3}, {"lowerIsBetter": true, "replicates": [69.0, 67.0, 68.0, 69.0, 68.0, 71.0, 69.0, 69.0, 68.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.6}], "name": "kraken", "value": 1508.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.91704223001167], "unit": "score", "name": "Richards", "value": 211.49428200000003}, {"lowerIsBetter": false, "replicates": [191.46084625694044], "unit": "score", "name": "DeltaBlue", "value": 345.33431399999995}, {"lowerIsBetter": false, "replicates": [281.37310073157005], "unit": "score", "name": "Encrypt", "value": 946.0072739999998}, {"lowerIsBetter": false, "replicates": [5251.308900523561], "unit": "score", "name": "Decrypt", "value": 50.68850548354939}, {"lowerIsBetter": false, "replicates": [1992.03187250996], "unit": "score", "name": "RayTrace", "value": 371.474478}, {"lowerIsBetter": false, "replicates": [777.000777000777], "unit": "score", "name": "Earley", "value": 857.737881}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [41000.0], "unit": "score", "name": "RegExp", "value": 22.219146341463418}, {"lowerIsBetter": false, "replicates": [564.3340857787811], "unit": "score", "name": "Splay", "value": 144.40205199999994}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19060.97680746901}]} 05:26:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19060.98"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1508.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6696.81"}}} 05:26:57 INFO - Return code: 0 05:26:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:57 INFO - # TBPL SUCCESS # 05:26:57 INFO - Running post-action listener: _resource_record_post_action 05:26:57 INFO - Running post-run listener: _resource_record_post_run 05:26:58 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 0; Write bytes: 577044480; Read time: 0; Write time: 974680 05:26:58 INFO - install - Wall time: 16s; CPU: 17.0%; Read bytes: 0; Write bytes: 250249216; Read time: 0; Write time: 606564 05:26:58 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 0; Write bytes: 322764800; Read time: 0; Write time: 367512 05:26:58 INFO - Running post-run listener: _upload_blobber_files 05:26:58 INFO - Blob upload gear active. 05:26:58 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:26:58 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:26:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 05:26:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 05:26:59 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:27:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:00 INFO - (blobuploader) - INFO - Done attempting. 05:27:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:27:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:27:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:00 INFO - (blobuploader) - INFO - Done attempting. 05:27:00 INFO - (blobuploader) - INFO - Iteration through files over. 05:27:00 INFO - Return code: 0 05:27:00 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:27:00 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:27:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cf62ba241b95c5ff7c8c9c7a6331f2586b80728310822d4b0a38d37bbbfd672330af2876e7885bdf44fcd1c170efa3992634518e3a7c153adcfa1c747ae95bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6f8077b012448c63b8c746da9d941dfdec69f88a48777762cee9a260440443615e13505b27395ab7ca54aba21b16d8b752a6ed07439150448ba0c028df93c23"} 05:27:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:27:00 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:27:00 INFO - Contents: 05:27:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cf62ba241b95c5ff7c8c9c7a6331f2586b80728310822d4b0a38d37bbbfd672330af2876e7885bdf44fcd1c170efa3992634518e3a7c153adcfa1c747ae95bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6f8077b012448c63b8c746da9d941dfdec69f88a48777762cee9a260440443615e13505b27395ab7ca54aba21b16d8b752a6ed07439150448ba0c028df93c23"} 05:27:00 INFO - Copying logs to upload dir... 05:27:00 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.915175 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-01-08 05:27:00.483908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-08 05:27:00.487944) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cf62ba241b95c5ff7c8c9c7a6331f2586b80728310822d4b0a38d37bbbfd672330af2876e7885bdf44fcd1c170efa3992634518e3a7c153adcfa1c747ae95bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6f8077b012448c63b8c746da9d941dfdec69f88a48777762cee9a260440443615e13505b27395ab7ca54aba21b16d8b752a6ed07439150448ba0c028df93c23"} build_url:https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/YZMS3XNKQsemKHvSfcuSDg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cf62ba241b95c5ff7c8c9c7a6331f2586b80728310822d4b0a38d37bbbfd672330af2876e7885bdf44fcd1c170efa3992634518e3a7c153adcfa1c747ae95bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6f8077b012448c63b8c746da9d941dfdec69f88a48777762cee9a260440443615e13505b27395ab7ca54aba21b16d8b752a6ed07439150448ba0c028df93c23"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-08 05:27:00.512359) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:27:00.512679) ========= 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=f919189b672bc2eeae365d21000001d6-1452257071.762135-2137885114 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 05:27:00.595150) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 05:27:00.595455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 05:27:00.595775) ========= ========= Total master_lag: 0.24 =========