builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-056 starttime: 1451804249.01 results: success (0) buildid: 20160102203606 builduid: bed859263e8c47428191a769ce060290 revision: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.013153) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.014006) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.014292) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006319 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.045935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.046290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.117814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.118260) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.179906) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.180399) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.180957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.181466) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-02 22:57:30-- 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% 36.1M=0s 2016-01-02 22:57:30 (36.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.204473 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.403883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.404179) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019615 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.453186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:29.453542) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --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 d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-02 22:57:30,378 truncating revision to first 12 chars 2016-01-02 22:57:30,378 Setting DEBUG logging. 2016-01-02 22:57:30,378 attempt 1/10 2016-01-02 22:57:30,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d7a0ad85d9fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-02 22:57:30,671 unpacking tar archive at: fx-team-d7a0ad85d9fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541192 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:30.007583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:30.007937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:30.018183) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:30.018444) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-02 22:57:30.018838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-02 22:57:30.019117) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:57:31 INFO - MultiFileLogger online at 20160102 22:57:31 in /builds/slave/test-pgo 22:57:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:57:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:57:31 INFO - {'append_to_log': False, 22:57:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:57:31 INFO - 'blob_upload_branch': 'Fx-Team', 22:57:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:57:31 INFO - 'branch': 'Fx-Team', 22:57:31 INFO - 'buildbot_json_path': 'buildprops.json', 22:57:31 INFO - 'config_files': ('talos/linux_config.py',), 22:57:31 INFO - 'default_actions': ('clobber', 22:57:31 INFO - 'read-buildbot-config', 22:57:31 INFO - 'download-and-extract', 22:57:31 INFO - 'populate-webroot', 22:57:31 INFO - 'create-virtualenv', 22:57:31 INFO - 'install', 22:57:31 INFO - 'run-tests'), 22:57:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:57:31 INFO - 'download_minidump_stackwalk': True, 22:57:31 INFO - 'download_symbols': 'ondemand', 22:57:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:57:31 INFO - 'tooltool.py': '/tools/tooltool.py', 22:57:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:57:31 INFO - '/tools/misc-python/virtualenv.py')}, 22:57:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:57:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:57:31 INFO - 'installer_path': 'installer.exe', 22:57:31 INFO - 'log_level': 'info', 22:57:31 INFO - 'log_name': 'talos', 22:57:31 INFO - 'log_to_console': True, 22:57:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:57:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:57:31 INFO - 'opt_config_files': (), 22:57:31 INFO - 'pip_index': False, 22:57:31 INFO - 'sps_profile': False, 22:57:31 INFO - 'sps_profile_interval': 0, 22:57:31 INFO - 'suite': 'dromaeojs-e10s', 22:57:31 INFO - 'system_bits': '32', 22:57:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:57:31 INFO - 'title': 'talos-linux64-ix-056', 22:57:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:57:31 INFO - 'use_talos_json': True, 22:57:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:57:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:57:31 INFO - 'work_dir': 'build'} 22:57:31 INFO - ##### 22:57:31 INFO - ##### Running clobber step. 22:57:31 INFO - ##### 22:57:31 INFO - Running pre-action listener: _resource_record_pre_action 22:57:31 INFO - Running main action method: clobber 22:57:31 INFO - rmtree: /builds/slave/test-pgo/build 22:57:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:57:31 INFO - Running post-action listener: _resource_record_post_action 22:57:31 INFO - ##### 22:57:31 INFO - ##### Running read-buildbot-config step. 22:57:31 INFO - ##### 22:57:31 INFO - Running pre-action listener: _resource_record_pre_action 22:57:31 INFO - Running main action method: read_buildbot_config 22:57:31 INFO - Using buildbot properties: 22:57:31 INFO - { 22:57:31 INFO - "project": "", 22:57:31 INFO - "product": "firefox", 22:57:31 INFO - "script_repo_revision": "production", 22:57:31 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 22:57:31 INFO - "repository": "", 22:57:31 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 22:57:31 INFO - "buildid": "20160102203606", 22:57:31 INFO - "pgo_build": "True", 22:57:31 INFO - "basedir": "/builds/slave/test-pgo", 22:57:31 INFO - "buildnumber": 173, 22:57:31 INFO - "slavename": "talos-linux64-ix-056", 22:57:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:57:31 INFO - "platform": "ubuntu64_hw", 22:57:31 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:57:31 INFO - "branch": "fx-team", 22:57:31 INFO - "repo_path": "integration/fx-team", 22:57:31 INFO - "revision": "d7a0ad85d9fb77916f9d77d62697b852f3dc63e6", 22:57:31 INFO - "stage_platform": "linux64-pgo", 22:57:31 INFO - "builduid": "bed859263e8c47428191a769ce060290", 22:57:31 INFO - "slavebuilddir": "test" 22:57:31 INFO - } 22:57:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:57:31 INFO - Running post-action listener: _resource_record_post_action 22:57:31 INFO - ##### 22:57:31 INFO - ##### Running download-and-extract step. 22:57:31 INFO - ##### 22:57:31 INFO - Running pre-action listener: _resource_record_pre_action 22:57:31 INFO - Running main action method: download_and_extract 22:57:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:57:31 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')]} 22:57:31 INFO - https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:57:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/test_packages.json 22:57:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/test_packages.json 22:57:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:57:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:57:34 INFO - Downloaded 1302 bytes. 22:57:34 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:57:34 INFO - Using the following test package requirements: 22:57:34 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:57:34 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:57:34 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'jsshell-linux-x86_64.zip'], 22:57:34 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:57:34 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:57:34 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:57:34 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:57:34 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:57:34 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:57:34 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:57:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:57:34 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:34 INFO - https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:57:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:36 INFO - Downloaded 23198176 bytes. 22:57:36 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 22:57:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:36 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 22:57:37 INFO - Return code: 0 22:57:37 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:37 INFO - https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:57:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:40 INFO - Downloaded 23198176 bytes. 22:57:40 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 22:57:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:57:40 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 22:57:41 INFO - Return code: 0 22:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:41 INFO - https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:57:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:57:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:57:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:42 INFO - Downloaded 11132285 bytes. 22:57:42 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 22:57:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:57:42 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 22:57:42 INFO - Return code: 0 22:57: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')]} 22:57:42 INFO - https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:57:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:57:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:57:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UksYm0SlTISm7DLn6TKUUQ/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 22:57:44 INFO - Downloaded 54984186 bytes. 22:57:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:57:44 INFO - mkdir: /builds/slave/test-pgo/properties 22:57:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:57:44 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:57:44 INFO - Contents: 22:57:44 INFO - build_url:https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:57:44 INFO - Running post-action listener: _resource_record_post_action 22:57:44 INFO - Running post-action listener: set_extra_try_arguments 22:57:44 INFO - ##### 22:57:44 INFO - ##### Running populate-webroot step. 22:57:44 INFO - ##### 22:57:44 INFO - Running pre-action listener: _resource_record_pre_action 22:57:44 INFO - Running main action method: populate_webroot 22:57:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:57:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:57:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:57:44 INFO - '--tppagecycles', 22:57:44 INFO - '7'], 22:57:44 INFO - 'tests': ['tsvgm']}}, 22:57:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:57:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:57:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:57:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:57:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'tests': ['damp', 'tps']}, 22:57:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['damp', 'tps']}, 22:57:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:57:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:57:44 INFO - 'other': {'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'other_l64': {'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'other_nol64': {'tests': ['a11yr', 22:57:44 INFO - 'ts_paint', 22:57:44 INFO - 'tpaint', 22:57:44 INFO - 'sessionrestore', 22:57:44 INFO - 'sessionrestore_no_auto_restore']}, 22:57:44 INFO - 'svgr': {'tests': ['tsvgx', 22:57:44 INFO - 'tsvgr_opacity', 22:57:44 INFO - 'tart', 22:57:44 INFO - 'tscrollx', 22:57:44 INFO - 'cart']}, 22:57:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['tsvgx', 22:57:44 INFO - 'tsvgr_opacity', 22:57:44 INFO - 'tart', 22:57:44 INFO - 'tscrollx', 22:57:44 INFO - 'cart']}, 22:57:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'tests': ['tp5o']}, 22:57:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'talos_options': ['--e10s'], 22:57:44 INFO - 'tests': ['tp5o']}, 22:57:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'talos_options': ['--xperf_path', 22:57:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:44 INFO - 'tests': ['tp5n']}, 22:57:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:44 INFO - 'talos_options': ['--e10s', 22:57:44 INFO - '--xperf_path', 22:57:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:44 INFO - 'tests': ['tp5n']}}, 22:57:44 INFO - 'talos.zip': {'path': '', 22:57:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:57:44 INFO - Running post-action listener: _resource_record_post_action 22:57:44 INFO - ##### 22:57:44 INFO - ##### Running create-virtualenv step. 22:57:44 INFO - ##### 22:57:44 INFO - Running pre-action listener: _resource_record_pre_action 22:57:44 INFO - Running main action method: create_virtualenv 22:57:44 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:57:44 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 22:57:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:57:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:57:44 INFO - Using real prefix '/usr' 22:57:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:57:44 INFO - Installing distribute.............................................................................................................................................................................................done. 22:57:46 INFO - Installing pip.................done. 22:57:46 INFO - Return code: 0 22:57:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:46 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 22:57:46 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 22:57:46 INFO - Using env: {'DISPLAY': ':0', 22:57:46 INFO - 'HOME': '/home/cltbld', 22:57:46 INFO - 'LANG': 'en_US.UTF-8', 22:57:46 INFO - 'LANGUAGE': 'en_US:en', 22:57:46 INFO - 'LOGNAME': 'cltbld', 22:57:46 INFO - 'MAIL': '/var/mail/cltbld', 22:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:46 INFO - 'MOZ_NO_REMOTE': '1', 22:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:46 INFO - 'NO_EM_RESTART': '1', 22:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:46 INFO - 'SHELL': '/bin/bash', 22:57:46 INFO - 'SHLVL': '1', 22:57:46 INFO - 'TERM': 'linux', 22:57:46 INFO - 'TMOUT': '86400', 22:57:46 INFO - 'USER': 'cltbld', 22:57:46 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:46 INFO - '_': '/tools/buildbot/bin/python'} 22:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:46 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 22:57:46 INFO - Cleaning up... 22:57:46 INFO - Return code: 0 22:57:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:46 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:46 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 22:57:46 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 22:57:46 INFO - Using env: {'DISPLAY': ':0', 22:57:46 INFO - 'HOME': '/home/cltbld', 22:57:46 INFO - 'LANG': 'en_US.UTF-8', 22:57:46 INFO - 'LANGUAGE': 'en_US:en', 22:57:46 INFO - 'LOGNAME': 'cltbld', 22:57:46 INFO - 'MAIL': '/var/mail/cltbld', 22:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:46 INFO - 'MOZ_NO_REMOTE': '1', 22:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:46 INFO - 'NO_EM_RESTART': '1', 22:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:46 INFO - 'SHELL': '/bin/bash', 22:57:46 INFO - 'SHLVL': '1', 22:57:46 INFO - 'TERM': 'linux', 22:57:46 INFO - 'TMOUT': '86400', 22:57:46 INFO - 'USER': 'cltbld', 22:57:46 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:46 INFO - '_': '/tools/buildbot/bin/python'} 22:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:47 INFO - Downloading/unpacking psutil>=0.7.1 22:57:47 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 22:57:47 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 22:57:47 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 22:57:47 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 22:57:47 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 22:57:47 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 22:57:48 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:57:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:57:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:57:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:48 INFO - Installing collected packages: psutil 22:57:48 INFO - Running setup.py install for psutil 22:57:48 INFO - building 'psutil._psutil_linux' extension 22:57:48 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 22:57:49 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 22:57:49 INFO - building 'psutil._psutil_posix' extension 22:57:49 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 22:57:49 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 22:57:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:49 INFO - Successfully installed psutil 22:57:49 INFO - Cleaning up... 22:57:49 INFO - Return code: 0 22:57:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:49 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:49 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 22:57:49 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 22:57:49 INFO - Using env: {'DISPLAY': ':0', 22:57:49 INFO - 'HOME': '/home/cltbld', 22:57:49 INFO - 'LANG': 'en_US.UTF-8', 22:57:49 INFO - 'LANGUAGE': 'en_US:en', 22:57:49 INFO - 'LOGNAME': 'cltbld', 22:57:49 INFO - 'MAIL': '/var/mail/cltbld', 22:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:49 INFO - 'MOZ_NO_REMOTE': '1', 22:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:49 INFO - 'NO_EM_RESTART': '1', 22:57:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:49 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:49 INFO - 'SHELL': '/bin/bash', 22:57:49 INFO - 'SHLVL': '1', 22:57:49 INFO - 'TERM': 'linux', 22:57:49 INFO - 'TMOUT': '86400', 22:57:49 INFO - 'USER': 'cltbld', 22:57:49 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:49 INFO - '_': '/tools/buildbot/bin/python'} 22:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:57:49 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 22:57:49 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 22:57:49 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 22:57:49 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 22:57:49 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 22:57:49 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 22:57:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:57:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:57:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:57:51 INFO - Installing collected packages: mozsystemmonitor 22:57:51 INFO - Running setup.py install for mozsystemmonitor 22:57:51 INFO - Successfully installed mozsystemmonitor 22:57:51 INFO - Cleaning up... 22:57:51 INFO - Return code: 0 22:57:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:51 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:51 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 22:57:51 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 22:57:51 INFO - Using env: {'DISPLAY': ':0', 22:57:51 INFO - 'HOME': '/home/cltbld', 22:57:51 INFO - 'LANG': 'en_US.UTF-8', 22:57:51 INFO - 'LANGUAGE': 'en_US:en', 22:57:51 INFO - 'LOGNAME': 'cltbld', 22:57:51 INFO - 'MAIL': '/var/mail/cltbld', 22:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:51 INFO - 'MOZ_NO_REMOTE': '1', 22:57:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:51 INFO - 'NO_EM_RESTART': '1', 22:57:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:51 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:51 INFO - 'SHELL': '/bin/bash', 22:57:51 INFO - 'SHLVL': '1', 22:57:51 INFO - 'TERM': 'linux', 22:57:51 INFO - 'TMOUT': '86400', 22:57:51 INFO - 'USER': 'cltbld', 22:57:51 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:51 INFO - '_': '/tools/buildbot/bin/python'} 22:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:51 INFO - Downloading/unpacking blobuploader==1.2.4 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:51 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 22:57:53 INFO - Downloading blobuploader-1.2.4.tar.gz 22:57:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:57:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:57:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:57:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:57:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:53 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 22:57:54 INFO - Downloading docopt-0.6.1.tar.gz 22:57:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:57:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:57:54 INFO - Installing collected packages: blobuploader, requests, docopt 22:57:54 INFO - Running setup.py install for blobuploader 22:57:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:57:54 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:57:54 INFO - Running setup.py install for requests 22:57:54 INFO - Running setup.py install for docopt 22:57:54 INFO - Successfully installed blobuploader requests docopt 22:57:54 INFO - Cleaning up... 22:57:54 INFO - Return code: 0 22:57:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:54 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:54 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 22:57:54 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 22:57:54 INFO - Using env: {'DISPLAY': ':0', 22:57:54 INFO - 'HOME': '/home/cltbld', 22:57:54 INFO - 'LANG': 'en_US.UTF-8', 22:57:54 INFO - 'LANGUAGE': 'en_US:en', 22:57:54 INFO - 'LOGNAME': 'cltbld', 22:57:54 INFO - 'MAIL': '/var/mail/cltbld', 22:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:54 INFO - 'MOZ_NO_REMOTE': '1', 22:57:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:54 INFO - 'NO_EM_RESTART': '1', 22:57:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:54 INFO - 'SHELL': '/bin/bash', 22:57:54 INFO - 'SHLVL': '1', 22:57:54 INFO - 'TERM': 'linux', 22:57:54 INFO - 'TMOUT': '86400', 22:57:54 INFO - 'USER': 'cltbld', 22:57:54 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:54 INFO - '_': '/tools/buildbot/bin/python'} 22:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:57:54 INFO - Running setup.py (path:/tmp/pip-mHuUYw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:57:55 INFO - Running setup.py (path:/tmp/pip-SAgc_w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:57:55 INFO - Running setup.py (path:/tmp/pip-ipl175-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:57:55 INFO - Running setup.py (path:/tmp/pip-fhX3va-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:57:55 INFO - Running setup.py (path:/tmp/pip-QGIMLV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:57:55 INFO - Running setup.py (path:/tmp/pip-ohk7D1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:57:55 INFO - Running setup.py (path:/tmp/pip-mV1w24-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:57:55 INFO - Running setup.py (path:/tmp/pip-Y3G4eF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:57:55 INFO - Running setup.py (path:/tmp/pip-SX4SkL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:57:55 INFO - Running setup.py (path:/tmp/pip-1_Xtdg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:57:55 INFO - Running setup.py (path:/tmp/pip-00j2Ru-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:57:55 INFO - Running setup.py (path:/tmp/pip-7JC4F8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:57:55 INFO - Running setup.py (path:/tmp/pip-E6pFku-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:57:55 INFO - Running setup.py (path:/tmp/pip-Dbubba-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:57:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:57:55 INFO - Running setup.py (path:/tmp/pip-ZOiGgi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:57:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:57:56 INFO - Running setup.py (path:/tmp/pip-armFHK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:57:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:57:56 INFO - Running setup.py (path:/tmp/pip-7y0vCv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:57:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:57:56 INFO - Running setup.py install for manifestparser 22:57:56 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Running setup.py install for mozcrash 22:57:56 INFO - Running setup.py install for mozdebug 22:57:56 INFO - Running setup.py install for mozdevice 22:57:56 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Running setup.py install for mozfile 22:57:56 INFO - Running setup.py install for mozhttpd 22:57:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Running setup.py install for mozinfo 22:57:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Running setup.py install for mozInstall 22:57:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:57:56 INFO - Running setup.py install for mozleak 22:57:57 INFO - Running setup.py install for mozlog 22:57:57 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Running setup.py install for moznetwork 22:57:57 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Running setup.py install for mozprocess 22:57:57 INFO - Running setup.py install for mozprofile 22:57:57 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Running setup.py install for mozrunner 22:57:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Running setup.py install for mozscreenshot 22:57:57 INFO - Running setup.py install for moztest 22:57:57 INFO - Running setup.py install for mozversion 22:57:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:57:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:57:57 INFO - Cleaning up... 22:57:57 INFO - Return code: 0 22:57:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:57 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:57:57 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 22:57:57 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 22:57:57 INFO - Using env: {'DISPLAY': ':0', 22:57:57 INFO - 'HOME': '/home/cltbld', 22:57:57 INFO - 'LANG': 'en_US.UTF-8', 22:57:57 INFO - 'LANGUAGE': 'en_US:en', 22:57:57 INFO - 'LOGNAME': 'cltbld', 22:57:57 INFO - 'MAIL': '/var/mail/cltbld', 22:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:57 INFO - 'MOZ_NO_REMOTE': '1', 22:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:57 INFO - 'NO_EM_RESTART': '1', 22:57:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:57:57 INFO - 'PWD': '/builds/slave/test-pgo', 22:57:57 INFO - 'SHELL': '/bin/bash', 22:57:57 INFO - 'SHLVL': '1', 22:57:57 INFO - 'TERM': 'linux', 22:57:57 INFO - 'TMOUT': '86400', 22:57:57 INFO - 'USER': 'cltbld', 22:57:57 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:57 INFO - '_': '/tools/buildbot/bin/python'} 22:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:57:58 INFO - Running setup.py (path:/tmp/pip-UClU_W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:57:58 INFO - Running setup.py (path:/tmp/pip-wpLTTI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:57:58 INFO - Running setup.py (path:/tmp/pip-i1DrN7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:57:58 INFO - Running setup.py (path:/tmp/pip-34H4cz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:57:58 INFO - Running setup.py (path:/tmp/pip-_mgdKj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:57:58 INFO - Running setup.py (path:/tmp/pip-eBXs4X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:57:58 INFO - Running setup.py (path:/tmp/pip-b4K8bN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:57:58 INFO - Running setup.py (path:/tmp/pip-0nmLIp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:57:58 INFO - Running setup.py (path:/tmp/pip-6x_aIZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:57:58 INFO - Running setup.py (path:/tmp/pip-3i5dgC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:57:58 INFO - Running setup.py (path:/tmp/pip-uL5k0V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:57:58 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)) 22:57:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:57:59 INFO - Running setup.py (path:/tmp/pip-ZYmulO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:57:59 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)) 22:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:57:59 INFO - Running setup.py (path:/tmp/pip-05KbR2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:57:59 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)) 22:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:57:59 INFO - Running setup.py (path:/tmp/pip-fMUasI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:57:59 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)) 22:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:57:59 INFO - Running setup.py (path:/tmp/pip-qLQgJC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:57:59 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)) 22:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:57:59 INFO - Running setup.py (path:/tmp/pip-qh4E0f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:57:59 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)) 22:57:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:57:59 INFO - Running setup.py (path:/tmp/pip-0AvrMZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:57:59 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)) 22:57:59 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)) 22:57:59 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)) 22:57:59 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)) 22:57:59 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)) 22:57:59 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)) 22:57:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:57:59 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 22:57:59 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 22:57:59 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 22:57:59 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 22:57:59 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 22:57:59 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 22:58:01 INFO - Downloading blessings-1.5.1.tar.gz 22:58:01 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 22:58:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:01 INFO - Installing collected packages: blessings 22:58:01 INFO - Running setup.py install for blessings 22:58:01 INFO - Successfully installed blessings 22:58:01 INFO - Cleaning up... 22:58:01 INFO - Return code: 0 22:58:01 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:58:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:58:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:58:01 INFO - Reading from file tmpfile_stdout 22:58:01 INFO - Current package versions: 22:58:01 INFO - argparse == 1.2.1 22:58:01 INFO - blessings == 1.5.1 22:58:01 INFO - blobuploader == 1.2.4 22:58:01 INFO - docopt == 0.6.1 22:58:01 INFO - manifestparser == 1.1 22:58:01 INFO - mozInstall == 1.12 22:58:01 INFO - mozcrash == 0.16 22:58:01 INFO - mozdebug == 0.1 22:58:01 INFO - mozdevice == 0.47 22:58:01 INFO - mozfile == 1.2 22:58:01 INFO - mozhttpd == 0.7 22:58:01 INFO - mozinfo == 0.9 22:58:01 INFO - mozleak == 0.1 22:58:01 INFO - mozlog == 3.1 22:58:01 INFO - moznetwork == 0.27 22:58:01 INFO - mozprocess == 0.22 22:58:01 INFO - mozprofile == 0.28 22:58:01 INFO - mozrunner == 6.11 22:58:01 INFO - mozscreenshot == 0.1 22:58:01 INFO - mozsystemmonitor == 0.0 22:58:01 INFO - moztest == 0.7 22:58:01 INFO - mozversion == 1.4 22:58:01 INFO - psutil == 3.1.1 22:58:01 INFO - requests == 1.2.3 22:58:01 INFO - wsgiref == 0.1.2 22:58:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:58:01 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')]} 22:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:01 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')]} 22:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:01 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:58:01 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 22:58:01 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 22:58:01 INFO - Using env: {'DISPLAY': ':0', 22:58:01 INFO - 'HOME': '/home/cltbld', 22:58:01 INFO - 'LANG': 'en_US.UTF-8', 22:58:01 INFO - 'LANGUAGE': 'en_US:en', 22:58:01 INFO - 'LOGNAME': 'cltbld', 22:58:01 INFO - 'MAIL': '/var/mail/cltbld', 22:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:01 INFO - 'MOZ_NO_REMOTE': '1', 22:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:01 INFO - 'NO_EM_RESTART': '1', 22:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:58:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:58:01 INFO - 'SHELL': '/bin/bash', 22:58:01 INFO - 'SHLVL': '1', 22:58:01 INFO - 'TERM': 'linux', 22:58:01 INFO - 'TMOUT': '86400', 22:58:01 INFO - 'USER': 'cltbld', 22:58:01 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:01 INFO - '_': '/tools/buildbot/bin/python'} 22:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 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)) 22:58:01 INFO - Cleaning up... 22:58:01 INFO - Return code: 0 22:58:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:58:01 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')]} 22:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:01 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')]} 22:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:01 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:58:01 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 22:58:01 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 22:58:01 INFO - Using env: {'DISPLAY': ':0', 22:58:01 INFO - 'HOME': '/home/cltbld', 22:58:01 INFO - 'LANG': 'en_US.UTF-8', 22:58:01 INFO - 'LANGUAGE': 'en_US:en', 22:58:01 INFO - 'LOGNAME': 'cltbld', 22:58:01 INFO - 'MAIL': '/var/mail/cltbld', 22:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:01 INFO - 'MOZ_NO_REMOTE': '1', 22:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:01 INFO - 'NO_EM_RESTART': '1', 22:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:58:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:58:01 INFO - 'SHELL': '/bin/bash', 22:58:01 INFO - 'SHLVL': '1', 22:58:01 INFO - 'TERM': 'linux', 22:58:01 INFO - 'TMOUT': '86400', 22:58:01 INFO - 'USER': 'cltbld', 22:58:01 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:01 INFO - '_': '/tools/buildbot/bin/python'} 22:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:02 INFO - Downloading/unpacking jsonschema 22:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:03 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 22:58:03 INFO - Downloading/unpacking functools32 (from jsonschema) 22:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:58:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:03 INFO - warning: no files found matching '*.txt' 22:58:03 INFO - no previously-included directories found matching 'build' 22:58:03 INFO - no previously-included directories found matching 'dist' 22:58:03 INFO - no previously-included directories found matching '.git*' 22:58:03 INFO - Installing collected packages: jsonschema, functools32 22:58:04 INFO - Running setup.py install for functools32 22:58:04 INFO - warning: no files found matching '*.txt' 22:58:04 INFO - no previously-included directories found matching 'build' 22:58:04 INFO - no previously-included directories found matching 'dist' 22:58:04 INFO - no previously-included directories found matching '.git*' 22:58:04 INFO - Successfully installed jsonschema functools32 22:58:04 INFO - Cleaning up... 22:58:04 INFO - Return code: 0 22:58:04 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:04 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 0x7f4f832c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, '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': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', '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 22:58:04 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 22:58:04 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 22:58:04 INFO - Using env: {'DISPLAY': ':0', 22:58:04 INFO - 'HOME': '/home/cltbld', 22:58:04 INFO - 'LANG': 'en_US.UTF-8', 22:58:04 INFO - 'LANGUAGE': 'en_US:en', 22:58:04 INFO - 'LOGNAME': 'cltbld', 22:58:04 INFO - 'MAIL': '/var/mail/cltbld', 22:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:04 INFO - 'MOZ_NO_REMOTE': '1', 22:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:04 INFO - 'NO_EM_RESTART': '1', 22:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:58:04 INFO - 'PWD': '/builds/slave/test-pgo', 22:58:04 INFO - 'SHELL': '/bin/bash', 22:58:04 INFO - 'SHLVL': '1', 22:58:04 INFO - 'TERM': 'linux', 22:58:04 INFO - 'TMOUT': '86400', 22:58:04 INFO - 'USER': 'cltbld', 22:58:04 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:04 INFO - '_': '/tools/buildbot/bin/python'} 22:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:04 INFO - Downloading/unpacking flake8 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:04 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 22:58:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:58:06 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 22:58:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:58:06 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 22:58:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 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 22:58:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:58:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:06 INFO - no previously-included directories found matching 'docs/_build' 22:58:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:58:06 INFO - Running setup.py install for pep8 22:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:06 INFO - no previously-included directories found matching 'docs/_build' 22:58:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:58:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:58:06 INFO - Cleaning up... 22:58:06 INFO - Return code: 0 22:58:06 INFO - Running post-action listener: _resource_record_post_action 22:58:06 INFO - Running post-action listener: _start_resource_monitoring 22:58:06 INFO - Starting resource monitoring. 22:58:06 INFO - ##### 22:58:06 INFO - ##### Running install step. 22:58:06 INFO - ##### 22:58:06 INFO - Running pre-action listener: _resource_record_pre_action 22:58:06 INFO - Running main action method: install 22:58:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:58:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:58:07 INFO - Reading from file tmpfile_stdout 22:58:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:58:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:58:07 INFO - Reading from file tmpfile_stdout 22:58:07 INFO - Output received: 22:58:07 INFO - Usage: mozinstall [options] installer 22:58:07 INFO - Options: 22:58:07 INFO - -h, --help show this help message and exit 22:58:07 INFO - -d DEST, --destination=DEST 22:58:07 INFO - Directory to install application into. [default: 22:58:07 INFO - "/builds/slave/test-pgo"] 22:58:07 INFO - --app=APP Application being installed. [default: firefox] 22:58:07 INFO - mkdir: /builds/slave/test-pgo/build/application 22:58:07 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'] 22:58:07 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 22:58:23 INFO - Reading from file tmpfile_stdout 22:58:23 INFO - Output received: 22:58:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:58:23 INFO - Running post-action listener: _resource_record_post_action 22:58:23 INFO - ##### 22:58:23 INFO - ##### Running run-tests step. 22:58:23 INFO - ##### 22:58:23 INFO - Running pre-action listener: _resource_record_pre_action 22:58:23 INFO - Running main action method: run_tests 22:58:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:58:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:58:23 INFO - Python 2.7.3 22:58:23 INFO - Return code: 0 22:58:23 INFO - grabbing minidump binary from tooltool 22:58:23 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')]} 22:58:23 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 0x1610840>, '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 0x157d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x157db60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:58:23 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 22:58:23 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 22:58:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:58:23 INFO - Return code: 0 22:58:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:58:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:58:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:58:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:58:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:58:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:58:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:58:24 INFO - Return code: 0 22:58:24 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 22:58:24 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:58:24 INFO - Using env: {'DISPLAY': ':0', 22:58:24 INFO - 'HOME': '/home/cltbld', 22:58:24 INFO - 'LANG': 'en_US.UTF-8', 22:58:24 INFO - 'LANGUAGE': 'en_US:en', 22:58:24 INFO - 'LOGNAME': 'cltbld', 22:58:24 INFO - 'MAIL': '/var/mail/cltbld', 22:58:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:58:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:24 INFO - 'MOZ_NO_REMOTE': '1', 22:58:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:58:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:24 INFO - 'NO_EM_RESTART': '1', 22:58:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:58:24 INFO - 'PWD': '/builds/slave/test-pgo', 22:58:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:58:24 INFO - 'SHELL': '/bin/bash', 22:58:24 INFO - 'SHLVL': '1', 22:58:24 INFO - 'TERM': 'linux', 22:58:24 INFO - 'TMOUT': '86400', 22:58:24 INFO - 'USER': 'cltbld', 22:58:24 INFO - 'XDG_SESSION_COOKIE': '0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027', 22:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:24 INFO - '_': '/tools/buildbot/bin/python'} 22:58:24 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:58:24 INFO - mozversion application_buildid: 20160102203606 22:58:24 INFO - mozversion application_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 22:58:24 INFO - mozversion application_display_name: Nightly 22:58:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:58:24 INFO - mozversion application_name: Firefox 22:58:24 INFO - mozversion application_remotingname: firefox 22:58:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:58:24 INFO - mozversion application_vendor: Mozilla 22:58:24 INFO - mozversion application_version: 46.0a1 22:58:24 INFO - mozversion platform_buildid: 20160102203606 22:58:24 INFO - mozversion platform_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 22:58:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:58:24 INFO - mozversion platform_version: 46.0a1 22:58:24 INFO - using testdate: 1451804304 22:58:24 INFO - actual date: 1451804304 22:58:24 INFO - starting webserver on 'localhost:59959' 22:58:24 INFO - SUITE-START | Running 3 tests 22:58:24 INFO - TEST-START | dromaeo_css 22:58:24 INFO - operating with platform_type : linux_ 22:58:24 INFO - Initialising browser for dromaeo_css test... 22:58:24 INFO - TEST-INFO | started process 18293 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8AuuRN/profile http://localhost:59959/getInfo.html) 22:58:29 INFO - PROCESS | 18293 | __metrics Screen width/height:1600/1200 22:58:29 INFO - PROCESS | 18293 | colorDepth:24 22:58:29 INFO - PROCESS | 18293 | Browser inner width/height: 1024/697 22:58:29 INFO - PROCESS | 18293 | __metrics 22:58:29 INFO - PROCESS | 18293 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:58:30 INFO - TEST-INFO | 18293: exit 0 22:58:30 INFO - Browser initialized. 22:58:30 INFO - Running cycle 1/1 for dromaeo_css test... 22:58:30 INFO - TEST-INFO | started process 18426 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8AuuRN/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:58:42 INFO - PROCESS | 18426 | RSS: Main: 150269952 22:58:42 INFO - PROCESS | 18426 | 22:59:32 INFO - PROCESS | 18426 | 1451804372212 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] 23:01:40 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59959/tests/dromaeo/cssquery-ext.html) 23:01:42 INFO - PROCESS | 18426 | RSS: Main: 157270016 23:01:42 INFO - PROCESS | 18426 | 23:04:43 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-ext.html (next: http://localhost:59959/tests/dromaeo/cssquery-jquery.html) 23:04:44 INFO - PROCESS | 18426 | RSS: Main: 153026560 23:04:44 INFO - PROCESS | 18426 | 23:07:46 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59959/tests/dromaeo/cssquery-mootools.html) 23:07:46 INFO - PROCESS | 18426 | RSS: Main: 153378816 23:07:46 INFO - PROCESS | 18426 | 23:10:48 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59959/tests/dromaeo/cssquery-prototype.html) 23:10:49 INFO - PROCESS | 18426 | RSS: Main: 152313856 23:10:49 INFO - PROCESS | 18426 | 23:13:51 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59959/tests/dromaeo/cssquery-yui.html) 23:13:52 INFO - PROCESS | 18426 | RSS: Main: 153374720 23:13:52 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | Cycle 1(1): loaded http://localhost:59959/tests/dromaeo/cssquery-yui.html (next: http://localhost:59959/tests/dromaeo/cssquery-dojo.html) 23:16:54 INFO - PROCESS | 18426 | RSS: Main: 155242496 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | __start_tp_report 23:16:54 INFO - PROCESS | 18426 | _x_x_mozilla_page_load 23:16:54 INFO - PROCESS | 18426 | _x_x_mozilla_page_load_details 23:16:54 INFO - PROCESS | 18426 | |i|pagename|runs| 23:16:54 INFO - PROCESS | 18426 | |0;dojo.html;3163.066895265896;3199.1202419334663;3226.2095786532273;3250.6911843374887;3251.561039259699;5176.50823171799;5327.200919862018;5340.893182136359;5360.946390536098;5364.1149210380245;5224.138017227153;5327.760250788714;5346.973265133669;5380.381256155543;5429.131338985753;4598.517155698637;4874.756262186894;4938.08645400602;4972.2044472884345;5017.523335283142;4769.451513075988;4881.12139814835;4930.137225985456;4953.380827396575;4975.925361119586;4778.068276686047;4812.181929072049;4918.8278410255825;4919.2867034279825;4945.23348880924;4819.566239038469;4820.39745031871;4823.831165909176;4877.780499877514;4882.731449770261;4754.358161648168;4845.224764037737;4852.8544143675745;4853;4868.391451068616;5081.187010078406;5106.412762532282;5110.361204849394;5111.565516931066;5114.59083273341;5704.885902281972;5742.397048309909;5774.220480235156;5774.797882074149;5779.624324418899;5623.522000629951;5706.885862282773;5739.655620662774;5749.223854779635;5781.971090144564;5382.838514844561;5400.540954018913;5408.648437851527;5409.161579955153;5438.619296649236;5894.498967587718;5896.056630939029;5896.587238893237;5897.734601942923;5907.261592300963;5542.695151766691;5587.804426845122;5654.85862853432;5684.459976302244;5694.06048002075;5764.365919748824;5769.07694768835;5771.480566748929;5793.855153621109;5810.1865738796605;5693.772249109989;5736.598438109292;5745.3105627324985;5759.22250496186;5761.596688231867;5523.447655234525;5638.718064096861;5757.827265182052;5791.986402379567;5901.793437229677;4457.3091170868565;4459.397981272552;4465.218586747306;4474.552544745565;4518.073794872043;3981.5620281768984;3992.161646054303;3993.121513267077;4034.8789536313957;4043.838246470167;5215.817446389358;5325.334333208349;5373.946260537423;5410.08028635133;5472.26124473199;5700.401457846866;5717.04702266198;5724.284464441945;5726.685032323262;5750.971245143747;5233.450487698813;5392.137258038695;5410.945890541123;5421.376541697755;5437.728113594384;4870.245112007645;4907.9263811042865;4963.478834722302;4965.652404331662;5004.9499505005215;5961.433663801932;5980.641161530322;5995.400459954057;6006.978813601698;6018.398160183947;5620.634658747168;5727.455891690282;5783.363829978614;5827.621204621686;5835.79574714883;6046.486048685911;6080.604760690541;6097.119178184023;6103.580118178587;6120.663363515049;5654.349749778664;5657.264555607745;5720.08478643415;5735.168400581976;5769.423057694365;5640.069388551007;5741.425857414392;5765.971170144123;5844.912326315194;5857.209276747584;5695;5701.971490142523;5702.942970570407;5708.286464191976;5725.484706376446;5677.517411019944;5679.318481782213;5679.37526872052;5682.403347648603;5692.800751973661;5393.514583687487;5478.424765399575;5550.47270509301;5570.916436253378;5652.406497317723;5618.157276408571;5652.152177173457;5659.85850353728;5669.31968163823;5680.318361796611;5487.972560137174;5536.197251398444;5551.389347171727;5556.16657501378;5561.8609534760335 23:16:54 INFO - PROCESS | 18426 | |1;ext.html;511.39655206855895;514.6500379741776;533.9305890234269;535.3361831329152;541.4342012596836;27636.585451218223;28555.57166642502;34934.47598286021;35037.124071898215;35108.297834043304;37760.43359349612;38952.61047389525;39052.02369940753;39076.60923390765;39175.41236881442;8312.709055183055;8374.790630234233;8402.873956890651;8462.957685211566;8499.150084991514;8209.384296177781;8302.252797248246;8378.245957863792;8404.873926891101;8405.663773449085;8367.916320836775;8508.659653613848;8652.48085114892;8674.826503469927;8696.521691307007;8477;8511.957440212791;8532.933383327085;8575.785605359853;8579.785505362353;8432.409731318749;8508;8520.957395212985;8544.871826922601;8553.615087321023;38841.611583884085;39869.20261594781;40620.59379406198;40747;40861.79569102165;3119.610048743907;3181.4114388837907;3183.5861338025907;3385.306012267479;3797.2215695782575;28483;28646.427071458664;28788.280292992633;29192;29502.26244343887;3698.8335524901177;3868.535775706905;3948.763074215556;4069.552349241581;4174.853880114212;266772;266787;268572;268855;269211;53866.73066634642;55100;60488.395116049156;60590.39409605847;60840.69579652162;64469;64684.44235567089;65517;65726.67136664285;65800.67099664471;15486.922565387102;15719.607009824616;15746.055236685836;15854.9207253963;15951.920240398724;11382.487788049557;11423.371714555613;11469;11521.596744113916;11542.94228528852;19453.124609392613;19599.804001959797;19746.802531974783;19826.801731982785;20228.696569551175;7699.538027718243;7701.191374905667;7714.498557593738;7876.72431464896;7940.205979402129;834.2991886815105;911.1389736698889;957.8132264208551;1062.7893980520498;1117.8211486162174;30173.698263017526;31940.201494962348;32025.359492810014;32139.839300803345;32429.51355729666;969.1132613658424;982.4694664880884;1003.8996100389902;1030.2736570717625;1064.3454275620438;13137.934310328386;13147.934260328637;13164.539241126515;13390.93304533471;15343.232838358037;387767;388745;390386;396625;397812;52763;52914.206286905726;53141;53336.46663533393;53594;1168.1297433412162;1173.730042090324;1177.840991466141;1183.355071486042;1278.239447528719;28732.85633571819;29413.70586294195;29554.261143471584;29567.85216073992;29913.401731965674;45992.54007460016;46165.30752038655;46442.535574645175;46814.063718724756;46897.296540552605;309648;310024;310378;311194;311482;31490.055298341085;39932;40839.324460797485;41073.58926410697;41242.38136428016;36656.63343366532;37425.62574374222;37837.24325513529;38148.42777358397;38601.42097868591;31457.21356966094;31913.36173276475;32300.515492267165;32594.83702581472;32594.8813327133;32618.184545386554;33352.66647333495;35632.82183589169;36574.817125914204;38261.808690956364;31307.686923131387;32131.196720082185;32187.67812321847;32222.516662250553;32395;29490.852545737132;31684.36631267408;31913.680863192;32104.678953211103;32188.839055805507;24516.14193503219;24547.052595833055;25151.496970060864;25203.74796252014;25412.491750164525 23:16:54 INFO - PROCESS | 18426 | |2;jquery.html;348.6530901752756;350.07754566716693;350.6178265690397;350.72117666455176;352.7424979764772;15299.388024479007;16002.279897404616;16775.496735097968;17281.30874765008;17718.91140544297;10429.79140417193;10451.529681164347;10504.317219380735;12172.81740773889;15110.773338399906;4847.515248475151;4956.851294461172;4962.925556116661;5094.923576146361;5361.571074314045;5337.332833395825;5389.649672771257;5390.78436862525;5392.325959255093;5396.568274538047;4947.406311242638;5124.615653825959;5136;5329.813456529022;5359.946400536003;5112.565431938271;5129.743512824344;5130.56390206831;5177.922331165036;5197.922031169498;5192.792288308463;5193.766280517386;5193.948060519422;5203.479652034766;5204.245384419238;11757.706057348549;11923.761524769456;15872.920635396864;19114.23543058276;19184.520386990436;1906.9332573359866;1983.8512111591688;2039.5512987142804;2103.5372218111993;2141.5181584143597;8890.555472226362;9112.589933453011;9266.536673166314;11004.889951100467;12580.182288151242;1665.1757380096808;1666.9416570419978;1675.9413420530223;1715.0481482777054;1724.3792234795465;58457;61127.388726112615;62331.68834155845;63247.6837615809;63958.360416395706;9643.662471813454;9955.502224888727;10042.297039207186;10151.898481015243;10234.130098941525;13132.934335328262;13275.137116087424;13277.20336779777;13287.402066907012;13301.733965320833;7143.321384468467;7216.5670059796585;7273.23631018746;7371.594562299125;7467.626618669045;6724.260331363546;6753.898691519631;6778.966105169443;6793.728250870009;6806.5235433520165;4308.978455107705;4332.523422423531;4338;4340.327249276367;4401.40581021558;1788.5439213000602;1797.541626885162;1835.954101147482;1849.3804575467368;1855.2300795170124;834.0616806093145;900.5587262241586;904.1365495951351;939.8778158839307;970.1705042188968;15018.774718379233;16106.597335066483;16240.187990600423;16436.01383916945;17080.402185923434;822.8765685147276;897.8698088777221;915.2906497464385;920.521328908977;929.7907970706672;9215.354925155309;9838.9016109838;9880.604775809032;9919.355241909252;9965.750856228653;509641;510794;515330;520270;533457;78224.60887695524;78863;81169.59415202886;82005.17994820095;82575.58712206401;1078.9190810689072;1111.7053980694961;1112.165875593305;1114.2311804854623;1125.1336470917347;15350.309236084626;15755.606109847346;15807.532795608005;15810.920945395199;15977.68044639124;34891;35149.66078222565;35179;36261.456078159375;36569.45145822762;46075.539244608466;49000.50999490102;49240.753796232224;49318.50681493283;51169.23246151385;8980.86528702057;9291.48896810682;9478.95260523693;9492.05079492073;9569;11042.17183711241;11150.330980141218;11199.60801371948;11215.719607009563;12188.695282618006;13202.471901123847;13335.599932002055;13755.31223438844;14043.929780351033;14187.432502699776;13485.528006519724;13567.185968841506;13753.931230343784;14081.436742530184;14228.857711422754;13513.594592161857;13893;14004.78992815129;14053.508127215498;14630.414783408545;9350.485723285285;9383.953080234554;9538.380005299634;9773.755656108655;9786.41281523111;9848.950755246178;10104.69685909424;12327.260364378166;12879.678008049874;12910.160839545399 23:16:54 INFO - PROCESS | 18426 | |3;mootools.html;1337.6856438736893;1344.8453427855795;1432.326806400992;1676.011093597239;1683.8989660620357;7524.435667324952;7528.661210245538;7604.581748003858;7634.389248860085;7649.694012239511;7584.620768961558;7638.312551870331;7647;7700.268474494928;7724.42066844986;2176.0969197782943;2217.9001944912516;2252.2905284835224;2252.9211477598287;2252.988735056331;2221.833362497811;2260.4348912771807;2261.3442101790542;2262.2082271205113;2284.132029828671;2209.3924170852983;2229.4092065602626;2254.9323520294415;2281.74900760917;2284.7715228477186;2187;2195.714557107574;2229.1529218896876;2231.9107235710553;2237.2057919438525;2254.5152792149765;2295.9540809183723;2301.390131615123;2302.3553405046614;2305.5965206089036;7547.245275472464;7575.962120189419;7629.732959346452;7649.464537482379;7654;4483.529229430928;4556.111558246143;4595.172868883599;4597.310403439477;4614.446266448014;2983.4331477019296;2993.266649670845;3082.059971708628;3101.1006808025645;3112.937741245185;974.6929717139078;994.9303548751591;1003.1573478278208;1012.4026824173699;1051.978960420795;10273.12678422335;10326.22553308505;10368.377897326109;10401.479926003669;10413.79172416555;4131.855385061508;4157.293260145782;4181.226473102425;4201.45381100455;4235.597618226264;8255.628496717662;8260.926079609733;8280.792980175544;8284;8323.916760832435;4570.611498022706;4630.490646028934;4640.257558790578;4651.534846515321;4664.813407463664;4252.85115020979;4280.89297767552;4283.2932566126865;4302.075053863435;4302.720323179046;3394.372041172391;3411.7611767176554;3412.1981334386896;3421.2131209821882;3445.1903802606366;1083.4474418046852;1099.5925215723726;1102.8897110288906;1111.0556027376665;1112.955481780736;974.6880998080546;977.4819345746765;1083.864516935383;1134.1066179391364;1294.9805752913715;6956.930430695729;7014.824629384306;7030.226675065739;7036.261192574808;7043.225245223021;1197.874223206568;1211.1340391620033;1211.5638370186725;1219.231883913129;1241.3358853013594;5660.433956604389;5696.344920334214;5710.8001219957105;5744.4829965303325;5745.569082318842;8982.236509896602;9186.356955013085;9239.861402078974;9243.676471323606;9263.212626926652;8945.418547794372;8968.23769979559;8999.32505062123;9067.68263110788;9142.908570914205;6610;6878.518503704692;6882.931170688229;6907.274736152732;6916.170059592881;6810.353016463428;6840.384365406938;6842.110525631636;6886.380225779503;6901;7737.613119344122;7835.216478352119;8046.637901294573;8057.476264042818;8097.676092956452;8048.356131509582;8050.476719013245;8152.062512811102;8159.306458951056;8175.67297308101;6498.220213574401;6537.640429776408;6538.705758240795;6542.34576542361;6556.377144171296;7032.437405007485;7123.643817809192;7150.892736608851;7216.061911951622;7274.381677557255;6627.602343859384;6760.18877734675;6853.451723862178;6922.7923162305215;7018.508704390643;6220.346863579259;6244.500439964882;6582.17722784652;6586.209654841449;6693.832654183684;6662.6335551545135;6750.32496750321;6756.662166891734;6766.492513061442;6771.52599318043;6152.169457123231;6155.507559395327;6164.7842325518395;6189.288231853395;6201.782937597162;5931.317898441562;6064.727087280995;6066.757329706762;6076.665783381956;6160.691965401802 23:16:54 INFO - PROCESS | 18426 | |4;prototype.html;324.7710364193244;336.88040745535335;338.5176124023266;344.28045385145043;346.5840990811026;6198.4731297839735;6235.345288744679;6261.311255761868;6265.18552588164;6363.522735794822;6171.598846075002;6185.721642526086;6237.625742455444;6237.906431403532;6255.718492667829;4704.717716936986;4737.90524189516;4758.476567577563;4765.42814862217;4766.499517550659;4734.928976065362;4741.075490279397;4762.6428017898625;4764.118638051954;4772.451168115659;4594.931076033862;4634.884127896813;4679.321498382749;4687.718736875781;4696.5538273864095;4654.5810877020895;4661.648122044603;4671.953280467186;4692.131955588193;4717;4085.5710150434074;4088.325426304656;4092.386142078682;4092.611201935841;4098.938515922264;5819.6508209507565;5828.921649494857;5841.153032810261;5890.410958904118;5919.437653422918;5288.497592728723;5453.454654534515;5468.261784659099;5487.780488780444;5499.917501237484;6500.057491663682;6546.0835483032415;6553.3446655334565;6562.1469209003;6569.737210511574;4507.076049409863;4507.864764057083;4524.570165834273;4526.502084770706;4530.478994915594;6732.057511948331;6778.762743303961;6798.728050878009;6863.931360686429;6869.587824730532;4631.143238500821;4634.21218392874;4635.327877457749;4637.976810115928;4647.512011238771;4627.259638457898;4659.184642687584;4669.859904202879;4676.906461870812;4686.95313046872;5410.594205434608;5428.0500912340185;5430.321209848769;5436.945630543644;5446.863828404243;6452.354764523604;6569.441597464173;6584.30864759203;6598;6607.339266073354;1639.7950256217973;1660.4769497608215;1662.6259091704512;1665.5836040989752;1665.841745034244;1086.7717779266284;1099.8616431992848;1103.586155191803;1123.2193625430248;1129.316763358173;6264.749410023639;6268.310485846553;6271.4669253114;6274.2784579773;6282.183316168869;6736.157980252468;6767.898481522781;6770.763023294161;6770.763023294161;6786.59280443175;6164.630122192682;6203.4416902479;6268.185135932391;6358.523110766711;6368.77709280173;3659.7438179327723;3683.8710645127826;3697.186618943828;3705.184859330943;3708.517892673935;7170.749023784142;7173.10336207974;7174.856502869913;7175.35421812029;7179.210286868441;6886.552374095668;7034.929650703428;7042.401395881305;7043.542169759052;7073.363397294268;7176;7178.5692858428665;7181.784546463719;7183.245759195313;7200.675969581277;6762.797116086525;6764.627945463047;6827.078344423439;6833.145856767904;6835.624040677811;6609.768658096944;6694.765683201064;6711.295313991961;6733.966330168318;6755.560888542229;7160.248173941661;7214.567125972459;7227.421806255382;7255.310745479171;7265.854682906418;4603.033362993809;4639.907201855876;4640.350350950937;4646.767661616973;4648.1168577970075;6748.223954245128;6817.488688348294;6876.106106206161;6887.827804304932;6896.551724137915;6688.996650502463;6700.329967003261;6701.262861085179;6701.966490167518;6702.564333318319;6518.120053792867;6598.769043083469;6641.734330626914;6649.767258145942;6666.966665166643;6514.1531600891585;6525.249596296487;6539.509536784855;6562.343765623399;6568;6625.834354140992;6639.933600663931;6677.76627818024;6679.83300417474;6733.360330768569;6427.3251308611925;6480.157579514633;6483.384078512534;6525.0538671893255;6534 23:16:54 INFO - PROCESS | 18426 | |5;yui.html;706.4842664854652;714.9642517874105;721.6428511327023;757.8825282081277;788.7357735158722;12471.314077725721;12823.871761282384;12846.743065138691;12897.742045159092;12930.741385172303;11850.288982661046;11871.28772273662;11926.28442293463;11972.22180558263;11983.340916249603;759.692324608533;774.2876553570728;795.9602019899011;796.9402294827883;799.0930294116191;749.7338444852081;750.0886422996059;775.8487095016474;783.4594130050276;803.1406395157184;779.1195948578098;783.2519943454016;790.7588185603389;791.2957467853615;796.4026979765176;803.116571771053;803.8954935858301;815.2662603656697;816.6161903905185;818.1409520004001;568.2811243776625;577.125654633231;580.5413723158699;583.6731430398947;589.7198830555494;13163.144395614254;13210.207387556682;13255.270960097287;13285.734285314336;13342.399592018382;10997.175211859143;11213.831792523119;11310.717232069183;11535.826962595567;11612.535498580046;9400.388974716621;9446.763830904214;9517.476538790364;9521.714348569554;9539.47532885691;3424.4007298722872;3429.3655673700446;3468.739844511672;3490.5985811631735;3495.3358861816173;46252.30621540713;46473.535264647595;46514.767426162995;46555.30167047497;46695.5330446698;8861.379703420827;9059.501727405055;9192.586333615003;9213.078692130734;9312.953435232781;16842.326306947583;16861.915690421716;16877.40929067477;16895.746563801553;16905.49283521496;6760.560563563451;6919.861602767916;6943.652817359111;7029.22678505364;7261.636918154071;3916.8629097981434;3964.1873415949663;3990.660793832557;4010.578889216649;4044.090079732037;2948.8083274587084;2995.670476247611;2999.3401451680597;2999.610050693396;3002.6697063323018;517.3817288340472;546.131650675428;547.6905548365161;548.3502050070599;559.2729451712758;431.8877091956114;525.3538148077886;546.9808556700497;552.2958228259002;555.4528789142691;7320.450966177558;7392;7457.366123879531;7598.240175982468;7625.771226863203;446.6761597841604;451.4695233101093;457.9862604121882;465.2579136277632;512.9794808207705;3471.7743346682387;3473.3053389322235;3485.4249048906645;3508.7193024558483;3540.6105328413855;54578.72710636421;57771.13343299854;57821.71089144528;58095;58151.41848581545;44664.640243170375;47186.764066180134;47560.286595701094;47657.523424765306;47843.760781196564;43301.78349108297;43883.780581097526;44199;44416.11167776691;44590;7754;7773.519668633713;7887.40844436681;7996.3602911768085;8239.876401854097;34137.48793768145;34684.30631387408;34865;35085.64914350926;35599.28801424009;39768.403473947335;41021.58978410178;43479.04344304577;43583.78208109066;43788.781056094514;9383.249340052917;9783.41299522031;9784.168345690696;9970.651027214013;10061.949690251502;10136.797264054825;10225.539850706884;10374.844377334195;10448.68653940383;10524.789504209719;9574.617015319309;9928.553215105194;9978.451185184884;10018.899811001796;10029.348092373972;8492.872606910778;8494.447860889022;8573.785655358666;8623.956880215559;8635.093315201813;9201.907980920105;9581.568829402833;9588.232941364535;9873.358231714916;9934.403935763878;8372;8512.531810750468;8583.234829596724;8634.956825215833;8639.654413823377;7569.47013709035;7797.454178207471;7874;8213.712520061768;8377.329813614775 23:16:54 INFO - PROCESS | 18426 | __end_tp_report 23:16:54 INFO - PROCESS | 18426 | __start_cc_report 23:16:54 INFO - PROCESS | 18426 | _x_x_mozilla_cycle_collect,313 23:16:54 INFO - PROCESS | 18426 | __end_cc_report 23:16:54 INFO - PROCESS | 18426 | __startTimestamp1451805414651__endTimestamp 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | ------- Summary: start ------- 23:16:54 INFO - PROCESS | 18426 | Number of tests: 6 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#0] dojo.html Cycles:165 Average:5342.24 Median:5551.39 stddev:598.45 (10.8%) stddev-sans-first:575.35 23:16:54 INFO - PROCESS | 18426 | Values: 3163.1 3199.1 3226.2 3250.7 3251.6 5176.5 5327.2 5340.9 5360.9 5364.1 5224.1 5327.8 5347.0 5380.4 5429.1 4598.5 4874.8 4938.1 4972.2 5017.5 4769.5 4881.1 4930.1 4953.4 4975.9 4778.1 4812.2 4918.8 4919.3 4945.2 4819.6 4820.4 4823.8 4877.8 4882.7 4754.4 4845.2 4852.9 4853.0 4868.4 5081.2 5106.4 5110.4 5111.6 5114.6 5704.9 5742.4 5774.2 5774.8 5779.6 5623.5 5706.9 5739.7 5749.2 5782.0 5382.8 5400.5 5408.6 5409.2 5438.6 5894.5 5896.1 5896.6 5897.7 5907.3 5542.7 5587.8 5654.9 5684.5 5694.1 5764.4 5769.1 5771.5 5793.9 5810.2 5693.8 5736.6 5745.3 5759.2 5761.6 5523.4 5638.7 5757.8 5792.0 5901.8 4457.3 4459.4 4465.2 4474.6 4518.1 3981.6 3992.2 3993.1 4034.9 4043.8 5215.8 5325.3 5373.9 5410.1 5472.3 5700.4 5717.0 5724.3 5726.7 5751.0 5233.5 5392.1 5410.9 5421.4 5437.7 4870.2 4907.9 4963.5 4965.7 5004.9 5961.4 5980.6 5995.4 6007.0 6018.4 5620.6 5727.5 5783.4 5827.6 5835.8 6046.5 6080.6 6097.1 6103.6 6120.7 5654.3 5657.3 5720.1 5735.2 5769.4 5640.1 5741.4 5766.0 5844.9 5857.2 5695.0 5702.0 5702.9 5708.3 5725.5 5677.5 5679.3 5679.4 5682.4 5692.8 5393.5 5478.4 5550.5 5570.9 5652.4 5618.2 5652.2 5659.9 5669.3 5680.3 5488.0 5536.2 5551.4 5556.2 5561.9 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#1] ext.html Cycles:180 Average:48600.45 Median:20562.23 stddev:86190.39 (419.2%) stddev-sans-first:86356.55 23:16:54 INFO - PROCESS | 18426 | Values: 511.4 514.7 533.9 535.3 541.4 27636.6 28555.6 34934.5 35037.1 35108.3 37760.4 38952.6 39052.0 39076.6 39175.4 8312.7 8374.8 8402.9 8463.0 8499.2 8209.4 8302.3 8378.2 8404.9 8405.7 8367.9 8508.7 8652.5 8674.8 8696.5 8477.0 8512.0 8532.9 8575.8 8579.8 8432.4 8508.0 8521.0 8544.9 8553.6 38841.6 39869.2 40620.6 40747.0 40861.8 3119.6 3181.4 3183.6 3385.3 3797.2 28483.0 28646.4 28788.3 29192.0 29502.3 3698.8 3868.5 3948.8 4069.6 4174.9 266772.0 266787.0 268572.0 268855.0 269211.0 53866.7 55100.0 60488.4 60590.4 60840.7 64469.0 64684.4 65517.0 65726.7 65800.7 15486.9 15719.6 15746.1 15854.9 15951.9 11382.5 11423.4 11469.0 11521.6 11542.9 19453.1 19599.8 19746.8 19826.8 20228.7 7699.5 7701.2 7714.5 7876.7 7940.2 834.3 911.1 957.8 1062.8 1117.8 30173.7 31940.2 32025.4 32139.8 32429.5 969.1 982.5 1003.9 1030.3 1064.3 13137.9 13147.9 13164.5 13390.9 15343.2 387767.0 388745.0 390386.0 396625.0 397812.0 52763.0 52914.2 53141.0 53336.5 53594.0 1168.1 1173.7 1177.8 1183.4 1278.2 28732.9 29413.7 29554.3 29567.9 29913.4 45992.5 46165.3 46442.5 46814.1 46897.3 309648.0 310024.0 310378.0 311194.0 311482.0 31490.1 39932.0 40839.3 41073.6 41242.4 36656.6 37425.6 37837.2 38148.4 38601.4 31457.2 31913.4 32300.5 32594.8 32594.9 32618.2 33352.7 35632.8 36574.8 38261.8 31307.7 32131.2 32187.7 32222.5 32395.0 29490.9 31684.4 31913.7 32104.7 32188.8 24516.1 24547.1 25151.5 25203.7 25412.5 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#2] jquery.html Cycles:180 Average:27792.29 Median:4335.26 stddev:84796.79 (1956.0%) stddev-sans-first:85009.62 23:16:54 INFO - PROCESS | 18426 | Values: 348.7 350.1 350.6 350.7 352.7 15299.4 16002.3 16775.5 17281.3 17718.9 10429.8 10451.5 10504.3 12172.8 15110.8 4847.5 4956.9 4962.9 5094.9 5361.6 5337.3 5389.6 5390.8 5392.3 5396.6 4947.4 5124.6 5136.0 5329.8 5359.9 5112.6 5129.7 5130.6 5177.9 5197.9 5192.8 5193.8 5193.9 5203.5 5204.2 11757.7 11923.8 15872.9 19114.2 19184.5 1906.9 1983.9 2039.6 2103.5 2141.5 8890.6 9112.6 9266.5 11004.9 12580.2 1665.2 1666.9 1675.9 1715.0 1724.4 58457.0 61127.4 62331.7 63247.7 63958.4 9643.7 9955.5 10042.3 10151.9 10234.1 13132.9 13275.1 13277.2 13287.4 13301.7 7143.3 7216.6 7273.2 7371.6 7467.6 6724.3 6753.9 6779.0 6793.7 6806.5 4309.0 4332.5 4338.0 4340.3 4401.4 1788.5 1797.5 1836.0 1849.4 1855.2 834.1 900.6 904.1 939.9 970.2 15018.8 16106.6 16240.2 16436.0 17080.4 822.9 897.9 915.3 920.5 929.8 9215.4 9838.9 9880.6 9919.4 9965.8 509641.0 510794.0 515330.0 520270.0 533457.0 78224.6 78863.0 81169.6 82005.2 82575.6 1078.9 1111.7 1112.2 1114.2 1125.1 15350.3 15755.6 15807.5 15810.9 15977.7 34891.0 35149.7 35179.0 36261.5 36569.5 46075.5 49000.5 49240.8 49318.5 51169.2 8980.9 9291.5 9479.0 9492.1 9569.0 11042.2 11150.3 11199.6 11215.7 12188.7 13202.5 13335.6 13755.3 14043.9 14187.4 13485.5 13567.2 13753.9 14081.4 14228.9 13513.6 13893.0 14004.8 14053.5 14630.4 9350.5 9384.0 9538.4 9773.8 9786.4 9849.0 10104.7 12327.3 12879.7 12910.2 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#3] mootools.html Cycles:180 Average:5263.05 Median:6153.84 stddev:2684.40 (43.6%) stddev-sans-first:2675.71 23:16:54 INFO - PROCESS | 18426 | Values: 1337.7 1344.8 1432.3 1676.0 1683.9 7524.4 7528.7 7604.6 7634.4 7649.7 7584.6 7638.3 7647.0 7700.3 7724.4 2176.1 2217.9 2252.3 2252.9 2253.0 2221.8 2260.4 2261.3 2262.2 2284.1 2209.4 2229.4 2254.9 2281.7 2284.8 2187.0 2195.7 2229.2 2231.9 2237.2 2254.5 2296.0 2301.4 2302.4 2305.6 7547.2 7576.0 7629.7 7649.5 7654.0 4483.5 4556.1 4595.2 4597.3 4614.4 2983.4 2993.3 3082.1 3101.1 3112.9 974.7 994.9 1003.2 1012.4 1052.0 10273.1 10326.2 10368.4 10401.5 10413.8 4131.9 4157.3 4181.2 4201.5 4235.6 8255.6 8260.9 8280.8 8284.0 8323.9 4570.6 4630.5 4640.3 4651.5 4664.8 4252.9 4280.9 4283.3 4302.1 4302.7 3394.4 3411.8 3412.2 3421.2 3445.2 1083.4 1099.6 1102.9 1111.1 1113.0 974.7 977.5 1083.9 1134.1 1295.0 6956.9 7014.8 7030.2 7036.3 7043.2 1197.9 1211.1 1211.6 1219.2 1241.3 5660.4 5696.3 5710.8 5744.5 5745.6 8982.2 9186.4 9239.9 9243.7 9263.2 8945.4 8968.2 8999.3 9067.7 9142.9 6610.0 6878.5 6882.9 6907.3 6916.2 6810.4 6840.4 6842.1 6886.4 6901.0 7737.6 7835.2 8046.6 8057.5 8097.7 8048.4 8050.5 8152.1 8159.3 8175.7 6498.2 6537.6 6538.7 6542.3 6556.4 7032.4 7123.6 7150.9 7216.1 7274.4 6627.6 6760.2 6853.5 6922.8 7018.5 6220.3 6244.5 6582.2 6586.2 6693.8 6662.6 6750.3 6756.7 6766.5 6771.5 6152.2 6155.5 6164.8 6189.3 6201.8 5931.3 6064.7 6066.8 6076.7 6160.7 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#4] prototype.html Cycles:180 Average:5516.47 Median:6264.97 stddev:1684.56 (26.9%) stddev-sans-first:1643.60 23:16:54 INFO - PROCESS | 18426 | Values: 324.8 336.9 338.5 344.3 346.6 6198.5 6235.3 6261.3 6265.2 6363.5 6171.6 6185.7 6237.6 6237.9 6255.7 4704.7 4737.9 4758.5 4765.4 4766.5 4734.9 4741.1 4762.6 4764.1 4772.5 4594.9 4634.9 4679.3 4687.7 4696.6 4654.6 4661.6 4672.0 4692.1 4717.0 4085.6 4088.3 4092.4 4092.6 4098.9 5819.7 5828.9 5841.2 5890.4 5919.4 5288.5 5453.5 5468.3 5487.8 5499.9 6500.1 6546.1 6553.3 6562.1 6569.7 4507.1 4507.9 4524.6 4526.5 4530.5 6732.1 6778.8 6798.7 6863.9 6869.6 4631.1 4634.2 4635.3 4638.0 4647.5 4627.3 4659.2 4669.9 4676.9 4687.0 5410.6 5428.1 5430.3 5436.9 5446.9 6452.4 6569.4 6584.3 6598.0 6607.3 1639.8 1660.5 1662.6 1665.6 1665.8 1086.8 1099.9 1103.6 1123.2 1129.3 6264.7 6268.3 6271.5 6274.3 6282.2 6736.2 6767.9 6770.8 6770.8 6786.6 6164.6 6203.4 6268.2 6358.5 6368.8 3659.7 3683.9 3697.2 3705.2 3708.5 7170.7 7173.1 7174.9 7175.4 7179.2 6886.6 7034.9 7042.4 7043.5 7073.4 7176.0 7178.6 7181.8 7183.2 7200.7 6762.8 6764.6 6827.1 6833.1 6835.6 6609.8 6694.8 6711.3 6734.0 6755.6 7160.2 7214.6 7227.4 7255.3 7265.9 4603.0 4639.9 4640.4 4646.8 4648.1 6748.2 6817.5 6876.1 6887.8 6896.6 6689.0 6700.3 6701.3 6702.0 6702.6 6518.1 6598.8 6641.7 6649.8 6667.0 6514.2 6525.2 6539.5 6562.3 6568.0 6625.8 6639.9 6677.8 6679.8 6733.4 6427.3 6480.2 6483.4 6525.1 6534.0 23:16:54 INFO - PROCESS | 18426 | 23:16:54 INFO - PROCESS | 18426 | [#5] yui.html Cycles:180 Average:12886.86 Median:550.32 stddev:15429.09 (2803.6%) stddev-sans-first:15445.26 23:16:54 INFO - PROCESS | 18426 | Values: 706.5 715.0 721.6 757.9 788.7 12471.3 12823.9 12846.7 12897.7 12930.7 11850.3 11871.3 11926.3 11972.2 11983.3 759.7 774.3 796.0 796.9 799.1 749.7 750.1 775.8 783.5 803.1 779.1 783.3 790.8 791.3 796.4 803.1 803.9 815.3 816.6 818.1 568.3 577.1 580.5 583.7 589.7 13163.1 13210.2 13255.3 13285.7 13342.4 10997.2 11213.8 11310.7 11535.8 11612.5 9400.4 9446.8 9517.5 9521.7 9539.5 3424.4 3429.4 3468.7 3490.6 3495.3 46252.3 46473.5 46514.8 46555.3 46695.5 8861.4 9059.5 9192.6 9213.1 9313.0 16842.3 16861.9 16877.4 16895.7 16905.5 6760.6 6919.9 6943.7 7029.2 7261.6 3916.9 3964.2 3990.7 4010.6 4044.1 2948.8 2995.7 2999.3 2999.6 3002.7 517.4 546.1 547.7 548.4 559.3 431.9 525.4 547.0 552.3 555.5 7320.5 7392.0 7457.4 7598.2 7625.8 446.7 451.5 458.0 465.3 513.0 3471.8 3473.3 3485.4 3508.7 3540.6 54578.7 57771.1 57821.7 58095.0 58151.4 44664.6 47186.8 47560.3 47657.5 47843.8 43301.8 43883.8 44199.0 44416.1 44590.0 7754.0 7773.5 7887.4 7996.4 8239.9 34137.5 34684.3 34865.0 35085.6 35599.3 39768.4 41021.6 43479.0 43583.8 43788.8 9383.2 9783.4 9784.2 9970.7 10061.9 10136.8 10225.5 10374.8 10448.7 10524.8 9574.6 9928.6 9978.5 10018.9 10029.3 8492.9 8494.4 8573.8 8624.0 8635.1 9201.9 9581.6 9588.2 9873.4 9934.4 8372.0 8512.5 8583.2 8635.0 8639.7 7569.5 7797.5 7874.0 8213.7 8377.3 23:16:54 INFO - PROCESS | 18426 | -------- Summary: end -------- 23:16:54 INFO - PROCESS | 18426 | 23:16:55 INFO - TEST-INFO | 18426: exit 0 23:16:55 INFO - TEST-OK | dromaeo_css | took 1111045ms 23:16:55 INFO - TEST-START | kraken 23:16:55 INFO - operating with platform_type : linux_ 23:16:55 INFO - Initialising browser for kraken test... 23:16:55 INFO - TEST-INFO | started process 18669 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeZXXfZ/profile http://localhost:59959/getInfo.html) 23:17:00 INFO - PROCESS | 18669 | __metrics Screen width/height:1600/1200 23:17:00 INFO - PROCESS | 18669 | colorDepth:24 23:17:00 INFO - PROCESS | 18669 | Browser inner width/height: 1024/697 23:17:00 INFO - PROCESS | 18669 | __metrics 23:17:00 INFO - PROCESS | 18669 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:17:00 INFO - PROCESS | 18669 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 23:17:01 INFO - TEST-INFO | 18669: exit 0 23:17:01 INFO - Browser initialized. 23:17:01 INFO - Running cycle 1/1 for kraken test... 23:17:01 INFO - TEST-INFO | started process 18788 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeZXXfZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:13 INFO - PROCESS | 18788 | RSS: Main: 150396928 23:17:13 INFO - PROCESS | 18788 | 23:17:15 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59959/tests/kraken/driver.html?testName=audio-beat-detection) 23:17:16 INFO - PROCESS | 18788 | RSS: Main: 151994368 23:17:16 INFO - PROCESS | 18788 | 23:17:19 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59959/tests/kraken/driver.html?testName=audio-dft) 23:17:19 INFO - PROCESS | 18788 | RSS: Main: 151724032 23:17:19 INFO - PROCESS | 18788 | 23:17:22 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59959/tests/kraken/driver.html?testName=audio-fft) 23:17:23 INFO - PROCESS | 18788 | RSS: Main: 145346560 23:17:23 INFO - PROCESS | 18788 | 23:17:25 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59959/tests/kraken/driver.html?testName=audio-oscillator) 23:17:25 INFO - PROCESS | 18788 | RSS: Main: 146116608 23:17:25 INFO - PROCESS | 18788 | 23:17:28 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59959/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:17:28 INFO - PROCESS | 18788 | RSS: Main: 146423808 23:17:28 INFO - PROCESS | 18788 | 23:17:31 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59959/tests/kraken/driver.html?testName=imaging-darkroom) 23:17:32 INFO - PROCESS | 18788 | RSS: Main: 146534400 23:17:32 INFO - PROCESS | 18788 | 23:17:35 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59959/tests/kraken/driver.html?testName=imaging-desaturate) 23:17:36 INFO - PROCESS | 18788 | RSS: Main: 147415040 23:17:36 INFO - PROCESS | 18788 | 23:17:38 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59959/tests/kraken/driver.html?testName=json-parse-financial) 23:17:39 INFO - PROCESS | 18788 | RSS: Main: 148279296 23:17:39 INFO - PROCESS | 18788 | 23:17:41 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59959/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:17:41 INFO - PROCESS | 18788 | RSS: Main: 148262912 23:17:41 INFO - PROCESS | 18788 | 23:17:43 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:17:44 INFO - PROCESS | 18788 | RSS: Main: 148525056 23:17:44 INFO - PROCESS | 18788 | 23:17:46 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:17:46 INFO - PROCESS | 18788 | RSS: Main: 148692992 23:17:46 INFO - PROCESS | 18788 | 23:17:49 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:17:49 INFO - PROCESS | 18788 | RSS: Main: 148779008 23:17:49 INFO - PROCESS | 18788 | 23:17:52 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:17:53 INFO - PROCESS | 18788 | RSS: Main: 147382272 23:17:53 INFO - PROCESS | 18788 | 23:17:54 INFO - PROCESS | 18788 | Cycle 1(1): loaded http://localhost:59959/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59959/tests/kraken/driver.html?testName=ai-astar) 23:17:55 INFO - PROCESS | 18788 | RSS: Main: 149827584 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | __start_tp_report 23:17:55 INFO - PROCESS | 18788 | _x_x_mozilla_page_load 23:17:55 INFO - PROCESS | 18788 | _x_x_mozilla_page_load_details 23:17:55 INFO - PROCESS | 18788 | |i|pagename|runs| 23:17:55 INFO - PROCESS | 18788 | |0;ai-astar;112;108;114;113;113;123;112;110;112;112 23:17:55 INFO - PROCESS | 18788 | |1;audio-beat-detection;156;127;151;158;130;146;154;131;161;128 23:17:55 INFO - PROCESS | 18788 | |2;audio-dft;164;162;163;164;164;166;161;167;169;163 23:17:55 INFO - PROCESS | 18788 | |3;audio-fft;73;73;72;72;73;150;71;75;74;71 23:17:55 INFO - PROCESS | 18788 | |4;audio-oscillator;97;98;146;97;97;101;151;96;96;153 23:17:55 INFO - PROCESS | 18788 | |5;imaging-gaussian-blur;110;111;110;110;110;109;110;110;111;110 23:17:55 INFO - PROCESS | 18788 | |6;imaging-darkroom;148;148;148;148;147;147;147;151;149;147 23:17:55 INFO - PROCESS | 18788 | |7;imaging-desaturate;96;97;96;97;96;97;96;96;97;96 23:17:55 INFO - PROCESS | 18788 | |8;json-parse-financial;69;60;58;58;60;57;61;57;59;56 23:17:55 INFO - PROCESS | 18788 | |9;json-stringify-tinderbox;68;68;68;68;69;68;69;67;67;68 23:17:55 INFO - PROCESS | 18788 | |10;stanford-crypto-aes;86;93;87;87;87;87;87;90;87;86 23:17:55 INFO - PROCESS | 18788 | |11;stanford-crypto-ccm;128;123;119;129;123;121;134;125;123;122 23:17:55 INFO - PROCESS | 18788 | |12;stanford-crypto-pbkdf2;144;153;157;156;157;151;153;153;154;139 23:17:55 INFO - PROCESS | 18788 | |13;stanford-crypto-sha256-iterative;72;70;68;70;71;68;68;72;69;68 23:17:55 INFO - PROCESS | 18788 | __end_tp_report 23:17:55 INFO - PROCESS | 18788 | __start_cc_report 23:17:55 INFO - PROCESS | 18788 | _x_x_mozilla_cycle_collect,742 23:17:55 INFO - PROCESS | 18788 | __end_cc_report 23:17:55 INFO - PROCESS | 18788 | __startTimestamp1451805475114__endTimestamp 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | ------- Summary: start ------- 23:17:55 INFO - PROCESS | 18788 | Number of tests: 14 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#0] ai-astar Cycles:10 Average:112.90 Median:112.50 stddev:3.93 (3.5%) stddev-sans-first:4.15 23:17:55 INFO - PROCESS | 18788 | Values: 112.0 108.0 114.0 113.0 113.0 123.0 112.0 110.0 112.0 112.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:152.50 stddev:13.71 (9.0%) stddev-sans-first:13.86 23:17:55 INFO - PROCESS | 18788 | Values: 156.0 127.0 151.0 158.0 130.0 146.0 154.0 131.0 161.0 128.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 23:17:55 INFO - PROCESS | 18788 | Values: 164.0 162.0 163.0 164.0 164.0 166.0 161.0 167.0 169.0 163.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#3] audio-fft Cycles:10 Average:80.40 Median:73.00 stddev:24.49 (33.5%) stddev-sans-first:25.83 23:17:55 INFO - PROCESS | 18788 | Values: 73.0 73.0 72.0 72.0 73.0 150.0 71.0 75.0 74.0 71.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#4] audio-oscillator Cycles:10 Average:113.20 Median:96.50 stddev:25.49 (26.4%) stddev-sans-first:26.35 23:17:55 INFO - PROCESS | 18788 | Values: 97.0 98.0 146.0 97.0 97.0 101.0 151.0 96.0 96.0 153.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 23:17:55 INFO - PROCESS | 18788 | Values: 110.0 111.0 110.0 110.0 110.0 109.0 110.0 110.0 111.0 110.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 23:17:55 INFO - PROCESS | 18788 | Values: 148.0 148.0 148.0 148.0 147.0 147.0 147.0 151.0 149.0 147.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:17:55 INFO - PROCESS | 18788 | Values: 96.0 97.0 96.0 97.0 96.0 97.0 96.0 96.0 97.0 96.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.50 stddev:3.69 (6.2%) stddev-sans-first:1.67 23:17:55 INFO - PROCESS | 18788 | Values: 69.0 60.0 58.0 58.0 60.0 57.0 61.0 57.0 59.0 56.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 23:17:55 INFO - PROCESS | 18788 | Values: 68.0 68.0 68.0 68.0 69.0 68.0 69.0 67.0 67.0 68.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.00 stddev:2.16 (2.5%) stddev-sans-first:2.20 23:17:55 INFO - PROCESS | 18788 | Values: 86.0 93.0 87.0 87.0 87.0 87.0 87.0 90.0 87.0 86.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:124.00 stddev:4.45 (3.6%) stddev-sans-first:4.56 23:17:55 INFO - PROCESS | 18788 | Values: 128.0 123.0 119.0 129.0 123.0 121.0 134.0 125.0 123.0 122.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:153.50 stddev:5.83 (3.8%) stddev-sans-first:5.48 23:17:55 INFO - PROCESS | 18788 | Values: 144.0 153.0 157.0 156.0 157.0 151.0 153.0 153.0 154.0 139.0 23:17:55 INFO - PROCESS | 18788 | 23:17:55 INFO - PROCESS | 18788 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.65 (2.4%) stddev-sans-first:1.50 23:17:55 INFO - PROCESS | 18788 | Values: 72.0 70.0 68.0 70.0 71.0 68.0 68.0 72.0 69.0 68.0 23:17:55 INFO - PROCESS | 18788 | -------- Summary: end -------- 23:17:55 INFO - PROCESS | 18788 | 23:17:56 INFO - TEST-INFO | 18788: exit 0 23:17:56 INFO - TEST-OK | kraken | took 60670ms 23:17:56 INFO - TEST-START | v8_7 23:17:56 INFO - operating with platform_type : linux_ 23:17:56 INFO - Initialising browser for v8_7 test... 23:17:56 INFO - TEST-INFO | started process 19077 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUY9hgv/profile http://localhost:59959/getInfo.html) 23:18:01 INFO - PROCESS | 19077 | __metrics Screen width/height:1600/1200 23:18:01 INFO - PROCESS | 19077 | colorDepth:24 23:18:01 INFO - PROCESS | 19077 | Browser inner width/height: 1024/697 23:18:01 INFO - PROCESS | 19077 | __metrics 23:18:01 INFO - PROCESS | 19077 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:18:02 INFO - TEST-INFO | 19077: exit 0 23:18:02 INFO - Browser initialized. 23:18:02 INFO - Running cycle 1/1 for v8_7 test... 23:18:02 INFO - TEST-INFO | started process 19203 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUY9hgv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:14 INFO - PROCESS | 19203 | RSS: Main: 150310912 23:18:14 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | Cycle 1(1): loaded http://localhost:59959/tests/v8_7/run.html (next: http://localhost:59959/tests/v8_7/run.html) 23:18:35 INFO - PROCESS | 19203 | RSS: Main: 145752064 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | __start_tp_report 23:18:35 INFO - PROCESS | 19203 | _x_x_mozilla_page_load 23:18:35 INFO - PROCESS | 19203 | _x_x_mozilla_page_load_details 23:18:35 INFO - PROCESS | 19203 | |i|pagename|runs| 23:18:35 INFO - PROCESS | 19203 | |0;Richards;171.2035610340695 23:18:35 INFO - PROCESS | 19203 | |1;DeltaBlue;188.28845791752966 23:18:35 INFO - PROCESS | 19203 | |2;Encrypt;279.87685418415896 23:18:35 INFO - PROCESS | 19203 | |3;Decrypt;5202.072538860104 23:18:35 INFO - PROCESS | 19203 | |4;RayTrace;1072.961373390558 23:18:35 INFO - PROCESS | 19203 | |5;Earley;725.6894049346879 23:18:35 INFO - PROCESS | 19203 | |6;Boyer;12243.90243902439 23:18:35 INFO - PROCESS | 19203 | |7;RegExp;24926.829268292684 23:18:35 INFO - PROCESS | 19203 | |8;Splay;588.9281507656066 23:18:35 INFO - PROCESS | 19203 | |9;NavierStokes;4840.579710144928 23:18:35 INFO - PROCESS | 19203 | __end_tp_report 23:18:35 INFO - PROCESS | 19203 | __startTimestamp1451805515989__endTimestamp 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | ------- Summary: start ------- 23:18:35 INFO - PROCESS | 19203 | Number of tests: 10 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#0] Richards Cycles:1 Average:171.20 Median:171.20 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 171.2 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#1] DeltaBlue Cycles:1 Average:188.29 Median:188.29 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 188.3 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#2] Encrypt Cycles:1 Average:279.88 Median:279.88 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 279.9 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 5202.1 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#4] RayTrace Cycles:1 Average:1072.96 Median:1072.96 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 1073.0 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#5] Earley Cycles:1 Average:725.69 Median:725.69 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 725.7 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 12243.9 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#7] RegExp Cycles:1 Average:24926.83 Median:24926.83 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 24926.8 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#8] Splay Cycles:1 Average:588.93 Median:588.93 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 588.9 23:18:35 INFO - PROCESS | 19203 | 23:18:35 INFO - PROCESS | 19203 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 23:18:35 INFO - PROCESS | 19203 | Values: 4840.6 23:18:35 INFO - PROCESS | 19203 | -------- Summary: end -------- 23:18:36 INFO - PROCESS | 19203 | 23:18:36 INFO - TEST-INFO | 19203: exit 0 23:18:36 INFO - TEST-OK | v8_7 | took 40813ms 23:18:36 INFO - SUITE-END | took 1212s 23:18:36 INFO - Completed test suite (00:20:12) 23:18:36 INFO - Working with test: dromaeo_css 23:18:36 INFO - Generating results file: dromaeo_css 23:18:36 INFO - Working with test: kraken 23:18:36 INFO - Generating results file: kraken 23:18:36 INFO - javascript benchmark 23:18:36 INFO - Working with test: v8_7 23:18:36 INFO - Generating results file: v8_7 23:18:36 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:18:37 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 23:18:37 INFO - process_Request line: dromaeo_css 6827.00 graph.html#tests=[[72,64,43]] 23:18:37 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:18:37 INFO - process_Request line: kraken 1530.70 graph.html#tests=[[232,64,43]] 23:18:37 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:18:37 INFO - process_Request line: v8_7 21340.83 graph.html#tests=[[230,64,43]] 23:18:37 INFO - RETURN: dromaeo_css: 6827.00 23:18:37 INFO - RETURN: kraken: 1530.70 23:18:37 INFO - RETURN: v8_7: 21340.83 23:18:37 INFO - javascript benchmark 23:18:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3163.066895265896, 3199.1202419334663, 3226.2095786532273, 3250.6911843374887, 3251.561039259699, 5176.50823171799, 5327.200919862018, 5340.893182136359, 5360.946390536098, 5364.1149210380245, 5224.138017227153, 5327.760250788714, 5346.973265133669, 5380.381256155543, 5429.131338985753, 4598.517155698637, 4874.756262186894, 4938.08645400602, 4972.2044472884345, 5017.523335283142, 4769.451513075988, 4881.12139814835, 4930.137225985456, 4953.380827396575, 4975.925361119586, 4778.068276686047, 4812.181929072049, 4918.8278410255825, 4919.2867034279825, 4945.23348880924, 4819.566239038469, 4820.39745031871, 4823.831165909176, 4877.780499877514, 4882.731449770261, 4754.358161648168, 4845.224764037737, 4852.8544143675745, 4853.0, 4868.391451068616, 5081.187010078406, 5106.412762532282, 5110.361204849394, 5111.565516931066, 5114.59083273341, 5704.885902281972, 5742.397048309909, 5774.220480235156, 5774.797882074149, 5779.624324418899, 5623.522000629951, 5706.885862282773, 5739.655620662774, 5749.223854779635, 5781.971090144564, 5382.838514844561, 5400.540954018913, 5408.648437851527, 5409.161579955153, 5438.619296649236, 5894.498967587718, 5896.056630939029, 5896.587238893237, 5897.734601942923, 5907.261592300963, 5542.695151766691, 5587.804426845122, 5654.85862853432, 5684.459976302244, 5694.06048002075, 5764.365919748824, 5769.07694768835, 5771.480566748929, 5793.855153621109, 5810.1865738796605, 5693.772249109989, 5736.598438109292, 5745.3105627324985, 5759.22250496186, 5761.596688231867, 5523.447655234525, 5638.718064096861, 5757.827265182052, 5791.986402379567, 5901.793437229677, 4457.3091170868565, 4459.397981272552, 4465.218586747306, 4474.552544745565, 4518.073794872043, 3981.5620281768984, 3992.161646054303, 3993.121513267077, 4034.8789536313957, 4043.838246470167, 5215.817446389358, 5325.334333208349, 5373.946260537423, 5410.08028635133, 5472.26124473199, 5700.401457846866, 5717.04702266198, 5724.284464441945, 5726.685032323262, 5750.971245143747, 5233.450487698813, 5392.137258038695, 5410.945890541123, 5421.376541697755, 5437.728113594384, 4870.245112007645, 4907.9263811042865, 4963.478834722302, 4965.652404331662, 5004.9499505005215, 5961.433663801932, 5980.641161530322, 5995.400459954057, 6006.978813601698, 6018.398160183947, 5620.634658747168, 5727.455891690282, 5783.363829978614, 5827.621204621686, 5835.79574714883, 6046.486048685911, 6080.604760690541, 6097.119178184023, 6103.580118178587, 6120.663363515049, 5654.349749778664, 5657.264555607745, 5720.08478643415, 5735.168400581976, 5769.423057694365, 5640.069388551007, 5741.425857414392, 5765.971170144123, 5844.912326315194, 5857.209276747584, 5695.0, 5701.971490142523, 5702.942970570407, 5708.286464191976, 5725.484706376446, 5677.517411019944, 5679.318481782213, 5679.37526872052, 5682.403347648603, 5692.800751973661, 5393.514583687487, 5478.424765399575, 5550.47270509301, 5570.916436253378, 5652.406497317723, 5618.157276408571, 5652.152177173457, 5659.85850353728, 5669.31968163823, 5680.318361796611, 5487.972560137174, 5536.197251398444, 5551.389347171727, 5556.16657501378, 5561.8609534760335], "unit": "score", "name": "dojo.html", "value": 5303.494401959461}, {"lowerIsBetter": false, "replicates": [511.39655206855895, 514.6500379741776, 533.9305890234269, 535.3361831329152, 541.4342012596836, 27636.585451218223, 28555.57166642502, 34934.47598286021, 35037.124071898215, 35108.297834043304, 37760.43359349612, 38952.61047389525, 39052.02369940753, 39076.60923390765, 39175.41236881442, 8312.709055183055, 8374.790630234233, 8402.873956890651, 8462.957685211566, 8499.150084991514, 8209.384296177781, 8302.252797248246, 8378.245957863792, 8404.873926891101, 8405.663773449085, 8367.916320836775, 8508.659653613848, 8652.48085114892, 8674.826503469927, 8696.521691307007, 8477.0, 8511.957440212791, 8532.933383327085, 8575.785605359853, 8579.785505362353, 8432.409731318749, 8508.0, 8520.957395212985, 8544.871826922601, 8553.615087321023, 38841.611583884085, 39869.20261594781, 40620.59379406198, 40747.0, 40861.79569102165, 3119.610048743907, 3181.4114388837907, 3183.5861338025907, 3385.306012267479, 3797.2215695782575, 28483.0, 28646.427071458664, 28788.280292992633, 29192.0, 29502.26244343887, 3698.8335524901177, 3868.535775706905, 3948.763074215556, 4069.552349241581, 4174.853880114212, 266772.0, 266787.0, 268572.0, 268855.0, 269211.0, 53866.73066634642, 55100.0, 60488.395116049156, 60590.39409605847, 60840.69579652162, 64469.0, 64684.44235567089, 65517.0, 65726.67136664285, 65800.67099664471, 15486.922565387102, 15719.607009824616, 15746.055236685836, 15854.9207253963, 15951.920240398724, 11382.487788049557, 11423.371714555613, 11469.0, 11521.596744113916, 11542.94228528852, 19453.124609392613, 19599.804001959797, 19746.802531974783, 19826.801731982785, 20228.696569551175, 7699.538027718243, 7701.191374905667, 7714.498557593738, 7876.72431464896, 7940.205979402129, 834.2991886815105, 911.1389736698889, 957.8132264208551, 1062.7893980520498, 1117.8211486162174, 30173.698263017526, 31940.201494962348, 32025.359492810014, 32139.839300803345, 32429.51355729666, 969.1132613658424, 982.4694664880884, 1003.8996100389902, 1030.2736570717625, 1064.3454275620438, 13137.934310328386, 13147.934260328637, 13164.539241126515, 13390.93304533471, 15343.232838358037, 387767.0, 388745.0, 390386.0, 396625.0, 397812.0, 52763.0, 52914.206286905726, 53141.0, 53336.46663533393, 53594.0, 1168.1297433412162, 1173.730042090324, 1177.840991466141, 1183.355071486042, 1278.239447528719, 28732.85633571819, 29413.70586294195, 29554.261143471584, 29567.85216073992, 29913.401731965674, 45992.54007460016, 46165.30752038655, 46442.535574645175, 46814.063718724756, 46897.296540552605, 309648.0, 310024.0, 310378.0, 311194.0, 311482.0, 31490.055298341085, 39932.0, 40839.324460797485, 41073.58926410697, 41242.38136428016, 36656.63343366532, 37425.62574374222, 37837.24325513529, 38148.42777358397, 38601.42097868591, 31457.21356966094, 31913.36173276475, 32300.515492267165, 32594.83702581472, 32594.8813327133, 32618.184545386554, 33352.66647333495, 35632.82183589169, 36574.817125914204, 38261.808690956364, 31307.686923131387, 32131.196720082185, 32187.67812321847, 32222.516662250553, 32395.0, 29490.852545737132, 31684.36631267408, 31913.680863192, 32104.678953211103, 32188.839055805507, 24516.14193503219, 24547.052595833055, 25151.496970060864, 25203.74796252014, 25412.491750164525], "unit": "score", "name": "ext.html", "value": 18217.596953485772}, {"lowerIsBetter": false, "replicates": [348.6530901752756, 350.07754566716693, 350.6178265690397, 350.72117666455176, 352.7424979764772, 15299.388024479007, 16002.279897404616, 16775.496735097968, 17281.30874765008, 17718.91140544297, 10429.79140417193, 10451.529681164347, 10504.317219380735, 12172.81740773889, 15110.773338399906, 4847.515248475151, 4956.851294461172, 4962.925556116661, 5094.923576146361, 5361.571074314045, 5337.332833395825, 5389.649672771257, 5390.78436862525, 5392.325959255093, 5396.568274538047, 4947.406311242638, 5124.615653825959, 5136.0, 5329.813456529022, 5359.946400536003, 5112.565431938271, 5129.743512824344, 5130.56390206831, 5177.922331165036, 5197.922031169498, 5192.792288308463, 5193.766280517386, 5193.948060519422, 5203.479652034766, 5204.245384419238, 11757.706057348549, 11923.761524769456, 15872.920635396864, 19114.23543058276, 19184.520386990436, 1906.9332573359866, 1983.8512111591688, 2039.5512987142804, 2103.5372218111993, 2141.5181584143597, 8890.555472226362, 9112.589933453011, 9266.536673166314, 11004.889951100467, 12580.182288151242, 1665.1757380096808, 1666.9416570419978, 1675.9413420530223, 1715.0481482777054, 1724.3792234795465, 58457.0, 61127.388726112615, 62331.68834155845, 63247.6837615809, 63958.360416395706, 9643.662471813454, 9955.502224888727, 10042.297039207186, 10151.898481015243, 10234.130098941525, 13132.934335328262, 13275.137116087424, 13277.20336779777, 13287.402066907012, 13301.733965320833, 7143.321384468467, 7216.5670059796585, 7273.23631018746, 7371.594562299125, 7467.626618669045, 6724.260331363546, 6753.898691519631, 6778.966105169443, 6793.728250870009, 6806.5235433520165, 4308.978455107705, 4332.523422423531, 4338.0, 4340.327249276367, 4401.40581021558, 1788.5439213000602, 1797.541626885162, 1835.954101147482, 1849.3804575467368, 1855.2300795170124, 834.0616806093145, 900.5587262241586, 904.1365495951351, 939.8778158839307, 970.1705042188968, 15018.774718379233, 16106.597335066483, 16240.187990600423, 16436.01383916945, 17080.402185923434, 822.8765685147276, 897.8698088777221, 915.2906497464385, 920.521328908977, 929.7907970706672, 9215.354925155309, 9838.9016109838, 9880.604775809032, 9919.355241909252, 9965.750856228653, 509641.0, 510794.0, 515330.0, 520270.0, 533457.0, 78224.60887695524, 78863.0, 81169.59415202886, 82005.17994820095, 82575.58712206401, 1078.9190810689072, 1111.7053980694961, 1112.165875593305, 1114.2311804854623, 1125.1336470917347, 15350.309236084626, 15755.606109847346, 15807.532795608005, 15810.920945395199, 15977.68044639124, 34891.0, 35149.66078222565, 35179.0, 36261.456078159375, 36569.45145822762, 46075.539244608466, 49000.50999490102, 49240.753796232224, 49318.50681493283, 51169.23246151385, 8980.86528702057, 9291.48896810682, 9478.95260523693, 9492.05079492073, 9569.0, 11042.17183711241, 11150.330980141218, 11199.60801371948, 11215.719607009563, 12188.695282618006, 13202.471901123847, 13335.599932002055, 13755.31223438844, 14043.929780351033, 14187.432502699776, 13485.528006519724, 13567.185968841506, 13753.931230343784, 14081.436742530184, 14228.857711422754, 13513.594592161857, 13893.0, 14004.78992815129, 14053.508127215498, 14630.414783408545, 9350.485723285285, 9383.953080234554, 9538.380005299634, 9773.755656108655, 9786.41281523111, 9848.950755246178, 10104.69685909424, 12327.260364378166, 12879.678008049874, 12910.160839545399], "unit": "score", "name": "jquery.html", "value": 8449.735376736415}, {"lowerIsBetter": false, "replicates": [1337.6856438736893, 1344.8453427855795, 1432.326806400992, 1676.011093597239, 1683.8989660620357, 7524.435667324952, 7528.661210245538, 7604.581748003858, 7634.389248860085, 7649.694012239511, 7584.620768961558, 7638.312551870331, 7647.0, 7700.268474494928, 7724.42066844986, 2176.0969197782943, 2217.9001944912516, 2252.2905284835224, 2252.9211477598287, 2252.988735056331, 2221.833362497811, 2260.4348912771807, 2261.3442101790542, 2262.2082271205113, 2284.132029828671, 2209.3924170852983, 2229.4092065602626, 2254.9323520294415, 2281.74900760917, 2284.7715228477186, 2187.0, 2195.714557107574, 2229.1529218896876, 2231.9107235710553, 2237.2057919438525, 2254.5152792149765, 2295.9540809183723, 2301.390131615123, 2302.3553405046614, 2305.5965206089036, 7547.245275472464, 7575.962120189419, 7629.732959346452, 7649.464537482379, 7654.0, 4483.529229430928, 4556.111558246143, 4595.172868883599, 4597.310403439477, 4614.446266448014, 2983.4331477019296, 2993.266649670845, 3082.059971708628, 3101.1006808025645, 3112.937741245185, 974.6929717139078, 994.9303548751591, 1003.1573478278208, 1012.4026824173699, 1051.978960420795, 10273.12678422335, 10326.22553308505, 10368.377897326109, 10401.479926003669, 10413.79172416555, 4131.855385061508, 4157.293260145782, 4181.226473102425, 4201.45381100455, 4235.597618226264, 8255.628496717662, 8260.926079609733, 8280.792980175544, 8284.0, 8323.916760832435, 4570.611498022706, 4630.490646028934, 4640.257558790578, 4651.534846515321, 4664.813407463664, 4252.85115020979, 4280.89297767552, 4283.2932566126865, 4302.075053863435, 4302.720323179046, 3394.372041172391, 3411.7611767176554, 3412.1981334386896, 3421.2131209821882, 3445.1903802606366, 1083.4474418046852, 1099.5925215723726, 1102.8897110288906, 1111.0556027376665, 1112.955481780736, 974.6880998080546, 977.4819345746765, 1083.864516935383, 1134.1066179391364, 1294.9805752913715, 6956.930430695729, 7014.824629384306, 7030.226675065739, 7036.261192574808, 7043.225245223021, 1197.874223206568, 1211.1340391620033, 1211.5638370186725, 1219.231883913129, 1241.3358853013594, 5660.433956604389, 5696.344920334214, 5710.8001219957105, 5744.4829965303325, 5745.569082318842, 8982.236509896602, 9186.356955013085, 9239.861402078974, 9243.676471323606, 9263.212626926652, 8945.418547794372, 8968.23769979559, 8999.32505062123, 9067.68263110788, 9142.908570914205, 6610.0, 6878.518503704692, 6882.931170688229, 6907.274736152732, 6916.170059592881, 6810.353016463428, 6840.384365406938, 6842.110525631636, 6886.380225779503, 6901.0, 7737.613119344122, 7835.216478352119, 8046.637901294573, 8057.476264042818, 8097.676092956452, 8048.356131509582, 8050.476719013245, 8152.062512811102, 8159.306458951056, 8175.67297308101, 6498.220213574401, 6537.640429776408, 6538.705758240795, 6542.34576542361, 6556.377144171296, 7032.437405007485, 7123.643817809192, 7150.892736608851, 7216.061911951622, 7274.381677557255, 6627.602343859384, 6760.18877734675, 6853.451723862178, 6922.7923162305215, 7018.508704390643, 6220.346863579259, 6244.500439964882, 6582.17722784652, 6586.209654841449, 6693.832654183684, 6662.6335551545135, 6750.32496750321, 6756.662166891734, 6766.492513061442, 6771.52599318043, 6152.169457123231, 6155.507559395327, 6164.7842325518395, 6189.288231853395, 6201.782937597162, 5931.317898441562, 6064.727087280995, 6066.757329706762, 6076.665783381956, 6160.691965401802], "unit": "score", "name": "mootools.html", "value": 4354.384802809422}, {"lowerIsBetter": false, "replicates": [324.7710364193244, 336.88040745535335, 338.5176124023266, 344.28045385145043, 346.5840990811026, 6198.4731297839735, 6235.345288744679, 6261.311255761868, 6265.18552588164, 6363.522735794822, 6171.598846075002, 6185.721642526086, 6237.625742455444, 6237.906431403532, 6255.718492667829, 4704.717716936986, 4737.90524189516, 4758.476567577563, 4765.42814862217, 4766.499517550659, 4734.928976065362, 4741.075490279397, 4762.6428017898625, 4764.118638051954, 4772.451168115659, 4594.931076033862, 4634.884127896813, 4679.321498382749, 4687.718736875781, 4696.5538273864095, 4654.5810877020895, 4661.648122044603, 4671.953280467186, 4692.131955588193, 4717.0, 4085.5710150434074, 4088.325426304656, 4092.386142078682, 4092.611201935841, 4098.938515922264, 5819.6508209507565, 5828.921649494857, 5841.153032810261, 5890.410958904118, 5919.437653422918, 5288.497592728723, 5453.454654534515, 5468.261784659099, 5487.780488780444, 5499.917501237484, 6500.057491663682, 6546.0835483032415, 6553.3446655334565, 6562.1469209003, 6569.737210511574, 4507.076049409863, 4507.864764057083, 4524.570165834273, 4526.502084770706, 4530.478994915594, 6732.057511948331, 6778.762743303961, 6798.728050878009, 6863.931360686429, 6869.587824730532, 4631.143238500821, 4634.21218392874, 4635.327877457749, 4637.976810115928, 4647.512011238771, 4627.259638457898, 4659.184642687584, 4669.859904202879, 4676.906461870812, 4686.95313046872, 5410.594205434608, 5428.0500912340185, 5430.321209848769, 5436.945630543644, 5446.863828404243, 6452.354764523604, 6569.441597464173, 6584.30864759203, 6598.0, 6607.339266073354, 1639.7950256217973, 1660.4769497608215, 1662.6259091704512, 1665.5836040989752, 1665.841745034244, 1086.7717779266284, 1099.8616431992848, 1103.586155191803, 1123.2193625430248, 1129.316763358173, 6264.749410023639, 6268.310485846553, 6271.4669253114, 6274.2784579773, 6282.183316168869, 6736.157980252468, 6767.898481522781, 6770.763023294161, 6770.763023294161, 6786.59280443175, 6164.630122192682, 6203.4416902479, 6268.185135932391, 6358.523110766711, 6368.77709280173, 3659.7438179327723, 3683.8710645127826, 3697.186618943828, 3705.184859330943, 3708.517892673935, 7170.749023784142, 7173.10336207974, 7174.856502869913, 7175.35421812029, 7179.210286868441, 6886.552374095668, 7034.929650703428, 7042.401395881305, 7043.542169759052, 7073.363397294268, 7176.0, 7178.5692858428665, 7181.784546463719, 7183.245759195313, 7200.675969581277, 6762.797116086525, 6764.627945463047, 6827.078344423439, 6833.145856767904, 6835.624040677811, 6609.768658096944, 6694.765683201064, 6711.295313991961, 6733.966330168318, 6755.560888542229, 7160.248173941661, 7214.567125972459, 7227.421806255382, 7255.310745479171, 7265.854682906418, 4603.033362993809, 4639.907201855876, 4640.350350950937, 4646.767661616973, 4648.1168577970075, 6748.223954245128, 6817.488688348294, 6876.106106206161, 6887.827804304932, 6896.551724137915, 6688.996650502463, 6700.329967003261, 6701.262861085179, 6701.966490167518, 6702.564333318319, 6518.120053792867, 6598.769043083469, 6641.734330626914, 6649.767258145942, 6666.966665166643, 6514.1531600891585, 6525.249596296487, 6539.509536784855, 6562.343765623399, 6568.0, 6625.834354140992, 6639.933600663931, 6677.76627818024, 6679.83300417474, 6733.360330768569, 6427.3251308611925, 6480.157579514633, 6483.384078512534, 6525.0538671893255, 6534.0], "unit": "score", "name": "prototype.html", "value": 4964.834427701417}, {"lowerIsBetter": false, "replicates": [706.4842664854652, 714.9642517874105, 721.6428511327023, 757.8825282081277, 788.7357735158722, 12471.314077725721, 12823.871761282384, 12846.743065138691, 12897.742045159092, 12930.741385172303, 11850.288982661046, 11871.28772273662, 11926.28442293463, 11972.22180558263, 11983.340916249603, 759.692324608533, 774.2876553570728, 795.9602019899011, 796.9402294827883, 799.0930294116191, 749.7338444852081, 750.0886422996059, 775.8487095016474, 783.4594130050276, 803.1406395157184, 779.1195948578098, 783.2519943454016, 790.7588185603389, 791.2957467853615, 796.4026979765176, 803.116571771053, 803.8954935858301, 815.2662603656697, 816.6161903905185, 818.1409520004001, 568.2811243776625, 577.125654633231, 580.5413723158699, 583.6731430398947, 589.7198830555494, 13163.144395614254, 13210.207387556682, 13255.270960097287, 13285.734285314336, 13342.399592018382, 10997.175211859143, 11213.831792523119, 11310.717232069183, 11535.826962595567, 11612.535498580046, 9400.388974716621, 9446.763830904214, 9517.476538790364, 9521.714348569554, 9539.47532885691, 3424.4007298722872, 3429.3655673700446, 3468.739844511672, 3490.5985811631735, 3495.3358861816173, 46252.30621540713, 46473.535264647595, 46514.767426162995, 46555.30167047497, 46695.5330446698, 8861.379703420827, 9059.501727405055, 9192.586333615003, 9213.078692130734, 9312.953435232781, 16842.326306947583, 16861.915690421716, 16877.40929067477, 16895.746563801553, 16905.49283521496, 6760.560563563451, 6919.861602767916, 6943.652817359111, 7029.22678505364, 7261.636918154071, 3916.8629097981434, 3964.1873415949663, 3990.660793832557, 4010.578889216649, 4044.090079732037, 2948.8083274587084, 2995.670476247611, 2999.3401451680597, 2999.610050693396, 3002.6697063323018, 517.3817288340472, 546.131650675428, 547.6905548365161, 548.3502050070599, 559.2729451712758, 431.8877091956114, 525.3538148077886, 546.9808556700497, 552.2958228259002, 555.4528789142691, 7320.450966177558, 7392.0, 7457.366123879531, 7598.240175982468, 7625.771226863203, 446.6761597841604, 451.4695233101093, 457.9862604121882, 465.2579136277632, 512.9794808207705, 3471.7743346682387, 3473.3053389322235, 3485.4249048906645, 3508.7193024558483, 3540.6105328413855, 54578.72710636421, 57771.13343299854, 57821.71089144528, 58095.0, 58151.41848581545, 44664.640243170375, 47186.764066180134, 47560.286595701094, 47657.523424765306, 47843.760781196564, 43301.78349108297, 43883.780581097526, 44199.0, 44416.11167776691, 44590.0, 7754.0, 7773.519668633713, 7887.40844436681, 7996.3602911768085, 8239.876401854097, 34137.48793768145, 34684.30631387408, 34865.0, 35085.64914350926, 35599.28801424009, 39768.403473947335, 41021.58978410178, 43479.04344304577, 43583.78208109066, 43788.781056094514, 9383.249340052917, 9783.41299522031, 9784.168345690696, 9970.651027214013, 10061.949690251502, 10136.797264054825, 10225.539850706884, 10374.844377334195, 10448.68653940383, 10524.789504209719, 9574.617015319309, 9928.553215105194, 9978.451185184884, 10018.899811001796, 10029.348092373972, 8492.872606910778, 8494.447860889022, 8573.785655358666, 8623.956880215559, 8635.093315201813, 9201.907980920105, 9581.568829402833, 9588.232941364535, 9873.358231714916, 9934.403935763878, 8372.0, 8512.531810750468, 8583.234829596724, 8634.956825215833, 8639.654413823377, 7569.47013709035, 7797.454178207471, 7874.0, 8213.712520061768, 8377.329813614775], "unit": "score", "name": "yui.html", "value": 5736.0702829580505}], "name": "dromaeo_css", "value": 6827.003375755147}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 114.0, 113.0, 113.0, 123.0, 112.0, 110.0, 112.0, 112.0], "unit": "score", "name": "ai-astar", "value": 112.9}, {"lowerIsBetter": true, "replicates": [156.0, 127.0, 151.0, 158.0, 130.0, 146.0, 154.0, 131.0, 161.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 144.2}, {"lowerIsBetter": true, "replicates": [164.0, 162.0, 163.0, 164.0, 164.0, 166.0, 161.0, 167.0, 169.0, 163.0], "unit": "score", "name": "audio-dft", "value": 164.3}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 72.0, 72.0, 73.0, 150.0, 71.0, 75.0, 74.0, 71.0], "unit": "score", "name": "audio-fft", "value": 80.4}, {"lowerIsBetter": true, "replicates": [97.0, 98.0, 146.0, 97.0, 97.0, 101.0, 151.0, 96.0, 96.0, 153.0], "unit": "score", "name": "audio-oscillator", "value": 113.2}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 147.0, 147.0, 151.0, 149.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 148.0}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [69.0, 60.0, 58.0, 58.0, 60.0, 57.0, 61.0, 57.0, 59.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.0}, {"lowerIsBetter": true, "replicates": [86.0, 93.0, 87.0, 87.0, 87.0, 87.0, 87.0, 90.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.7}, {"lowerIsBetter": true, "replicates": [128.0, 123.0, 119.0, 129.0, 123.0, 121.0, 134.0, 125.0, 123.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.7}, {"lowerIsBetter": true, "replicates": [144.0, 153.0, 157.0, 156.0, 157.0, 151.0, 153.0, 153.0, 154.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 151.7}, {"lowerIsBetter": true, "replicates": [72.0, 70.0, 68.0, 70.0, 71.0, 68.0, 68.0, 72.0, 69.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.6}], "name": "kraken", "value": 1530.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.2035610340695], "unit": "score", "name": "Richards", "value": 206.19898200000006}, {"lowerIsBetter": false, "replicates": [188.28845791752966], "unit": "score", "name": "DeltaBlue", "value": 351.152698}, {"lowerIsBetter": false, "replicates": [279.87685418415896], "unit": "score", "name": "Encrypt", "value": 951.0647130000004}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1072.961373390558], "unit": "score", "name": "RayTrace", "value": 689.6697479999999}, {"lowerIsBetter": false, "replicates": [725.6894049346879], "unit": "score", "name": "Earley", "value": 918.3860139999999}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [24926.829268292684], "unit": "score", "name": "RegExp", "value": 36.54636497064577}, {"lowerIsBetter": false, "replicates": [588.9281507656066], "unit": "score", "name": "Splay", "value": 138.37171800000004}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21340.828164221795}]} 23:18:37 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21340.83"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1530.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "6827.00"}}} 23:18:37 INFO - Return code: 0 23:18:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:18:37 INFO - # TBPL SUCCESS # 23:18:37 INFO - Running post-action listener: _resource_record_post_action 23:18:37 INFO - Running post-run listener: _resource_record_post_run 23:18:38 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 4096; Write bytes: 564039680; Read time: 28; Write time: 765480 23:18:38 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 246517760; Read time: 0; Write time: 431664 23:18:38 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317521920; Read time: 28; Write time: 333816 23:18:38 INFO - Running post-run listener: _upload_blobber_files 23:18:38 INFO - Blob upload gear active. 23:18:38 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:18:38 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 23:18:38 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'] 23:18:38 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 23:18:38 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:18:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:18:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:39 INFO - (blobuploader) - INFO - Done attempting. 23:18:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:18:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:18:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:40 INFO - (blobuploader) - INFO - Done attempting. 23:18:40 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:40 INFO - Return code: 0 23:18:40 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:18:40 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f456b2563e5e2fc34a812913836cf4c62bc33268cc57a221b8d2ab17a05a22d1718f3400e7d4691d7f1ba8bb46cd5aefa43fbdc437a50c0c4d09291736fc36f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01abaf610fa9fe66e0780b02900d0910eaebb806a67cfa80e0156958d0eaa6687b2b241f29e1d466bbd02625804d2164644c6f67b1d3e560a0bbc8f2d04840e4"} 23:18:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:18:40 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:18:40 INFO - Contents: 23:18:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f456b2563e5e2fc34a812913836cf4c62bc33268cc57a221b8d2ab17a05a22d1718f3400e7d4691d7f1ba8bb46cd5aefa43fbdc437a50c0c4d09291736fc36f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01abaf610fa9fe66e0780b02900d0910eaebb806a67cfa80e0156958d0eaa6687b2b241f29e1d466bbd02625804d2164644c6f67b1d3e560a0bbc8f2d04840e4"} 23:18:40 INFO - Copying logs to upload dir... 23:18:40 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1269.696083 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-02 23:18:39.711900) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-02 23:18:39.715877) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f456b2563e5e2fc34a812913836cf4c62bc33268cc57a221b8d2ab17a05a22d1718f3400e7d4691d7f1ba8bb46cd5aefa43fbdc437a50c0c4d09291736fc36f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01abaf610fa9fe66e0780b02900d0910eaebb806a67cfa80e0156958d0eaa6687b2b241f29e1d466bbd02625804d2164644c6f67b1d3e560a0bbc8f2d04840e4"} build_url:https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/UksYm0SlTISm7DLn6TKUUQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f456b2563e5e2fc34a812913836cf4c62bc33268cc57a221b8d2ab17a05a22d1718f3400e7d4691d7f1ba8bb46cd5aefa43fbdc437a50c0c4d09291736fc36f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01abaf610fa9fe66e0780b02900d0910eaebb806a67cfa80e0156958d0eaa6687b2b241f29e1d466bbd02625804d2164644c6f67b1d3e560a0bbc8f2d04840e4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-02 23:18:39.741944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 23:18:39.742300) ========= 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=0ae9de487587ba4b94dd3100000001b5-1451800934.609686-291317027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-02 23:18:39.826333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-02 23:18:39.826657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-02 23:18:39.826965) ========= ========= Total master_lag: 0.23 =========