builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1456534816.59 results: success (0) buildid: 20160226143401 builduid: c9171ca453d04779b32c1064d215c155 revision: 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.594539) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.594998) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.595414) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009163 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.619323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.619628) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.640787) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.641143) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004584 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.694096) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.694427) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.694805) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.695078) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-26 17:00:16-- 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% 35.3M=0s 2016-02-26 17:00:16 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101046 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.807912) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.808233) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017871 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.859077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:16.859467) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a --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 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a --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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-26 17:00:16,972 truncating revision to first 12 chars 2016-02-26 17:00:16,972 Setting DEBUG logging. 2016-02-26 17:00:16,972 attempt 1/10 2016-02-26 17:00:16,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8b401c37431d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-26 17:00:17,279 unpacking tar archive at: fx-team-8b401c37431d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.622687 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:17.495500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:17.495901) ========= echo 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'8b401c37431d5c774c4a2cf1b60e63f2faa6d82a'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a program finished with exit code 0 elapsedTime=0.003791 script_repo_revision: '8b401c37431d5c774c4a2cf1b60e63f2faa6d82a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:17.552143) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:17.552456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-26 17:00:17.566629) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-02-26 17:00:17.567054) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:00:17 INFO - MultiFileLogger online at 20160226 17:00:17 in /builds/slave/test-pgo 17:00:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:00:17 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:00:17 INFO - {'append_to_log': False, 17:00:17 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:00:17 INFO - 'blob_upload_branch': 'Fx-Team', 17:00:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:00:17 INFO - 'branch': 'Fx-Team', 17:00:17 INFO - 'buildbot_json_path': 'buildprops.json', 17:00:17 INFO - 'config_files': ('talos/linux_config.py',), 17:00:17 INFO - 'default_actions': ('clobber', 17:00:17 INFO - 'read-buildbot-config', 17:00:17 INFO - 'download-and-extract', 17:00:17 INFO - 'populate-webroot', 17:00:17 INFO - 'create-virtualenv', 17:00:17 INFO - 'install', 17:00:17 INFO - 'run-tests'), 17:00:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:00:17 INFO - 'download_minidump_stackwalk': True, 17:00:17 INFO - 'download_symbols': 'ondemand', 17:00:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:00:17 INFO - 'tooltool.py': '/tools/tooltool.py', 17:00:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:00:17 INFO - '/tools/misc-python/virtualenv.py')}, 17:00:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:00:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:00:17 INFO - 'installer_path': 'installer.exe', 17:00:17 INFO - 'log_level': 'info', 17:00:17 INFO - 'log_name': 'talos', 17:00:17 INFO - 'log_to_console': True, 17:00:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:00:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:00:17 INFO - 'opt_config_files': (), 17:00:17 INFO - 'pip_index': False, 17:00:17 INFO - 'sps_profile': False, 17:00:17 INFO - 'sps_profile_interval': 0, 17:00:17 INFO - 'suite': 'dromaeojs', 17:00:17 INFO - 'system_bits': '32', 17:00:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:00:17 INFO - 'title': 'talos-linux64-ix-041', 17:00:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:00:17 INFO - 'use_talos_json': True, 17:00:17 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:00:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:00:17 INFO - 'work_dir': 'build'} 17:00:17 INFO - ##### 17:00:17 INFO - ##### Running clobber step. 17:00:17 INFO - ##### 17:00:17 INFO - Running pre-action listener: _resource_record_pre_action 17:00:17 INFO - Running main action method: clobber 17:00:17 INFO - rmtree: /builds/slave/test-pgo/build 17:00:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:00:18 INFO - Running post-action listener: _resource_record_post_action 17:00:18 INFO - ##### 17:00:18 INFO - ##### Running read-buildbot-config step. 17:00:18 INFO - ##### 17:00:18 INFO - Running pre-action listener: _resource_record_pre_action 17:00:18 INFO - Running main action method: read_buildbot_config 17:00:18 INFO - Using buildbot properties: 17:00:18 INFO - { 17:00:18 INFO - "project": "", 17:00:18 INFO - "product": "firefox", 17:00:18 INFO - "script_repo_revision": "production", 17:00:18 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:00:18 INFO - "repository": "", 17:00:18 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:00:18 INFO - "buildid": "20160226143401", 17:00:18 INFO - "pgo_build": "True", 17:00:18 INFO - "basedir": "/builds/slave/test-pgo", 17:00:18 INFO - "buildnumber": 677, 17:00:18 INFO - "slavename": "talos-linux64-ix-041", 17:00:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:00:18 INFO - "platform": "ubuntu64_hw", 17:00:18 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:00:18 INFO - "branch": "fx-team", 17:00:18 INFO - "repo_path": "integration/fx-team", 17:00:18 INFO - "revision": "8b401c37431d5c774c4a2cf1b60e63f2faa6d82a", 17:00:18 INFO - "stage_platform": "linux64-pgo", 17:00:18 INFO - "builduid": "c9171ca453d04779b32c1064d215c155", 17:00:18 INFO - "slavebuilddir": "test" 17:00:18 INFO - } 17:00:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 17:00:18 INFO - Running post-action listener: _resource_record_post_action 17:00:18 INFO - ##### 17:00:18 INFO - ##### Running download-and-extract step. 17:00:18 INFO - ##### 17:00:18 INFO - Running pre-action listener: _resource_record_pre_action 17:00:18 INFO - Running main action method: download_and_extract 17:00:18 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:00:18 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')]} 17:00:18 INFO - https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:00:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/test_packages.json 17:00:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/test_packages.json 17:00:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:00:18 INFO - Downloaded 1448 bytes. 17:00:18 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:00:18 INFO - Using the following test package requirements: 17:00:18 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 17:00:18 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:00:18 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:00:18 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'jsshell-linux-x86_64.zip'], 17:00:18 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:00:18 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 17:00:18 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:00:18 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:00:18 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:00:18 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 17:00:18 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 17:00:18 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:00:18 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:00:18 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')]} 17:00:18 INFO - https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:00:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:00:19 INFO - Downloaded 23371646 bytes. 17:00:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 17:00:19 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 17:00:21 INFO - Return code: 0 17:00:21 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:21 INFO - https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:00:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 17:00:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:00:22 INFO - Downloaded 23371646 bytes. 17:00:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 17:00:22 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 17:00:23 INFO - Return code: 0 17:00: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')]} 17:00:23 INFO - https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 17:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 17:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 17:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:00:25 INFO - Downloaded 10713410 bytes. 17:00:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 17:00:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 17:00:25 INFO - Return code: 0 17:00:25 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')]} 17:00:25 INFO - https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:00:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 17:00:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 17:00:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 17:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:00:26 INFO - Downloaded 56638683 bytes. 17:00:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 17:00:26 INFO - mkdir: /builds/slave/test-pgo/properties 17:00:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:00:26 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:00:26 INFO - Contents: 17:00:26 INFO - build_url:https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 17:00:27 INFO - Running post-action listener: _resource_record_post_action 17:00:27 INFO - Running post-action listener: set_extra_try_arguments 17:00:27 INFO - ##### 17:00:27 INFO - ##### Running populate-webroot step. 17:00:27 INFO - ##### 17:00:27 INFO - Running pre-action listener: _resource_record_pre_action 17:00:27 INFO - Running main action method: populate_webroot 17:00:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:00:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:00:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:00:27 INFO - '--tppagecycles', 17:00:27 INFO - '7'], 17:00:27 INFO - 'tests': ['tsvgm']}}, 17:00:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:00:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:00:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 17:00:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:00:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'tests': ['damp', 'tps']}, 17:00:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['damp', 'tps']}, 17:00:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:00:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:00:27 INFO - 'other': {'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'other_l64': {'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'other_nol64': {'tests': ['a11yr', 17:00:27 INFO - 'ts_paint', 17:00:27 INFO - 'tpaint', 17:00:27 INFO - 'sessionrestore', 17:00:27 INFO - 'sessionrestore_no_auto_restore']}, 17:00:27 INFO - 'svgr': {'tests': ['tsvgx', 17:00:27 INFO - 'tsvgr_opacity', 17:00:27 INFO - 'tart', 17:00:27 INFO - 'tscrollx', 17:00:27 INFO - 'cart']}, 17:00:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['tsvgx', 17:00:27 INFO - 'tsvgr_opacity', 17:00:27 INFO - 'tart', 17:00:27 INFO - 'tscrollx', 17:00:27 INFO - 'cart']}, 17:00:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'tests': ['tp5o']}, 17:00:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'talos_options': ['--e10s'], 17:00:27 INFO - 'tests': ['tp5o']}, 17:00:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'talos_options': ['--xperf_path', 17:00:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:27 INFO - 'tests': ['tp5n']}, 17:00:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:27 INFO - 'talos_options': ['--e10s', 17:00:27 INFO - '--xperf_path', 17:00:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:27 INFO - 'tests': ['tp5n']}}, 17:00:27 INFO - 'talos.zip': {'path': '', 17:00:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:00:27 INFO - Running post-action listener: _resource_record_post_action 17:00:27 INFO - ##### 17:00:27 INFO - ##### Running create-virtualenv step. 17:00:27 INFO - ##### 17:00:27 INFO - Running pre-action listener: _resource_record_pre_action 17:00:27 INFO - Running main action method: create_virtualenv 17:00:27 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:00:27 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 17:00:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:00:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:00:27 INFO - Using real prefix '/usr' 17:00:27 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:00:27 INFO - Installing distribute.............................................................................................................................................................................................done. 17:00:29 INFO - Installing pip.................done. 17:00:29 INFO - Return code: 0 17:00:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:00:29 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')]} 17:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:29 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')]} 17:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:29 INFO - Using env: {'DISPLAY': ':0', 17:00:29 INFO - 'HOME': '/home/cltbld', 17:00:29 INFO - 'LANG': 'en_US.UTF-8', 17:00:29 INFO - 'LANGUAGE': 'en_US:en', 17:00:29 INFO - 'LOGNAME': 'cltbld', 17:00:29 INFO - 'MAIL': '/var/mail/cltbld', 17:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:29 INFO - 'MOZ_NO_REMOTE': '1', 17:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:29 INFO - 'NO_EM_RESTART': '1', 17:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:29 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:29 INFO - 'SHELL': '/bin/bash', 17:00:29 INFO - 'SHLVL': '1', 17:00:29 INFO - 'TERM': 'linux', 17:00:29 INFO - 'TMOUT': '86400', 17:00:29 INFO - 'USER': 'cltbld', 17:00:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:29 INFO - '_': '/tools/buildbot/bin/python'} 17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:30 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 17:00:30 INFO - Cleaning up... 17:00:30 INFO - Return code: 0 17:00:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:30 INFO - Using env: {'DISPLAY': ':0', 17:00:30 INFO - 'HOME': '/home/cltbld', 17:00:30 INFO - 'LANG': 'en_US.UTF-8', 17:00:30 INFO - 'LANGUAGE': 'en_US:en', 17:00:30 INFO - 'LOGNAME': 'cltbld', 17:00:30 INFO - 'MAIL': '/var/mail/cltbld', 17:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:30 INFO - 'MOZ_NO_REMOTE': '1', 17:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:30 INFO - 'NO_EM_RESTART': '1', 17:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:30 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:30 INFO - 'SHELL': '/bin/bash', 17:00:30 INFO - 'SHLVL': '1', 17:00:30 INFO - 'TERM': 'linux', 17:00:30 INFO - 'TMOUT': '86400', 17:00:30 INFO - 'USER': 'cltbld', 17:00:30 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:30 INFO - '_': '/tools/buildbot/bin/python'} 17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:30 INFO - Downloading/unpacking psutil>=0.7.1 17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:00:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:32 INFO - Installing collected packages: psutil 17:00:32 INFO - Running setup.py install for psutil 17:00:32 INFO - building 'psutil._psutil_linux' extension 17:00:32 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 17:00:32 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 17:00:32 INFO - building 'psutil._psutil_posix' extension 17:00:32 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 17:00:32 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 17:00:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:00:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:00:32 INFO - Successfully installed psutil 17:00:32 INFO - Cleaning up... 17:00:32 INFO - Return code: 0 17:00:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:32 INFO - Using env: {'DISPLAY': ':0', 17:00:32 INFO - 'HOME': '/home/cltbld', 17:00:32 INFO - 'LANG': 'en_US.UTF-8', 17:00:32 INFO - 'LANGUAGE': 'en_US:en', 17:00:32 INFO - 'LOGNAME': 'cltbld', 17:00:32 INFO - 'MAIL': '/var/mail/cltbld', 17:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:32 INFO - 'MOZ_NO_REMOTE': '1', 17:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:32 INFO - 'NO_EM_RESTART': '1', 17:00:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:32 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:32 INFO - 'SHELL': '/bin/bash', 17:00:32 INFO - 'SHLVL': '1', 17:00:32 INFO - 'TERM': 'linux', 17:00:32 INFO - 'TMOUT': '86400', 17:00:32 INFO - 'USER': 'cltbld', 17:00:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:32 INFO - '_': '/tools/buildbot/bin/python'} 17:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:00:32 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 17:00:32 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 17:00:32 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 17:00:32 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 17:00:32 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 17:00:32 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 17:00:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:00:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:00:34 INFO - Installing collected packages: mozsystemmonitor 17:00:34 INFO - Running setup.py install for mozsystemmonitor 17:00:34 INFO - Successfully installed mozsystemmonitor 17:00:34 INFO - Cleaning up... 17:00:35 INFO - Return code: 0 17:00:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:00:35 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')]} 17:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:35 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')]} 17:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:35 INFO - Using env: {'DISPLAY': ':0', 17:00:35 INFO - 'HOME': '/home/cltbld', 17:00:35 INFO - 'LANG': 'en_US.UTF-8', 17:00:35 INFO - 'LANGUAGE': 'en_US:en', 17:00:35 INFO - 'LOGNAME': 'cltbld', 17:00:35 INFO - 'MAIL': '/var/mail/cltbld', 17:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:35 INFO - 'MOZ_NO_REMOTE': '1', 17:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:35 INFO - 'NO_EM_RESTART': '1', 17:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:35 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:35 INFO - 'SHELL': '/bin/bash', 17:00:35 INFO - 'SHLVL': '1', 17:00:35 INFO - 'TERM': 'linux', 17:00:35 INFO - 'TMOUT': '86400', 17:00:35 INFO - 'USER': 'cltbld', 17:00:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:35 INFO - '_': '/tools/buildbot/bin/python'} 17:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:35 INFO - Downloading/unpacking blobuploader==1.2.4 17:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:37 INFO - Downloading blobuploader-1.2.4.tar.gz 17:00:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:00:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:00:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 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 17:00:37 INFO - Downloading docopt-0.6.1.tar.gz 17:00:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:00:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:00:37 INFO - Running setup.py install for blobuploader 17:00:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:00:37 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:00:37 INFO - Running setup.py install for requests 17:00:38 INFO - Running setup.py install for docopt 17:00:38 INFO - Successfully installed blobuploader requests docopt 17:00:38 INFO - Cleaning up... 17:00:38 INFO - Return code: 0 17:00:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:00:38 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')]} 17:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 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')]} 17:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 17:00:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 17:00:38 INFO - Using env: {'DISPLAY': ':0', 17:00:38 INFO - 'HOME': '/home/cltbld', 17:00:38 INFO - 'LANG': 'en_US.UTF-8', 17:00:38 INFO - 'LANGUAGE': 'en_US:en', 17:00:38 INFO - 'LOGNAME': 'cltbld', 17:00:38 INFO - 'MAIL': '/var/mail/cltbld', 17:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:38 INFO - 'MOZ_NO_REMOTE': '1', 17:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:38 INFO - 'NO_EM_RESTART': '1', 17:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:38 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:38 INFO - 'SHELL': '/bin/bash', 17:00:38 INFO - 'SHLVL': '1', 17:00:38 INFO - 'TERM': 'linux', 17:00:38 INFO - 'TMOUT': '86400', 17:00:38 INFO - 'USER': 'cltbld', 17:00:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:38 INFO - '_': '/tools/buildbot/bin/python'} 17:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:00:38 INFO - Running setup.py (path:/tmp/pip-DfycOQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:00:38 INFO - Running setup.py (path:/tmp/pip-lDuQb4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:00:38 INFO - Running setup.py (path:/tmp/pip-XzFqw4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:00:38 INFO - Running setup.py (path:/tmp/pip-3V5LpV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:00:38 INFO - Running setup.py (path:/tmp/pip-7ArKQ2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:00:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:00:38 INFO - Running setup.py (path:/tmp/pip-RplhEJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:00:39 INFO - Running setup.py (path:/tmp/pip-xUA633-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:00:39 INFO - Running setup.py (path:/tmp/pip-SyA_lC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:00:39 INFO - Running setup.py (path:/tmp/pip-mRCv12-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:00:39 INFO - Running setup.py (path:/tmp/pip-gmJDRJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:00:39 INFO - Running setup.py (path:/tmp/pip-P3nwBF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:00:39 INFO - Running setup.py (path:/tmp/pip-gtCTnn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:00:39 INFO - Running setup.py (path:/tmp/pip-dLX8i3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:00:39 INFO - Running setup.py (path:/tmp/pip-11ipmE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:00:39 INFO - Running setup.py (path:/tmp/pip-uy_crM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:00:39 INFO - Running setup.py (path:/tmp/pip-xrrlsg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:00:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:00:39 INFO - Running setup.py (path:/tmp/pip-t3axGn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:00:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:00:39 INFO - Running setup.py install for manifestparser 17:00:39 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:00:39 INFO - Running setup.py install for mozcrash 17:00:40 INFO - Running setup.py install for mozdebug 17:00:40 INFO - Running setup.py install for mozdevice 17:00:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for mozfile 17:00:40 INFO - Running setup.py install for mozhttpd 17:00:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for mozinfo 17:00:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for mozInstall 17:00:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for mozleak 17:00:40 INFO - Running setup.py install for mozlog 17:00:40 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for moznetwork 17:00:40 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:00:40 INFO - Running setup.py install for mozprocess 17:00:41 INFO - Running setup.py install for mozprofile 17:00:41 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:00:41 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:00:41 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:00:41 INFO - Running setup.py install for mozrunner 17:00:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:00:41 INFO - Running setup.py install for mozscreenshot 17:00:41 INFO - Running setup.py install for moztest 17:00:41 INFO - Running setup.py install for mozversion 17:00:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:00:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:00:41 INFO - Cleaning up... 17:00:41 INFO - Return code: 0 17:00:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:00: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')]} 17:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00: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')]} 17:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:41 INFO - Using env: {'DISPLAY': ':0', 17:00:41 INFO - 'HOME': '/home/cltbld', 17:00:41 INFO - 'LANG': 'en_US.UTF-8', 17:00:41 INFO - 'LANGUAGE': 'en_US:en', 17:00:41 INFO - 'LOGNAME': 'cltbld', 17:00:41 INFO - 'MAIL': '/var/mail/cltbld', 17:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:41 INFO - 'MOZ_NO_REMOTE': '1', 17:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:41 INFO - 'NO_EM_RESTART': '1', 17:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:41 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:41 INFO - 'SHELL': '/bin/bash', 17:00:41 INFO - 'SHLVL': '1', 17:00:41 INFO - 'TERM': 'linux', 17:00:41 INFO - 'TMOUT': '86400', 17:00:41 INFO - 'USER': 'cltbld', 17:00:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:41 INFO - '_': '/tools/buildbot/bin/python'} 17:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:00:41 INFO - Running setup.py (path:/tmp/pip-IzNfjQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:00:41 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)) 17:00:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:00:41 INFO - Running setup.py (path:/tmp/pip-N45Gsa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:00:41 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)) 17:00:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:00:41 INFO - Running setup.py (path:/tmp/pip-L8u8WR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:00:42 INFO - Running setup.py (path:/tmp/pip-mwtAE5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:00:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:00:42 INFO - Running setup.py (path:/tmp/pip-KFThhm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:00:42 INFO - Running setup.py (path:/tmp/pip-0sD3bo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:00:42 INFO - Running setup.py (path:/tmp/pip-SeTG58-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:00:42 INFO - Running setup.py (path:/tmp/pip-iylrO5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:00:42 INFO - Running setup.py (path:/tmp/pip-rFsFjz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:00:42 INFO - Running setup.py (path:/tmp/pip-fklfxP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:00:42 INFO - Running setup.py (path:/tmp/pip-EE3Pst-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:00:42 INFO - Running setup.py (path:/tmp/pip-pIxFI2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:00:42 INFO - Running setup.py (path:/tmp/pip-puesHQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:00:42 INFO - Running setup.py (path:/tmp/pip-1IJRZc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:00:42 INFO - Running setup.py (path:/tmp/pip-zmEl10-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:00:42 INFO - Running setup.py (path:/tmp/pip-myBeqK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:00:42 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)) 17:00:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:00:42 INFO - Running setup.py (path:/tmp/pip-ILL4bx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:00:43 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)) 17:00:43 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)) 17:00:43 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)) 17:00:43 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)) 17:00:43 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:43 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:00:43 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 17:00:43 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 17:00:43 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 17:00:43 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 17:00:43 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 17:00:43 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 17:00:44 INFO - Downloading blessings-1.6.tar.gz 17:00:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:00:44 INFO - Installing collected packages: blessings 17:00:44 INFO - Running setup.py install for blessings 17:00:45 INFO - Successfully installed blessings 17:00:45 INFO - Cleaning up... 17:00:45 INFO - Return code: 0 17:00:45 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:00:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:00:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:00:45 INFO - Reading from file tmpfile_stdout 17:00:45 INFO - Current package versions: 17:00:45 INFO - argparse == 1.2.1 17:00:45 INFO - blessings == 1.6 17:00:45 INFO - blobuploader == 1.2.4 17:00:45 INFO - docopt == 0.6.1 17:00:45 INFO - manifestparser == 1.1 17:00:45 INFO - mozInstall == 1.12 17:00:45 INFO - mozcrash == 0.16 17:00:45 INFO - mozdebug == 0.1 17:00:45 INFO - mozdevice == 0.48 17:00:45 INFO - mozfile == 1.2 17:00:45 INFO - mozhttpd == 0.7 17:00:45 INFO - mozinfo == 0.9 17:00:45 INFO - mozleak == 0.1 17:00:45 INFO - mozlog == 3.1 17:00:45 INFO - moznetwork == 0.27 17:00:45 INFO - mozprocess == 0.22 17:00:45 INFO - mozprofile == 0.28 17:00:45 INFO - mozrunner == 6.11 17:00:45 INFO - mozscreenshot == 0.1 17:00:45 INFO - mozsystemmonitor == 0.0 17:00:45 INFO - moztest == 0.7 17:00:45 INFO - mozversion == 1.4 17:00:45 INFO - psutil == 3.1.1 17:00:45 INFO - requests == 1.2.3 17:00:45 INFO - wsgiref == 0.1.2 17:00:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:00:45 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')]} 17:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:45 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')]} 17:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:45 INFO - Using env: {'DISPLAY': ':0', 17:00:45 INFO - 'HOME': '/home/cltbld', 17:00:45 INFO - 'LANG': 'en_US.UTF-8', 17:00:45 INFO - 'LANGUAGE': 'en_US:en', 17:00:45 INFO - 'LOGNAME': 'cltbld', 17:00:45 INFO - 'MAIL': '/var/mail/cltbld', 17:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:45 INFO - 'MOZ_NO_REMOTE': '1', 17:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:45 INFO - 'NO_EM_RESTART': '1', 17:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:45 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:45 INFO - 'SHELL': '/bin/bash', 17:00:45 INFO - 'SHLVL': '1', 17:00:45 INFO - 'TERM': 'linux', 17:00:45 INFO - 'TMOUT': '86400', 17:00:45 INFO - 'USER': 'cltbld', 17:00:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:45 INFO - '_': '/tools/buildbot/bin/python'} 17:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 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)) 17:00:45 INFO - Cleaning up... 17:00:45 INFO - Return code: 0 17:00:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:00:45 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')]} 17:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:45 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')]} 17:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:45 INFO - Using env: {'DISPLAY': ':0', 17:00:45 INFO - 'HOME': '/home/cltbld', 17:00:45 INFO - 'LANG': 'en_US.UTF-8', 17:00:45 INFO - 'LANGUAGE': 'en_US:en', 17:00:45 INFO - 'LOGNAME': 'cltbld', 17:00:45 INFO - 'MAIL': '/var/mail/cltbld', 17:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:45 INFO - 'MOZ_NO_REMOTE': '1', 17:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:45 INFO - 'NO_EM_RESTART': '1', 17:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:45 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:45 INFO - 'SHELL': '/bin/bash', 17:00:45 INFO - 'SHLVL': '1', 17:00:45 INFO - 'TERM': 'linux', 17:00:45 INFO - 'TMOUT': '86400', 17:00:45 INFO - 'USER': 'cltbld', 17:00:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:45 INFO - '_': '/tools/buildbot/bin/python'} 17:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:45 INFO - Downloading/unpacking jsonschema 17:00:45 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 17:00:45 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 17:00:45 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 17:00:45 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 17:00:45 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 17:00:45 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 17:00:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:00:47 INFO - Downloading/unpacking functools32 (from jsonschema) 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00: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 17:00:47 INFO - Downloading functools32-3.2.3-2.tar.gz 17:00:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:00:47 INFO - warning: no files found matching '*.txt' 17:00:47 INFO - no previously-included directories found matching 'build' 17:00:47 INFO - no previously-included directories found matching 'dist' 17:00:47 INFO - no previously-included directories found matching '.git*' 17:00:47 INFO - Installing collected packages: jsonschema, functools32 17:00:47 INFO - Running setup.py install for functools32 17:00:47 INFO - warning: no files found matching '*.txt' 17:00:47 INFO - no previously-included directories found matching 'build' 17:00:48 INFO - no previously-included directories found matching 'dist' 17:00:48 INFO - no previously-included directories found matching '.git*' 17:00:48 INFO - Successfully installed jsonschema functools32 17:00:48 INFO - Cleaning up... 17:00:48 INFO - Return code: 0 17:00:48 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:00:48 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')]} 17:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:48 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')]} 17:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9f94fd2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b7670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', '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 17:00:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 17:00:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 17:00:48 INFO - Using env: {'DISPLAY': ':0', 17:00:48 INFO - 'HOME': '/home/cltbld', 17:00:48 INFO - 'LANG': 'en_US.UTF-8', 17:00:48 INFO - 'LANGUAGE': 'en_US:en', 17:00:48 INFO - 'LOGNAME': 'cltbld', 17:00:48 INFO - 'MAIL': '/var/mail/cltbld', 17:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:48 INFO - 'MOZ_NO_REMOTE': '1', 17:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:00:48 INFO - 'NO_EM_RESTART': '1', 17:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:00:48 INFO - 'PWD': '/builds/slave/test-pgo', 17:00:48 INFO - 'SHELL': '/bin/bash', 17:00:48 INFO - 'SHLVL': '1', 17:00:48 INFO - 'TERM': 'linux', 17:00:48 INFO - 'TMOUT': '86400', 17:00:48 INFO - 'USER': 'cltbld', 17:00:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:48 INFO - '_': '/tools/buildbot/bin/python'} 17:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:48 INFO - Downloading/unpacking flake8 17:00:48 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 17:00:48 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 17:00:48 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 17:00:48 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 17:00:48 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 17:00:48 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 17:00:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:00:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:00:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 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 17:00:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:00:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:00:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:00:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:00:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:00:50 INFO - no previously-included directories found matching 'docs/_build' 17:00:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:00:50 INFO - Running setup.py install for pep8 17:00:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:00:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:00:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:00:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:00:50 INFO - no previously-included directories found matching 'docs/_build' 17:00:50 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:00:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:00:50 INFO - Cleaning up... 17:00:51 INFO - Return code: 0 17:00:51 INFO - Running post-action listener: _resource_record_post_action 17:00:51 INFO - Running post-action listener: _start_resource_monitoring 17:00:51 INFO - Starting resource monitoring. 17:00:51 INFO - ##### 17:00:51 INFO - ##### Running install step. 17:00:51 INFO - ##### 17:00:51 INFO - Running pre-action listener: _resource_record_pre_action 17:00:51 INFO - Running main action method: install 17:00:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:00:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:00:51 INFO - Reading from file tmpfile_stdout 17:00:51 INFO - Detecting whether we're running mozinstall >=1.0... 17:00:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:00:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:00:51 INFO - Reading from file tmpfile_stdout 17:00:51 INFO - Output received: 17:00:51 INFO - Usage: mozinstall [options] installer 17:00:51 INFO - Options: 17:00:51 INFO - -h, --help show this help message and exit 17:00:51 INFO - -d DEST, --destination=DEST 17:00:51 INFO - Directory to install application into. [default: 17:00:51 INFO - "/builds/slave/test-pgo"] 17:00:51 INFO - --app=APP Application being installed. [default: firefox] 17:00:51 INFO - mkdir: /builds/slave/test-pgo/build/application 17:00:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 17:00:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 17:01:07 INFO - Reading from file tmpfile_stdout 17:01:07 INFO - Output received: 17:01:07 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:01:07 INFO - Running post-action listener: _resource_record_post_action 17:01:07 INFO - ##### 17:01:07 INFO - ##### Running run-tests step. 17:01:07 INFO - ##### 17:01:07 INFO - Running pre-action listener: _resource_record_pre_action 17:01:07 INFO - Running main action method: run_tests 17:01:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:01:07 INFO - Python 2.7.3 17:01:07 INFO - Return code: 0 17:01:07 INFO - grabbing minidump binary from tooltool 17:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:01:07 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 0x14468c0>, '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 0x14477c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1447c50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:01:07 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 17:01:07 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 17:01:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:01:07 INFO - Return code: 0 17:01:07 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:01:07 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:01:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:01:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:01:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:01:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:01:08 INFO - Return code: 0 17:01:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 17:01:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 17:01:08 INFO - Using env: {'DISPLAY': ':0', 17:01:08 INFO - 'HOME': '/home/cltbld', 17:01:08 INFO - 'LANG': 'en_US.UTF-8', 17:01:08 INFO - 'LANGUAGE': 'en_US:en', 17:01:08 INFO - 'LOGNAME': 'cltbld', 17:01:08 INFO - 'MAIL': '/var/mail/cltbld', 17:01:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:01:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:08 INFO - 'MOZ_NO_REMOTE': '1', 17:01:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:01:08 INFO - 'NO_EM_RESTART': '1', 17:01:08 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:01:08 INFO - 'PWD': '/builds/slave/test-pgo', 17:01:08 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:01:08 INFO - 'SHELL': '/bin/bash', 17:01:08 INFO - 'SHLVL': '1', 17:01:08 INFO - 'TERM': 'linux', 17:01:08 INFO - 'TMOUT': '86400', 17:01:08 INFO - 'USER': 'cltbld', 17:01:08 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793', 17:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:08 INFO - '_': '/tools/buildbot/bin/python'} 17:01:08 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:01:09 INFO - mozversion application_buildid: 20160226143401 17:01:09 INFO - mozversion application_changeset: 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a 17:01:09 INFO - mozversion application_display_name: Nightly 17:01:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:01:09 INFO - mozversion application_name: Firefox 17:01:09 INFO - mozversion application_remotingname: firefox 17:01:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:01:09 INFO - mozversion application_vendor: Mozilla 17:01:09 INFO - mozversion application_version: 47.0a1 17:01:09 INFO - mozversion platform_buildid: 20160226143401 17:01:09 INFO - mozversion platform_changeset: 8b401c37431d5c774c4a2cf1b60e63f2faa6d82a 17:01:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:01:09 INFO - mozversion platform_version: 47.0a1 17:01:09 INFO - using testdate: 1456534869 17:01:09 INFO - actual date: 1456534869 17:01:09 INFO - starting webserver on 'localhost:45450' 17:01:09 INFO - SUITE-START | Running 2 tests 17:01:09 INFO - TEST-START | dromaeo_css 17:01:09 INFO - operating with platform_type : linux_ 17:01:09 INFO - Initialising browser for dromaeo_css test... 17:01:09 INFO - TEST-INFO | started process 9210 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYkkWs0/profile http://localhost:45450/getInfo.html) 17:01:13 INFO - PROCESS | 9210 | __metrics Screen width/height:1600/1200 17:01:13 INFO - PROCESS | 9210 | colorDepth:24 17:01:13 INFO - PROCESS | 9210 | Browser inner width/height: 1024/697 17:01:13 INFO - PROCESS | 9210 | __metrics 17:01:13 INFO - PROCESS | 9210 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:01:14 INFO - PROCESS | 9210 | 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. 17:01:15 INFO - TEST-INFO | 9210: exit 0 17:01:15 INFO - Browser initialized. 17:01:15 INFO - Running cycle 1/1 for dromaeo_css test... 17:01:15 INFO - TEST-INFO | started process 9305 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYkkWs0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:01:26 INFO - PROCESS | 9305 | RSS: Main: 151097344 17:01:26 INFO - PROCESS | 9305 | 17:02:18 INFO - PROCESS | 9305 | 1456534938319 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] 17:04:26 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45450/tests/dromaeo/cssquery-ext.html) 17:04:27 INFO - PROCESS | 9305 | RSS: Main: 254898176 17:04:27 INFO - PROCESS | 9305 | 17:07:29 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-ext.html (next: http://localhost:45450/tests/dromaeo/cssquery-jquery.html) 17:07:29 INFO - PROCESS | 9305 | RSS: Main: 231530496 17:07:29 INFO - PROCESS | 9305 | 17:10:31 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45450/tests/dromaeo/cssquery-mootools.html) 17:10:32 INFO - PROCESS | 9305 | RSS: Main: 236273664 17:10:32 INFO - PROCESS | 9305 | 17:13:34 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45450/tests/dromaeo/cssquery-prototype.html) 17:13:34 INFO - PROCESS | 9305 | RSS: Main: 200724480 17:13:34 INFO - PROCESS | 9305 | 17:16:37 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45450/tests/dromaeo/cssquery-yui.html) 17:16:38 INFO - PROCESS | 9305 | RSS: Main: 206749696 17:16:38 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | Cycle 1(1): loaded http://localhost:45450/tests/dromaeo/cssquery-yui.html (next: http://localhost:45450/tests/dromaeo/cssquery-dojo.html) 17:19:40 INFO - PROCESS | 9305 | RSS: Main: 276328448 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | __start_tp_report 17:19:40 INFO - PROCESS | 9305 | _x_x_mozilla_page_load 17:19:40 INFO - PROCESS | 9305 | _x_x_mozilla_page_load_details 17:19:40 INFO - PROCESS | 9305 | |i|pagename|runs| 17:19:40 INFO - PROCESS | 9305 | |0;dojo.html;3102.4415605191066;3150.9842450787773;3191.329820737645;3194.680531946806;3222.999999999999;4921.63087768417;4933.45731969483;5041.117804384232;5068.797248110076;5095.56687681547;4992.101421744084;4993.076280888038;5001.899962000758;5021.57316628087;5096.974515127419;4547;4558.254048154226;4603.033362993759;4607.907841843161;4664.790084446191;4579.9771001144945;4611.054733779584;4674.6494012949;4708.505606911276;4749.928751068736;4564.954350456519;4568.360429539867;4592.081583683277;4598.080383923212;4604.056168485469;4532.116237333721;4541;4553.043860789238;4553.362529245908;4612.630989520831;4535;4543.704659197141;4547.45430548333;4567.543245675439;4623.676342656016;4863.708177509361;4864.902701945976;4883.780229889628;4895.534924182197;4902.852914412573;5388.461153884619;5390.245365648812;5392.05639013175;5398.676079435247;5427.864303392446;5347.812826551052;5382.6232163748555;5400.756965936543;5408.188771684239;5449;5105.438401775802;5117.411497677777;5121.4110377306715;5128.076946149728;5143.691378517301;5556.472135147154;5572.972135139298;5574.219609254707;5579;5588.720563971785;5214.504622060974;5242.554382877498;5265.76304066318;5272.7100009499845;5282.286891269629;5342.171963345688;5345.893082138413;5349;5354.4645535446925;5363.570914326844;5218.086834803894;5318.282031925718;5321.600879933943;5326.360836699543;5389.811356602579;5326.760295786699;5350.357957045179;5367.1680889462195;5374.48942350476;5415.296011518478;4138.79306034697;4142.979285103615;4151.356539736345;4160.500739911169;4182.602652747984;3687.502187204747;3759.3609086455367;3789.374753165752;3825.1202223488754;3830.3871380578976;5146;5147;5147.40804807452;5149.665271757323;5187.325647665854;5405.243265942771;5428.077226871442;5429.348478182644;5433.945660543344;5470.370907345706;4977.676451030744;5046.772895219724;5089.389273287155;5127.051495473349;5141.948580514222;4495.1234509271035;4503.256962601135;4507.301368287921;4559.452865656142;4576.83981060668;5462.098753705594;5500.669959802344;5503.064479038573;5511.035568775448;5537.473939975695;5270.920936186037;5273.077211488051;5385.353757549119;5438.945610543844;5466.043442397485;5452.400235973961;5463.153211252262;5472.069748142746;5481.451854814487;5547.805826796042;5162.122439185272;5165.741712914414;5167.328247327823;5208.322918020633;5209.765560549708;5191.896162076813;5230.032443997872;5243.449437808975;5261.947380526299;5285;5283.365996080342;5305.12465443213;5307.363116426053;5310.203469479609;5315.309009828852;5224.529792318557;5225.686458812484;5231.973840130775;5232.9738351308;5324.440933701905;5075.061113693979;5107.387113546398;5124.8206312778875;5125.79496820123;5166.819161329486;5284.313039304866;5286.762095705625;5293.761780719954;5298.8940221196135;5305.65513241638;4981.252812078217;5181.870453238699;5183.3261675981885;5186.714730689849;5228.71242081674 17:19:40 INFO - PROCESS | 9305 | |1;ext.html;494.2487419122933;525.2436491452306;580.3616022375386;581.3053401185583;584.1179818474105;28081.578776318347;28510.14469565914;36798.26403471932;37056.444153337725;37058.07354816131;39394.40908386369;39723;39746.8012659937;39971.80014099933;40101;7491.587962662052;8361.958190209056;8554.187352201561;8558.443701159436;8564.871526927102;10092.394456332608;10269.589216431334;10298.433586152758;10307.330023548482;10315.742106447362;10073.697789066304;10120.342177758423;10179.389236645788;10185.898141018606;10186.388816671024;10088.546015429287;10150.644727434577;10188.6943391698;10193.59225630974;10193.745156371077;9923.851142232872;10027.298089133763;10076.24428167883;10099.747506312327;10100.040496152928;39794.403083953766;39999.60000400017;40001.79999099986;40037;40197.59802401997;3432.467967465047;3506.894793156184;3649.59854416014;3977.840886364542;4081.8367265309353;26834.329141771417;27444;27505.31236719078;27522.311942201406;27557.724422755917;3804.144067584799;3919.9216015679813;3925.5878132796215;3937.9212415751526;4041.838326466938;206983;209276;209436.9999999985;209482;209914;53615.19577206345;53687;53835.19247211295;53837;53878.730606347504;63718;63878.6806065976;64034;64164.35835641677;64304;15644.68710625781;15802.051876887424;15815.209239537977;15823.208839557976;15847.683046339009;10690.73273168177;11691.532338706527;11880.287182769061;11907.749686282988;11996.64010079699;19472.436425345124;20433.284835030703;20467.385978420672;20708.378748637566;20765.065572049294;6738.898916516257;8007.239312265326;8042.155573664798;8112.6349314281;8336.748258388267;917.3532659475054;979.6767066867917;994.0953732103751;1008.7706169365783;1014.7260239735231;28089.859550702116;28430.43139137247;28622.14133575996;28655;28884;1056.3186744549876;1085.2620218251507;1109.1792073865442;1145.6792098212513;1160.0255785140523;14535.200563969083;14809.18549479789;14854.480093196686;15112.244387780567;15120.848791512164;296947;298922;300881;300971;301536;51141.74429127831;51657.225141622905;51681.70795730137;51703.7414812931;51766.482335176166;1289.2715615677112;1301.7721898667696;1311.5278499740086;1328.1898042194255;1330.2218202351733;25037.499250015262;26246.86876565605;26256.34359141037;26356.604650930636;26453.470930581665;36854.8157259212;37453.43819842759;37601.811990940965;37736.81131594325;37764.24471510499;177215.11392442955;178501;178722;178996;180017;27180.456390871677;31059.844700777256;31157.37685246237;31347.373052538365;31610.841945790125;36481.81759091187;37721.434178486794;37797.81101094587;38019.239615206985;38052;37639.81180094191;38124;38303.808480957414;38756.41865371965;38855.6114438852;33566.16084597807;33646.49530257097;33900.66099338975;34259;34260;33073.50389744204;33399;33635.831820840736;33839.83080084584;33920;30523;31655.366892662478;31691;31699;31746.68253317437;24292.392690182885;24436.34198973166;24562.877185614674;24586.631200532363;24622.630660539748 17:19:40 INFO - PROCESS | 9305 | |2;jquery.html;333.8781344809145;335.95632567766194;339.2147179279965;341.1046004238873;346.9271452994871;16471.670566588677;16889.662206755875;17482.475525734215;17513.91243043786;17542.210600522976;15812.762808557882;15882.3647054118;16426.753598696;16597.751033734505;16613.584660383498;5185.55922746566;5189;5327.46725327468;5399.62202645815;5402.054640437927;5240.764165612557;5291.86770330741;5298.6555873868265;5323.920141197885;5341.145416733323;4847.2971419144205;5239.371275446952;5270.7628156733035;5273.6572122811895;5279.815206467774;5251.737413129347;5264.31563896691;5279.973600131994;5290.285811415468;5295.258663787073;4693.460252070987;4700.529947005272;4703.7883295251795;4706.788194531255;4710.152172608929;20372.007973740543;20954.79045209559;20993.8950305249;21547.892260538752;21554;2068.431181425105;2145.645968415225;2240.641497360431;2258.0854753824565;2348.835581509295;12814.35928203586;13072.477100915952;13119.606411807661;13341.999350048785;13382.263975481344;1764.8235176482249;1782.1712903499806;1817.6728188925988;1844.6034102667857;1855.16517567094;52599.47400525984;53221;60299.69850150721;61954;68436.6578167111;14951.551453456414;14999.550013499613;15517;15613.297401616728;15674.451394201149;21243.575128497654;24450.510989780105;24480.632790508156;24492.510149796904;24508.877455612608;8823.867641985375;8844.115588441104;8887.422317549339;8990.145936136056;9186.08139186076;7510.887336689954;7596.354309883612;7611.086669599573;7680.846383072308;7730.845383092306;7450.701971921062;7798.220177982156;7802.297793198526;7812.92187078122;7828.765137045898;2962.3927094945484;3052.740517056075;3086.753059755259;3144.2296637323543;3227.8547465364113;903.3676426501476;926.6571368593528;957.7318350861769;996.9252306077074;1012.7366884609908;16075.356985720675;16460.917695411445;16815.159242037847;16960.74558881618;17230.913845430692;892.4020905993;897.6319708919442;897.6947837735071;908.7236472636137;926.39784140308;8954.776130596787;9657.951710241405;9703.805923881482;9780.804383912424;9835.311528192959;447239;452670;461048;461417;462828;71226;72102.63948680223;72436;73165.6341718288;74003.62998184975;911.7173676160412;989.2432289298692;1000.0499525450706;1009.1926458832904;1011.7824667696481;14915.627109322353;15094.490550944818;15310.081395116329;15535.76696349533;15664.76502852481;33850;33879.830600846835;34778.478322824674;34973;35098.64901350954;45712;48302.5169748298;48693;48829.5117048825;49226;7280.890786638311;7418.740344087931;7459.888101678588;7485.326320631169;7599.506032107895;8764.737057888273;12102.576409825613;12242.387880606113;12314.261144331369;12469.314187719763;10303.845442318221;10486.895131048592;10525.421101839473;10835.404052777172;11035.172362072715;10706.501089847272;10745.194110441591;10762.901234259245;10938.289011214245;10991.61529346469;10847.67456976292;10852.131829453467;11043.503042362952;11210.159238057015;11706.122040847142;7251.927480725125;7562.886556701764;7664.041994750656;7705.036870391201;7712.537247765152;10893.400862952614;11012.229143959847;11066.280691755332;11074;11120.555177792798 17:19:40 INFO - PROCESS | 9305 | |3;mootools.html;1375.6148278482028;1463.3853781411808;1481.6444053427178;1639.237754444183;1754.5525890897827;6485.708143133558;7101.928980710191;7151.570905745659;7219.927800721991;7234.240404757504;6996.8950465743055;7096.5387249828855;7145.321194486515;7161.14066312042;7234.312740289677;1904.209752952523;1942.1163370666384;2149.451889768107;2171.5005548723802;2200.9889950550305;2118.50215199428;2138.3691810915816;2139.0695047654244;2141.4646338415396;2141.6787481877764;2120.586485635302;2137.5190582118976;2203.173809821317;2213.2253711201033;2214.66779983003;2171.35944896256;2200.3728937252886;2209.5801797658473;2211;2229.5095079082575;2195.242641288769;2202.2622421488823;2208.094681180708;2210.204326442479;2211.248175620297;7360.77917662471;7398;7435.702571897117;7437.628118594049;7453;4415.801288942005;4542.545745425464;4542.863714088583;4544;4546.636269098464;2888.292368369742;2940.588317635533;3029.9848500757357;3038.0278310940507;3043.026231605887;936.7143021378477;942.8020115775627;951.900050494695;952.8761261036091;969.6654654144309;10047.547860346229;10058.34620749649;10064;10067.194624430029;10093.848592271122;3915.4909861717792;3963.6829053675638;3974.602539746002;4050.1292222171787;4050.918981620351;8585.699500517452;8636.827263454696;8654.913450865412;8671.826563468821;8683.956580217058;4509.639228861749;4512.165249428866;4522.3668686383935;4527.547245275446;4528.773561321921;3667.74325797197;3898.4542164097047;3945.309570825094;3979.224051309967;3990;3440.2775417162175;3480.199554102571;3492.161881148506;3532.699720523733;3552.946705799415;1086.2667699303097;1100.6642973892879;1105.6572462536478;1109.7447587055024;1131.9624675583916;1303.2571434282368;1323.8345206849144;1326.522451917309;1335.1455068756004;1365.0103674835664;7046.894296585555;7050.259722729143;7140.678669459887;7141.9285807142305;7151.463640227003;417.8266019601892;427.6044658690761;429.276668813051;430.49201941709083;432.692788120438;5119.462456442095;5168.043911876315;5188.247704082887;5211.5049070338255;5257.973710131425;8492.660293588187;8591.65633374658;8725.389222754346;8772.122787721177;8811.162939520864;8695.217430431165;8750.737477875675;8792.296616270683;8794.34042446819;8808.163224493792;6794.660266986631;6849.006894000342;6943.027976083353;6970.756023539152;7016.298370162943;6567.343265673586;6595.637239951849;6631.33686631333;6639.502037347122;6639.502037347315;7580.393568514395;7657;7884.448088633741;7934.722284720007;7944.44388892772;7834.177411371724;8028.237317454832;8068.556229407439;8225.259726624632;8230.053543842534;5866.794662186803;5999.520038397005;6318.494520438446;6556.63938483388;6574.8356291093105;6862.656867156722;6871.896921546081;6917.58494490312;6922.273161317989;6927.445804335742;6168.383161683796;6607.933920660732;6625.17185351831;6642.634655094016;6660.666966651745;6349.873002539831;6397.808065758035;6509.414152726277;6546.738130474728;6601.504887133388;6606.570572912745;6647.235567909753;6653.401193892573;6679.198496180489;6682.532222744361;5493.066178749542;5524.06090964529;5579.386267510489;6206.627602343693;6214.25428948529;6153.169322141453;6225.968870155621;6253.96873015632;6257.061440783918;6265.248170219603 17:19:40 INFO - PROCESS | 9305 | |4;prototype.html;285.9928501787455;290.32354613749965;290.5481975528054;293.65055583855195;295.2913008778929;5640.74616642251;5713.514351280136;5733.426657334265;5734.598578099534;5744.9138262926035;5654.547636189105;5750.309962804469;5766.855828604276;5786.334571524276;5796.971015144929;4227.513835908864;4278.61492465678;4373.322135069069;4415.028693687384;4416.911661766762;4357.629601483878;4368.825246990116;4375.015621485156;4378.430803995476;4381.9342709859375;4321.308590625501;4325.2647050001415;4332.545082766295;4336.674749393792;4364.039911219543;4158.91682166358;4246.978765106185;4325.697201195918;4333.2200203963275;4343.370211319357;3706.7590606610484;3707.1102935295603;3709.517762690833;3715.0898029982827;3728.254349130185;5417.51242388183;5470.070088085035;5473.06957817172;5481.808136715196;5484;4971.403431588233;4982.551570358685;4983.127952608279;4984.053029924339;4988.251762235657;6052.606580572248;6053.334133245339;6055.666938318391;6056.21269235001;6059.606125601866;3940.783256920868;4102.487189101363;4103.835846566134;4106.938395924063;4108.219438306712;6275.18422605063;6389.488840892717;6396.616203027833;6417.871642567123;6428.132202152697;4184.686148538872;4189.141226048653;4191.622753952159;4191.769452680071;4192.119654872511;4228.04868904494;4236.4704411948505;4237.788110594458;4240.6395456385935;4241.427407299975;4972.303877457159;4974.676646017997;4982.90034199314;4985.601151907838;4991.975040124848;6119.908201376983;6124.203853499106;6126.571140020156;6127.295361033449;6132.754689812357;1446.7902154187584;1461.817272840895;1462.722082804264;1487.7322082025123;1488.5162322245314;922.5387306346827;937.8827646544182;938.6339327662217;1034.4569101221919;1061.2889364126054;5792.362840087587;5793.768249270066;5814.76740930358;5819.679917604572;5859.53123750099;6216.347283535164;6221.440070393686;6226.844328891814;6227.9377206227355;6238;5797.88404231913;5806.796762113306;5829.88340233193;5851.32709738377;5852.297724273115;3333.233356328058;3363.6972672459597;3365.5456513370873;3367.983160084184;3367.983160084184;6500.772472963424;6626.56927299724;6695.196576410862;6725.4619630430425;6740.696668649923;6420.325865784025;6472.093906853044;6476.870462590722;6540.37866402691;6545.083688283549;6714.597124172566;6717.56335838169;6722.764703235363;6722.9663851680425;6724.394804467622;6323.620582765049;6324.588901721373;6330.3669633036325;6330.936690633035;6333.65164915934;5687.146927960839;5923.200367950271;6244.968775156095;6276.1213430118905;6292.276388215414;6436.227652681709;6490.3185165558825;6666.200055993312;6670.032845237316;6677.265500795006;4159.292920203512;4205.990562264973;4206.558311377261;4207.221663992172;4208.452901122834;6267.341929097379;6271.560990730787;6277.937220627735;6280.9685951569945;6281.7173227203975;6119.051547010221;6127.325994140552;6131.080337949344;6131.478824299985;6135.6932153393045;5883.61756485827;5954.4938680210935;6123.469132716821;6126.6017708848785;6137.754489820357;5830.679312637845;5937.317208521075;5940.079287710412;5941.673207973603;5950.880982380415;6051.031834906394;6065.636061836304;6071.392860713893;6075.179850720273;6078.848028799139;5958.493528050164;5972.910406343822;5974.9402505974385;5976.372480889444;5983.37174596661 17:19:40 INFO - PROCESS | 9305 | |5;yui.html;754.7811134770917;759.3848982324314;765.1965436291893;826.8222332198576;832.5439952141185;13313.933430332847;13416.798748018788;13444.52944146955;13459.125156864797;13616.727665446697;12632.368381580907;12769.744605107891;12846.100772945898;13458.5289514867;13490.25803580805;834.8580741273983;846.3483117999137;851.4465597361702;852.2457625001887;852.8891244138254;839.8320335932807;840.1430540848331;844.1347618690829;845.4842546046906;846.7078857794052;870.751835726818;874.1957399192726;876.3558784293539;877.8376000439939;878.1043335797483;869.8608222684372;872.1975782280317;872.2847265242503;872.8559787635032;875.9562021898944;646.1567654211252;646.9741210351581;647.1651569475371;647.5661306924372;648.1347401219377;12425.254484730855;12491.188072775241;12541.623751287385;12552.560660376843;12561.183523070971;11532.423378831025;11565.884341156649;11665.300081995107;11686.41567921609;11927.880721192763;9804.411735295906;9897.406155630686;9953.850692239623;10062.295639305252;10135.64525241613;3463.8960831174854;3538.610752817188;3543.94684079739;3549.361114999299;3666.211764470652;47448.76275618634;47459;47525.52474475245;47528.524714752755;49166.262506062434;8303.95848020756;8433.367497437717;8613.913860861436;8913.910860891308;9134.588943497558;16903.901246418944;16963.406280780113;17050.74423883643;17070.658586828442;17099.145042747812;7599.430042746816;7643.9617801911745;7702.998610180641;7720.266574675396;7927.246911543394;4241.236577416094;4274.294741367702;4289.592488713567;4324.697271191049;4440.2007638625355;2984.253936515871;2989.446952313829;2992.1621945855195;3005.1435340927733;3018.0945716285028;500.21965733455926;516.6796586116632;520.8177138001745;524.1770420439873;529.6213207556616;591.6095377051125;639.7153266796231;641.6984017511762;648.089434344737;648.503894520692;8086;8120.796980075545;8205.425620206648;8298.8340233195;8438.59150859255;548.6324162810846;634.7238951056305;640.6764583885201;644.9516286278454;649.8570314530796;3613.747037707335;3727.6458736419995;3838.8272527736317;3995.260876737813;4039.171969746195;53969;54027.45972540303;54439;54733;55141;45923.540764591926;46218.53781462142;46647;48414;48616.27075593869;35378.64621353753;42530.57469425328;42883;43126.78436607797;43509;8243.587820608825;8279.95860020696;8333.749987500385;8464.407491475538;8474.830503389774;33034;33245.16887077843;34152.14619634529;34282.31435371229;35209;39181.60818391779;41691.58308416877;42729.78635106805;43307;43540.56459435492;11273.830892536454;11402.885971140182;11413.714657133305;11625.302481851117;11928.0457563393;11581.363025033705;12071.698207544881;12305.815412768994;12872.098953073195;12962.351882405654;11390.886091138982;11652.94173529127;11884.108691847972;12094.334811585448;12481.438335274754;11577.768444631229;11879.524819007143;12144.453499592362;12264.386780660752;12306.446209920396;11540.884591153981;11772.941135294268;11934.641960741192;12112.454939527566;12288.877111228774;9166.312526560401;9886.901130988887;9968.352057116264;10177.134943529883;10251.948740256252;9110.498922559324;9390.642328327658;9527.523623818919;9882.209423246266;10052.798944021224 17:19:40 INFO - PROCESS | 9305 | __end_tp_report 17:19:40 INFO - PROCESS | 9305 | __start_cc_report 17:19:40 INFO - PROCESS | 9305 | _x_x_mozilla_cycle_collect,833 17:19:40 INFO - PROCESS | 9305 | __end_cc_report 17:19:40 INFO - PROCESS | 9305 | __startTimestamp1456535980868__endTimestamp 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | ------- Summary: start ------- 17:19:40 INFO - PROCESS | 9305 | Number of tests: 6 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#0] dojo.html Cycles:165 Average:4995.86 Median:5167.33 stddev:522.65 (10.1%) stddev-sans-first:502.70 17:19:40 INFO - PROCESS | 9305 | Values: 3102.4 3151.0 3191.3 3194.7 3223.0 4921.6 4933.5 5041.1 5068.8 5095.6 4992.1 4993.1 5001.9 5021.6 5097.0 4547.0 4558.3 4603.0 4607.9 4664.8 4580.0 4611.1 4674.6 4708.5 4749.9 4565.0 4568.4 4592.1 4598.1 4604.1 4532.1 4541.0 4553.0 4553.4 4612.6 4535.0 4543.7 4547.5 4567.5 4623.7 4863.7 4864.9 4883.8 4895.5 4902.9 5388.5 5390.2 5392.1 5398.7 5427.9 5347.8 5382.6 5400.8 5408.2 5449.0 5105.4 5117.4 5121.4 5128.1 5143.7 5556.5 5573.0 5574.2 5579.0 5588.7 5214.5 5242.6 5265.8 5272.7 5282.3 5342.2 5345.9 5349.0 5354.5 5363.6 5218.1 5318.3 5321.6 5326.4 5389.8 5326.8 5350.4 5367.2 5374.5 5415.3 4138.8 4143.0 4151.4 4160.5 4182.6 3687.5 3759.4 3789.4 3825.1 3830.4 5146.0 5147.0 5147.4 5149.7 5187.3 5405.2 5428.1 5429.3 5433.9 5470.4 4977.7 5046.8 5089.4 5127.1 5141.9 4495.1 4503.3 4507.3 4559.5 4576.8 5462.1 5500.7 5503.1 5511.0 5537.5 5270.9 5273.1 5385.4 5438.9 5466.0 5452.4 5463.2 5472.1 5481.5 5547.8 5162.1 5165.7 5167.3 5208.3 5209.8 5191.9 5230.0 5243.4 5261.9 5285.0 5283.4 5305.1 5307.4 5310.2 5315.3 5224.5 5225.7 5232.0 5233.0 5324.4 5075.1 5107.4 5124.8 5125.8 5166.8 5284.3 5286.8 5293.8 5298.9 5305.7 4981.3 5181.9 5183.3 5186.7 5228.7 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#1] ext.html Cycles:180 Average:40295.56 Median:31108.61 stddev:61177.47 (196.7%) stddev-sans-first:61276.10 17:19:40 INFO - PROCESS | 9305 | Values: 494.2 525.2 580.4 581.3 584.1 28081.6 28510.1 36798.3 37056.4 37058.1 39394.4 39723.0 39746.8 39971.8 40101.0 7491.6 8362.0 8554.2 8558.4 8564.9 10092.4 10269.6 10298.4 10307.3 10315.7 10073.7 10120.3 10179.4 10185.9 10186.4 10088.5 10150.6 10188.7 10193.6 10193.7 9923.9 10027.3 10076.2 10099.7 10100.0 39794.4 39999.6 40001.8 40037.0 40197.6 3432.5 3506.9 3649.6 3977.8 4081.8 26834.3 27444.0 27505.3 27522.3 27557.7 3804.1 3919.9 3925.6 3937.9 4041.8 206983.0 209276.0 209437.0 209482.0 209914.0 53615.2 53687.0 53835.2 53837.0 53878.7 63718.0 63878.7 64034.0 64164.4 64304.0 15644.7 15802.1 15815.2 15823.2 15847.7 10690.7 11691.5 11880.3 11907.7 11996.6 19472.4 20433.3 20467.4 20708.4 20765.1 6738.9 8007.2 8042.2 8112.6 8336.7 917.4 979.7 994.1 1008.8 1014.7 28089.9 28430.4 28622.1 28655.0 28884.0 1056.3 1085.3 1109.2 1145.7 1160.0 14535.2 14809.2 14854.5 15112.2 15120.8 296947.0 298922.0 300881.0 300971.0 301536.0 51141.7 51657.2 51681.7 51703.7 51766.5 1289.3 1301.8 1311.5 1328.2 1330.2 25037.5 26246.9 26256.3 26356.6 26453.5 36854.8 37453.4 37601.8 37736.8 37764.2 177215.1 178501.0 178722.0 178996.0 180017.0 27180.5 31059.8 31157.4 31347.4 31610.8 36481.8 37721.4 37797.8 38019.2 38052.0 37639.8 38124.0 38303.8 38756.4 38855.6 33566.2 33646.5 33900.7 34259.0 34260.0 33073.5 33399.0 33635.8 33839.8 33920.0 30523.0 31655.4 31691.0 31699.0 31746.7 24292.4 24436.3 24562.9 24586.6 24622.6 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#2] jquery.html Cycles:180 Average:26407.76 Median:34875.74 stddev:74709.18 (214.2%) stddev-sans-first:74893.11 17:19:40 INFO - PROCESS | 9305 | Values: 333.9 336.0 339.2 341.1 346.9 16471.7 16889.7 17482.5 17513.9 17542.2 15812.8 15882.4 16426.8 16597.8 16613.6 5185.6 5189.0 5327.5 5399.6 5402.1 5240.8 5291.9 5298.7 5323.9 5341.1 4847.3 5239.4 5270.8 5273.7 5279.8 5251.7 5264.3 5280.0 5290.3 5295.3 4693.5 4700.5 4703.8 4706.8 4710.2 20372.0 20954.8 20993.9 21547.9 21554.0 2068.4 2145.6 2240.6 2258.1 2348.8 12814.4 13072.5 13119.6 13342.0 13382.3 1764.8 1782.2 1817.7 1844.6 1855.2 52599.5 53221.0 60299.7 61954.0 68436.7 14951.6 14999.6 15517.0 15613.3 15674.5 21243.6 24450.5 24480.6 24492.5 24508.9 8823.9 8844.1 8887.4 8990.1 9186.1 7510.9 7596.4 7611.1 7680.8 7730.8 7450.7 7798.2 7802.3 7812.9 7828.8 2962.4 3052.7 3086.8 3144.2 3227.9 903.4 926.7 957.7 996.9 1012.7 16075.4 16460.9 16815.2 16960.7 17230.9 892.4 897.6 897.7 908.7 926.4 8954.8 9658.0 9703.8 9780.8 9835.3 447239.0 452670.0 461048.0 461417.0 462828.0 71226.0 72102.6 72436.0 73165.6 74003.6 911.7 989.2 1000.0 1009.2 1011.8 14915.6 15094.5 15310.1 15535.8 15664.8 33850.0 33879.8 34778.5 34973.0 35098.6 45712.0 48302.5 48693.0 48829.5 49226.0 7280.9 7418.7 7459.9 7485.3 7599.5 8764.7 12102.6 12242.4 12314.3 12469.3 10303.8 10486.9 10525.4 10835.4 11035.2 10706.5 10745.2 10762.9 10938.3 10991.6 10847.7 10852.1 11043.5 11210.2 11706.1 7251.9 7562.9 7664.0 7705.0 7712.5 10893.4 11012.2 11066.3 11074.0 11120.6 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#3] mootools.html Cycles:180 Average:5107.98 Median:6160.78 stddev:2649.00 (43.0%) stddev-sans-first:2641.58 17:19:40 INFO - PROCESS | 9305 | Values: 1375.6 1463.4 1481.6 1639.2 1754.6 6485.7 7101.9 7151.6 7219.9 7234.2 6996.9 7096.5 7145.3 7161.1 7234.3 1904.2 1942.1 2149.5 2171.5 2201.0 2118.5 2138.4 2139.1 2141.5 2141.7 2120.6 2137.5 2203.2 2213.2 2214.7 2171.4 2200.4 2209.6 2211.0 2229.5 2195.2 2202.3 2208.1 2210.2 2211.2 7360.8 7398.0 7435.7 7437.6 7453.0 4415.8 4542.5 4542.9 4544.0 4546.6 2888.3 2940.6 3030.0 3038.0 3043.0 936.7 942.8 951.9 952.9 969.7 10047.5 10058.3 10064.0 10067.2 10093.8 3915.5 3963.7 3974.6 4050.1 4050.9 8585.7 8636.8 8654.9 8671.8 8684.0 4509.6 4512.2 4522.4 4527.5 4528.8 3667.7 3898.5 3945.3 3979.2 3990.0 3440.3 3480.2 3492.2 3532.7 3552.9 1086.3 1100.7 1105.7 1109.7 1132.0 1303.3 1323.8 1326.5 1335.1 1365.0 7046.9 7050.3 7140.7 7141.9 7151.5 417.8 427.6 429.3 430.5 432.7 5119.5 5168.0 5188.2 5211.5 5258.0 8492.7 8591.7 8725.4 8772.1 8811.2 8695.2 8750.7 8792.3 8794.3 8808.2 6794.7 6849.0 6943.0 6970.8 7016.3 6567.3 6595.6 6631.3 6639.5 6639.5 7580.4 7657.0 7884.4 7934.7 7944.4 7834.2 8028.2 8068.6 8225.3 8230.1 5866.8 5999.5 6318.5 6556.6 6574.8 6862.7 6871.9 6917.6 6922.3 6927.4 6168.4 6607.9 6625.2 6642.6 6660.7 6349.9 6397.8 6509.4 6546.7 6601.5 6606.6 6647.2 6653.4 6679.2 6682.5 5493.1 5524.1 5579.4 6206.6 6214.3 6153.2 6226.0 6254.0 6257.1 6265.2 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#4] prototype.html Cycles:180 Average:5068.05 Median:5802.34 stddev:1571.32 (27.1%) stddev-sans-first:1534.19 17:19:40 INFO - PROCESS | 9305 | Values: 286.0 290.3 290.5 293.7 295.3 5640.7 5713.5 5733.4 5734.6 5744.9 5654.5 5750.3 5766.9 5786.3 5797.0 4227.5 4278.6 4373.3 4415.0 4416.9 4357.6 4368.8 4375.0 4378.4 4381.9 4321.3 4325.3 4332.5 4336.7 4364.0 4158.9 4247.0 4325.7 4333.2 4343.4 3706.8 3707.1 3709.5 3715.1 3728.3 5417.5 5470.1 5473.1 5481.8 5484.0 4971.4 4982.6 4983.1 4984.1 4988.3 6052.6 6053.3 6055.7 6056.2 6059.6 3940.8 4102.5 4103.8 4106.9 4108.2 6275.2 6389.5 6396.6 6417.9 6428.1 4184.7 4189.1 4191.6 4191.8 4192.1 4228.0 4236.5 4237.8 4240.6 4241.4 4972.3 4974.7 4982.9 4985.6 4992.0 6119.9 6124.2 6126.6 6127.3 6132.8 1446.8 1461.8 1462.7 1487.7 1488.5 922.5 937.9 938.6 1034.5 1061.3 5792.4 5793.8 5814.8 5819.7 5859.5 6216.3 6221.4 6226.8 6227.9 6238.0 5797.9 5806.8 5829.9 5851.3 5852.3 3333.2 3363.7 3365.5 3368.0 3368.0 6500.8 6626.6 6695.2 6725.5 6740.7 6420.3 6472.1 6476.9 6540.4 6545.1 6714.6 6717.6 6722.8 6723.0 6724.4 6323.6 6324.6 6330.4 6330.9 6333.7 5687.1 5923.2 6245.0 6276.1 6292.3 6436.2 6490.3 6666.2 6670.0 6677.3 4159.3 4206.0 4206.6 4207.2 4208.5 6267.3 6271.6 6277.9 6281.0 6281.7 6119.1 6127.3 6131.1 6131.5 6135.7 5883.6 5954.5 6123.5 6126.6 6137.8 5830.7 5937.3 5940.1 5941.7 5950.9 6051.0 6065.6 6071.4 6075.2 6078.8 5958.5 5972.9 5974.9 5976.4 5983.4 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | [#5] yui.html Cycles:180 Average:13285.00 Median:46071.04 stddev:15001.97 (32.6%) stddev-sans-first:15014.55 17:19:40 INFO - PROCESS | 9305 | Values: 754.8 759.4 765.2 826.8 832.5 13313.9 13416.8 13444.5 13459.1 13616.7 12632.4 12769.7 12846.1 13458.5 13490.3 834.9 846.3 851.4 852.2 852.9 839.8 840.1 844.1 845.5 846.7 870.8 874.2 876.4 877.8 878.1 869.9 872.2 872.3 872.9 876.0 646.2 647.0 647.2 647.6 648.1 12425.3 12491.2 12541.6 12552.6 12561.2 11532.4 11565.9 11665.3 11686.4 11927.9 9804.4 9897.4 9953.9 10062.3 10135.6 3463.9 3538.6 3543.9 3549.4 3666.2 47448.8 47459.0 47525.5 47528.5 49166.3 8304.0 8433.4 8613.9 8913.9 9134.6 16903.9 16963.4 17050.7 17070.7 17099.1 7599.4 7644.0 7703.0 7720.3 7927.2 4241.2 4274.3 4289.6 4324.7 4440.2 2984.3 2989.4 2992.2 3005.1 3018.1 500.2 516.7 520.8 524.2 529.6 591.6 639.7 641.7 648.1 648.5 8086.0 8120.8 8205.4 8298.8 8438.6 548.6 634.7 640.7 645.0 649.9 3613.7 3727.6 3838.8 3995.3 4039.2 53969.0 54027.5 54439.0 54733.0 55141.0 45923.5 46218.5 46647.0 48414.0 48616.3 35378.6 42530.6 42883.0 43126.8 43509.0 8243.6 8280.0 8333.7 8464.4 8474.8 33034.0 33245.2 34152.1 34282.3 35209.0 39181.6 41691.6 42729.8 43307.0 43540.6 11273.8 11402.9 11413.7 11625.3 11928.0 11581.4 12071.7 12305.8 12872.1 12962.4 11390.9 11652.9 11884.1 12094.3 12481.4 11577.8 11879.5 12144.5 12264.4 12306.4 11540.9 11772.9 11934.6 12112.5 12288.9 9166.3 9886.9 9968.4 10177.1 10251.9 9110.5 9390.6 9527.5 9882.2 10052.8 17:19:40 INFO - PROCESS | 9305 | -------- Summary: end -------- 17:19:40 INFO - PROCESS | 9305 | 17:19:40 INFO - PROCESS | 9305 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:40 INFO - PROCESS | 9305 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:40 INFO - PROCESS | 9305 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:40 INFO - PROCESS | 9305 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:40 INFO - PROCESS | 9305 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:41 INFO - TEST-INFO | 9305: exit 0 17:19:41 INFO - TEST-OK | dromaeo_css | took 1112598ms 17:19:41 INFO - TEST-START | kraken 17:19:41 INFO - operating with platform_type : linux_ 17:19:41 INFO - Initialising browser for kraken test... 17:19:41 INFO - TEST-INFO | started process 9493 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpL6I9wy/profile http://localhost:45450/getInfo.html) 17:19:45 INFO - PROCESS | 9493 | __metrics Screen width/height:1600/1200 17:19:45 INFO - PROCESS | 9493 | colorDepth:24 17:19:45 INFO - PROCESS | 9493 | Browser inner width/height: 1024/697 17:19:45 INFO - PROCESS | 9493 | __metrics 17:19:46 INFO - PROCESS | 9493 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:19:46 INFO - PROCESS | 9493 | 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. 17:19:47 INFO - TEST-INFO | 9493: exit 0 17:19:47 INFO - Browser initialized. 17:19:47 INFO - Running cycle 1/1 for kraken test... 17:19:47 INFO - TEST-INFO | started process 9576 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpL6I9wy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:59 INFO - PROCESS | 9576 | RSS: Main: 150827008 17:19:59 INFO - PROCESS | 9576 | 17:20:01 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45450/tests/kraken/driver.html?testName=audio-beat-detection) 17:20:02 INFO - PROCESS | 9576 | RSS: Main: 196268032 17:20:02 INFO - PROCESS | 9576 | 17:20:05 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45450/tests/kraken/driver.html?testName=audio-dft) 17:20:05 INFO - PROCESS | 9576 | RSS: Main: 183095296 17:20:05 INFO - PROCESS | 9576 | 17:20:08 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45450/tests/kraken/driver.html?testName=audio-fft) 17:20:09 INFO - PROCESS | 9576 | RSS: Main: 168628224 17:20:09 INFO - PROCESS | 9576 | 17:20:11 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45450/tests/kraken/driver.html?testName=audio-oscillator) 17:20:11 INFO - PROCESS | 9576 | RSS: Main: 169398272 17:20:11 INFO - PROCESS | 9576 | 17:20:13 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45450/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:20:14 INFO - PROCESS | 9576 | RSS: Main: 171974656 17:20:14 INFO - PROCESS | 9576 | 17:20:17 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45450/tests/kraken/driver.html?testName=imaging-darkroom) 17:20:18 INFO - PROCESS | 9576 | RSS: Main: 172969984 17:20:18 INFO - PROCESS | 9576 | 17:20:21 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45450/tests/kraken/driver.html?testName=imaging-desaturate) 17:20:22 INFO - PROCESS | 9576 | RSS: Main: 172453888 17:20:22 INFO - PROCESS | 9576 | 17:20:24 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45450/tests/kraken/driver.html?testName=json-parse-financial) 17:20:25 INFO - PROCESS | 9576 | RSS: Main: 173768704 17:20:25 INFO - PROCESS | 9576 | 17:20:26 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45450/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:20:27 INFO - PROCESS | 9576 | RSS: Main: 184606720 17:20:27 INFO - PROCESS | 9576 | 17:20:29 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:20:29 INFO - PROCESS | 9576 | RSS: Main: 183394304 17:20:29 INFO - PROCESS | 9576 | 17:20:32 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:20:32 INFO - PROCESS | 9576 | RSS: Main: 194457600 17:20:32 INFO - PROCESS | 9576 | 17:20:35 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:20:35 INFO - PROCESS | 9576 | RSS: Main: 197939200 17:20:35 INFO - PROCESS | 9576 | 17:20:38 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:20:38 INFO - PROCESS | 9576 | RSS: Main: 199155712 17:20:38 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | Cycle 1(1): loaded http://localhost:45450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45450/tests/kraken/driver.html?testName=ai-astar) 17:20:40 INFO - PROCESS | 9576 | RSS: Main: 258650112 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | __start_tp_report 17:20:40 INFO - PROCESS | 9576 | _x_x_mozilla_page_load 17:20:40 INFO - PROCESS | 9576 | _x_x_mozilla_page_load_details 17:20:40 INFO - PROCESS | 9576 | |i|pagename|runs| 17:20:40 INFO - PROCESS | 9576 | |0;ai-astar;111;111;106;110;110;113;100;109;109;110 17:20:40 INFO - PROCESS | 9576 | |1;audio-beat-detection;153;124;156;163;130;149;153;127;179;127 17:20:40 INFO - PROCESS | 9576 | |2;audio-dft;162;162;162;161;161;161;160;165;163;162 17:20:40 INFO - PROCESS | 9576 | |3;audio-fft;72;73;92;99;73;74;72;131;96;72 17:20:40 INFO - PROCESS | 9576 | |4;audio-oscillator;97;96;140;96;96;96;138;95;98;150 17:20:40 INFO - PROCESS | 9576 | |5;imaging-gaussian-blur;119;115;114;116;116;115;115;115;115;114 17:20:40 INFO - PROCESS | 9576 | |6;imaging-darkroom;146;145;145;145;147;146;145;146;147;145 17:20:40 INFO - PROCESS | 9576 | |7;imaging-desaturate;96;96;95;95;96;96;96;96;96;96 17:20:40 INFO - PROCESS | 9576 | |8;json-parse-financial;59;58;59;57;58;58;59;66;57;57 17:20:40 INFO - PROCESS | 9576 | |9;json-stringify-tinderbox;68;68;67;67;67;67;69;67;69;67 17:20:40 INFO - PROCESS | 9576 | |10;stanford-crypto-aes;89;82;83;83;84;82;82;84;82;81 17:20:40 INFO - PROCESS | 9576 | |11;stanford-crypto-ccm;118;120;117;117;119;125;128;121;118;121 17:20:40 INFO - PROCESS | 9576 | |12;stanford-crypto-pbkdf2;139;143;140;153;155;138;149;152;137;148 17:20:40 INFO - PROCESS | 9576 | |13;stanford-crypto-sha256-iterative;67;69;74;70;68;68;68;73;67;67 17:20:40 INFO - PROCESS | 9576 | __end_tp_report 17:20:40 INFO - PROCESS | 9576 | __start_cc_report 17:20:40 INFO - PROCESS | 9576 | _x_x_mozilla_cycle_collect,1057 17:20:40 INFO - PROCESS | 9576 | __end_cc_report 17:20:40 INFO - PROCESS | 9576 | __startTimestamp1456536040716__endTimestamp 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | ------- Summary: start ------- 17:20:40 INFO - PROCESS | 9576 | Number of tests: 14 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:3.60 (3.3%) stddev-sans-first:3.74 17:20:40 INFO - PROCESS | 9576 | Values: 111.0 111.0 106.0 110.0 110.0 113.0 100.0 109.0 109.0 110.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:153.00 stddev:18.40 (12.0%) stddev-sans-first:19.35 17:20:40 INFO - PROCESS | 9576 | Values: 153.0 124.0 156.0 163.0 130.0 149.0 153.0 127.0 179.0 127.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.37 (0.8%) stddev-sans-first:1.45 17:20:40 INFO - PROCESS | 9576 | Values: 162.0 162.0 162.0 161.0 161.0 161.0 160.0 165.0 163.0 162.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#3] audio-fft Cycles:10 Average:85.40 Median:73.50 stddev:19.43 (26.4%) stddev-sans-first:19.99 17:20:40 INFO - PROCESS | 9576 | Values: 72.0 73.0 92.0 99.0 73.0 74.0 72.0 131.0 96.0 72.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:96.00 stddev:22.62 (23.6%) stddev-sans-first:23.48 17:20:40 INFO - PROCESS | 9576 | Values: 97.0 96.0 140.0 96.0 96.0 96.0 138.0 95.0 98.0 150.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.00 stddev:1.43 (1.2%) stddev-sans-first:0.71 17:20:40 INFO - PROCESS | 9576 | Values: 119.0 115.0 114.0 116.0 116.0 115.0 115.0 115.0 115.0 114.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 17:20:40 INFO - PROCESS | 9576 | Values: 146.0 145.0 145.0 145.0 147.0 146.0 145.0 146.0 147.0 145.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:20:40 INFO - PROCESS | 9576 | Values: 96.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.50 stddev:2.66 (4.5%) stddev-sans-first:2.82 17:20:40 INFO - PROCESS | 9576 | Values: 59.0 58.0 59.0 57.0 58.0 58.0 59.0 66.0 57.0 57.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 17:20:40 INFO - PROCESS | 9576 | Values: 68.0 68.0 67.0 67.0 67.0 67.0 69.0 67.0 69.0 67.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:2.25 (2.7%) stddev-sans-first:1.01 17:20:40 INFO - PROCESS | 9576 | Values: 89.0 82.0 83.0 83.0 84.0 82.0 82.0 84.0 82.0 81.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#11] stanford-crypto-ccm Cycles:10 Average:120.40 Median:120.50 stddev:3.60 (3.0%) stddev-sans-first:3.71 17:20:40 INFO - PROCESS | 9576 | Values: 118.0 120.0 117.0 117.0 119.0 125.0 128.0 121.0 118.0 121.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.40 Median:148.50 stddev:6.79 (4.6%) stddev-sans-first:6.79 17:20:40 INFO - PROCESS | 9576 | Values: 139.0 143.0 140.0 153.0 155.0 138.0 149.0 152.0 137.0 148.0 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:68.50 stddev:2.51 (3.7%) stddev-sans-first:2.55 17:20:40 INFO - PROCESS | 9576 | Values: 67.0 69.0 74.0 70.0 68.0 68.0 68.0 73.0 67.0 67.0 17:20:40 INFO - PROCESS | 9576 | -------- Summary: end -------- 17:20:40 INFO - PROCESS | 9576 | 17:20:40 INFO - PROCESS | 9576 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:20:40 INFO - PROCESS | 9576 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:20:40 INFO - PROCESS | 9576 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:20:40 INFO - PROCESS | 9576 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:20:40 INFO - PROCESS | 9576 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 17:20:41 INFO - TEST-INFO | 9576: exit 0 17:20:41 INFO - TEST-OK | kraken | took 60021ms 17:20:41 INFO - SUITE-END | took 1172s 17:20:41 INFO - Completed test suite (00:19:32) 17:20:41 INFO - Working with test: dromaeo_css 17:20:41 INFO - Generating results file: dromaeo_css 17:20:41 INFO - Working with test: kraken 17:20:41 INFO - Generating results file: kraken 17:20:41 INFO - javascript benchmark 17:20:41 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:20:41 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 17:20:41 INFO - process_Request line: dromaeo_css 6725.02 graph.html#tests=[[72,64,35]] 17:20:41 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:20:41 INFO - process_Request line: kraken 1513.90 graph.html#tests=[[232,64,35]] 17:20:41 INFO - RETURN: dromaeo_css: 6725.02 17:20:41 INFO - RETURN: kraken: 1513.90 17:20:41 INFO - javascript benchmark 17:20:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3102.4415605191066, 3150.9842450787773, 3191.329820737645, 3194.680531946806, 3222.999999999999, 4921.63087768417, 4933.45731969483, 5041.117804384232, 5068.797248110076, 5095.56687681547, 4992.101421744084, 4993.076280888038, 5001.899962000758, 5021.57316628087, 5096.974515127419, 4547.0, 4558.254048154226, 4603.033362993759, 4607.907841843161, 4664.790084446191, 4579.9771001144945, 4611.054733779584, 4674.6494012949, 4708.505606911276, 4749.928751068736, 4564.954350456519, 4568.360429539867, 4592.081583683277, 4598.080383923212, 4604.056168485469, 4532.116237333721, 4541.0, 4553.043860789238, 4553.362529245908, 4612.630989520831, 4535.0, 4543.704659197141, 4547.45430548333, 4567.543245675439, 4623.676342656016, 4863.708177509361, 4864.902701945976, 4883.780229889628, 4895.534924182197, 4902.852914412573, 5388.461153884619, 5390.245365648812, 5392.05639013175, 5398.676079435247, 5427.864303392446, 5347.812826551052, 5382.6232163748555, 5400.756965936543, 5408.188771684239, 5449.0, 5105.438401775802, 5117.411497677777, 5121.4110377306715, 5128.076946149728, 5143.691378517301, 5556.472135147154, 5572.972135139298, 5574.219609254707, 5579.0, 5588.720563971785, 5214.504622060974, 5242.554382877498, 5265.76304066318, 5272.7100009499845, 5282.286891269629, 5342.171963345688, 5345.893082138413, 5349.0, 5354.4645535446925, 5363.570914326844, 5218.086834803894, 5318.282031925718, 5321.600879933943, 5326.360836699543, 5389.811356602579, 5326.760295786699, 5350.357957045179, 5367.1680889462195, 5374.48942350476, 5415.296011518478, 4138.79306034697, 4142.979285103615, 4151.356539736345, 4160.500739911169, 4182.602652747984, 3687.502187204747, 3759.3609086455367, 3789.374753165752, 3825.1202223488754, 3830.3871380578976, 5146.0, 5147.0, 5147.40804807452, 5149.665271757323, 5187.325647665854, 5405.243265942771, 5428.077226871442, 5429.348478182644, 5433.945660543344, 5470.370907345706, 4977.676451030744, 5046.772895219724, 5089.389273287155, 5127.051495473349, 5141.948580514222, 4495.1234509271035, 4503.256962601135, 4507.301368287921, 4559.452865656142, 4576.83981060668, 5462.098753705594, 5500.669959802344, 5503.064479038573, 5511.035568775448, 5537.473939975695, 5270.920936186037, 5273.077211488051, 5385.353757549119, 5438.945610543844, 5466.043442397485, 5452.400235973961, 5463.153211252262, 5472.069748142746, 5481.451854814487, 5547.805826796042, 5162.122439185272, 5165.741712914414, 5167.328247327823, 5208.322918020633, 5209.765560549708, 5191.896162076813, 5230.032443997872, 5243.449437808975, 5261.947380526299, 5285.0, 5283.365996080342, 5305.12465443213, 5307.363116426053, 5310.203469479609, 5315.309009828852, 5224.529792318557, 5225.686458812484, 5231.973840130775, 5232.9738351308, 5324.440933701905, 5075.061113693979, 5107.387113546398, 5124.8206312778875, 5125.79496820123, 5166.819161329486, 5284.313039304866, 5286.762095705625, 5293.761780719954, 5298.8940221196135, 5305.65513241638, 4981.252812078217, 5181.870453238699, 5183.3261675981885, 5186.714730689849, 5228.71242081674], "unit": "score", "name": "dojo.html", "value": 4964.537037809454}, {"lowerIsBetter": false, "replicates": [494.2487419122933, 525.2436491452306, 580.3616022375386, 581.3053401185583, 584.1179818474105, 28081.578776318347, 28510.14469565914, 36798.26403471932, 37056.444153337725, 37058.07354816131, 39394.40908386369, 39723.0, 39746.8012659937, 39971.80014099933, 40101.0, 7491.587962662052, 8361.958190209056, 8554.187352201561, 8558.443701159436, 8564.871526927102, 10092.394456332608, 10269.589216431334, 10298.433586152758, 10307.330023548482, 10315.742106447362, 10073.697789066304, 10120.342177758423, 10179.389236645788, 10185.898141018606, 10186.388816671024, 10088.546015429287, 10150.644727434577, 10188.6943391698, 10193.59225630974, 10193.745156371077, 9923.851142232872, 10027.298089133763, 10076.24428167883, 10099.747506312327, 10100.040496152928, 39794.403083953766, 39999.60000400017, 40001.79999099986, 40037.0, 40197.59802401997, 3432.467967465047, 3506.894793156184, 3649.59854416014, 3977.840886364542, 4081.8367265309353, 26834.329141771417, 27444.0, 27505.31236719078, 27522.311942201406, 27557.724422755917, 3804.144067584799, 3919.9216015679813, 3925.5878132796215, 3937.9212415751526, 4041.838326466938, 206983.0, 209276.0, 209436.9999999985, 209482.0, 209914.0, 53615.19577206345, 53687.0, 53835.19247211295, 53837.0, 53878.730606347504, 63718.0, 63878.6806065976, 64034.0, 64164.35835641677, 64304.0, 15644.68710625781, 15802.051876887424, 15815.209239537977, 15823.208839557976, 15847.683046339009, 10690.73273168177, 11691.532338706527, 11880.287182769061, 11907.749686282988, 11996.64010079699, 19472.436425345124, 20433.284835030703, 20467.385978420672, 20708.378748637566, 20765.065572049294, 6738.898916516257, 8007.239312265326, 8042.155573664798, 8112.6349314281, 8336.748258388267, 917.3532659475054, 979.6767066867917, 994.0953732103751, 1008.7706169365783, 1014.7260239735231, 28089.859550702116, 28430.43139137247, 28622.14133575996, 28655.0, 28884.0, 1056.3186744549876, 1085.2620218251507, 1109.1792073865442, 1145.6792098212513, 1160.0255785140523, 14535.200563969083, 14809.18549479789, 14854.480093196686, 15112.244387780567, 15120.848791512164, 296947.0, 298922.0, 300881.0, 300971.0, 301536.0, 51141.74429127831, 51657.225141622905, 51681.70795730137, 51703.7414812931, 51766.482335176166, 1289.2715615677112, 1301.7721898667696, 1311.5278499740086, 1328.1898042194255, 1330.2218202351733, 25037.499250015262, 26246.86876565605, 26256.34359141037, 26356.604650930636, 26453.470930581665, 36854.8157259212, 37453.43819842759, 37601.811990940965, 37736.81131594325, 37764.24471510499, 177215.11392442955, 178501.0, 178722.0, 178996.0, 180017.0, 27180.456390871677, 31059.844700777256, 31157.37685246237, 31347.373052538365, 31610.841945790125, 36481.81759091187, 37721.434178486794, 37797.81101094587, 38019.239615206985, 38052.0, 37639.81180094191, 38124.0, 38303.808480957414, 38756.41865371965, 38855.6114438852, 33566.16084597807, 33646.49530257097, 33900.66099338975, 34259.0, 34260.0, 33073.50389744204, 33399.0, 33635.831820840736, 33839.83080084584, 33920.0, 30523.0, 31655.366892662478, 31691.0, 31699.0, 31746.68253317437, 24292.392690182885, 24436.34198973166, 24562.877185614674, 24586.631200532363, 24622.630660539748], "unit": "score", "name": "ext.html", "value": 17951.421948727602}, {"lowerIsBetter": false, "replicates": [333.8781344809145, 335.95632567766194, 339.2147179279965, 341.1046004238873, 346.9271452994871, 16471.670566588677, 16889.662206755875, 17482.475525734215, 17513.91243043786, 17542.210600522976, 15812.762808557882, 15882.3647054118, 16426.753598696, 16597.751033734505, 16613.584660383498, 5185.55922746566, 5189.0, 5327.46725327468, 5399.62202645815, 5402.054640437927, 5240.764165612557, 5291.86770330741, 5298.6555873868265, 5323.920141197885, 5341.145416733323, 4847.2971419144205, 5239.371275446952, 5270.7628156733035, 5273.6572122811895, 5279.815206467774, 5251.737413129347, 5264.31563896691, 5279.973600131994, 5290.285811415468, 5295.258663787073, 4693.460252070987, 4700.529947005272, 4703.7883295251795, 4706.788194531255, 4710.152172608929, 20372.007973740543, 20954.79045209559, 20993.8950305249, 21547.892260538752, 21554.0, 2068.431181425105, 2145.645968415225, 2240.641497360431, 2258.0854753824565, 2348.835581509295, 12814.35928203586, 13072.477100915952, 13119.606411807661, 13341.999350048785, 13382.263975481344, 1764.8235176482249, 1782.1712903499806, 1817.6728188925988, 1844.6034102667857, 1855.16517567094, 52599.47400525984, 53221.0, 60299.69850150721, 61954.0, 68436.6578167111, 14951.551453456414, 14999.550013499613, 15517.0, 15613.297401616728, 15674.451394201149, 21243.575128497654, 24450.510989780105, 24480.632790508156, 24492.510149796904, 24508.877455612608, 8823.867641985375, 8844.115588441104, 8887.422317549339, 8990.145936136056, 9186.08139186076, 7510.887336689954, 7596.354309883612, 7611.086669599573, 7680.846383072308, 7730.845383092306, 7450.701971921062, 7798.220177982156, 7802.297793198526, 7812.92187078122, 7828.765137045898, 2962.3927094945484, 3052.740517056075, 3086.753059755259, 3144.2296637323543, 3227.8547465364113, 903.3676426501476, 926.6571368593528, 957.7318350861769, 996.9252306077074, 1012.7366884609908, 16075.356985720675, 16460.917695411445, 16815.159242037847, 16960.74558881618, 17230.913845430692, 892.4020905993, 897.6319708919442, 897.6947837735071, 908.7236472636137, 926.39784140308, 8954.776130596787, 9657.951710241405, 9703.805923881482, 9780.804383912424, 9835.311528192959, 447239.0, 452670.0, 461048.0, 461417.0, 462828.0, 71226.0, 72102.63948680223, 72436.0, 73165.6341718288, 74003.62998184975, 911.7173676160412, 989.2432289298692, 1000.0499525450706, 1009.1926458832904, 1011.7824667696481, 14915.627109322353, 15094.490550944818, 15310.081395116329, 15535.76696349533, 15664.76502852481, 33850.0, 33879.830600846835, 34778.478322824674, 34973.0, 35098.64901350954, 45712.0, 48302.5169748298, 48693.0, 48829.5117048825, 49226.0, 7280.890786638311, 7418.740344087931, 7459.888101678588, 7485.326320631169, 7599.506032107895, 8764.737057888273, 12102.576409825613, 12242.387880606113, 12314.261144331369, 12469.314187719763, 10303.845442318221, 10486.895131048592, 10525.421101839473, 10835.404052777172, 11035.172362072715, 10706.501089847272, 10745.194110441591, 10762.901234259245, 10938.289011214245, 10991.61529346469, 10847.67456976292, 10852.131829453467, 11043.503042362952, 11210.159238057015, 11706.122040847142, 7251.927480725125, 7562.886556701764, 7664.041994750656, 7705.036870391201, 7712.537247765152, 10893.400862952614, 11012.229143959847, 11066.280691755332, 11074.0, 11120.555177792798], "unit": "score", "name": "jquery.html", "value": 8890.704868786428}, {"lowerIsBetter": false, "replicates": [1375.6148278482028, 1463.3853781411808, 1481.6444053427178, 1639.237754444183, 1754.5525890897827, 6485.708143133558, 7101.928980710191, 7151.570905745659, 7219.927800721991, 7234.240404757504, 6996.8950465743055, 7096.5387249828855, 7145.321194486515, 7161.14066312042, 7234.312740289677, 1904.209752952523, 1942.1163370666384, 2149.451889768107, 2171.5005548723802, 2200.9889950550305, 2118.50215199428, 2138.3691810915816, 2139.0695047654244, 2141.4646338415396, 2141.6787481877764, 2120.586485635302, 2137.5190582118976, 2203.173809821317, 2213.2253711201033, 2214.66779983003, 2171.35944896256, 2200.3728937252886, 2209.5801797658473, 2211.0, 2229.5095079082575, 2195.242641288769, 2202.2622421488823, 2208.094681180708, 2210.204326442479, 2211.248175620297, 7360.77917662471, 7398.0, 7435.702571897117, 7437.628118594049, 7453.0, 4415.801288942005, 4542.545745425464, 4542.863714088583, 4544.0, 4546.636269098464, 2888.292368369742, 2940.588317635533, 3029.9848500757357, 3038.0278310940507, 3043.026231605887, 936.7143021378477, 942.8020115775627, 951.900050494695, 952.8761261036091, 969.6654654144309, 10047.547860346229, 10058.34620749649, 10064.0, 10067.194624430029, 10093.848592271122, 3915.4909861717792, 3963.6829053675638, 3974.602539746002, 4050.1292222171787, 4050.918981620351, 8585.699500517452, 8636.827263454696, 8654.913450865412, 8671.826563468821, 8683.956580217058, 4509.639228861749, 4512.165249428866, 4522.3668686383935, 4527.547245275446, 4528.773561321921, 3667.74325797197, 3898.4542164097047, 3945.309570825094, 3979.224051309967, 3990.0, 3440.2775417162175, 3480.199554102571, 3492.161881148506, 3532.699720523733, 3552.946705799415, 1086.2667699303097, 1100.6642973892879, 1105.6572462536478, 1109.7447587055024, 1131.9624675583916, 1303.2571434282368, 1323.8345206849144, 1326.522451917309, 1335.1455068756004, 1365.0103674835664, 7046.894296585555, 7050.259722729143, 7140.678669459887, 7141.9285807142305, 7151.463640227003, 417.8266019601892, 427.6044658690761, 429.276668813051, 430.49201941709083, 432.692788120438, 5119.462456442095, 5168.043911876315, 5188.247704082887, 5211.5049070338255, 5257.973710131425, 8492.660293588187, 8591.65633374658, 8725.389222754346, 8772.122787721177, 8811.162939520864, 8695.217430431165, 8750.737477875675, 8792.296616270683, 8794.34042446819, 8808.163224493792, 6794.660266986631, 6849.006894000342, 6943.027976083353, 6970.756023539152, 7016.298370162943, 6567.343265673586, 6595.637239951849, 6631.33686631333, 6639.502037347122, 6639.502037347315, 7580.393568514395, 7657.0, 7884.448088633741, 7934.722284720007, 7944.44388892772, 7834.177411371724, 8028.237317454832, 8068.556229407439, 8225.259726624632, 8230.053543842534, 5866.794662186803, 5999.520038397005, 6318.494520438446, 6556.63938483388, 6574.8356291093105, 6862.656867156722, 6871.896921546081, 6917.58494490312, 6922.273161317989, 6927.445804335742, 6168.383161683796, 6607.933920660732, 6625.17185351831, 6642.634655094016, 6660.666966651745, 6349.873002539831, 6397.808065758035, 6509.414152726277, 6546.738130474728, 6601.504887133388, 6606.570572912745, 6647.235567909753, 6653.401193892573, 6679.198496180489, 6682.532222744361, 5493.066178749542, 5524.06090964529, 5579.386267510489, 6206.627602343693, 6214.25428948529, 6153.169322141453, 6225.968870155621, 6253.96873015632, 6257.061440783918, 6265.248170219603], "unit": "score", "name": "mootools.html", "value": 4142.629189470426}, {"lowerIsBetter": false, "replicates": [285.9928501787455, 290.32354613749965, 290.5481975528054, 293.65055583855195, 295.2913008778929, 5640.74616642251, 5713.514351280136, 5733.426657334265, 5734.598578099534, 5744.9138262926035, 5654.547636189105, 5750.309962804469, 5766.855828604276, 5786.334571524276, 5796.971015144929, 4227.513835908864, 4278.61492465678, 4373.322135069069, 4415.028693687384, 4416.911661766762, 4357.629601483878, 4368.825246990116, 4375.015621485156, 4378.430803995476, 4381.9342709859375, 4321.308590625501, 4325.2647050001415, 4332.545082766295, 4336.674749393792, 4364.039911219543, 4158.91682166358, 4246.978765106185, 4325.697201195918, 4333.2200203963275, 4343.370211319357, 3706.7590606610484, 3707.1102935295603, 3709.517762690833, 3715.0898029982827, 3728.254349130185, 5417.51242388183, 5470.070088085035, 5473.06957817172, 5481.808136715196, 5484.0, 4971.403431588233, 4982.551570358685, 4983.127952608279, 4984.053029924339, 4988.251762235657, 6052.606580572248, 6053.334133245339, 6055.666938318391, 6056.21269235001, 6059.606125601866, 3940.783256920868, 4102.487189101363, 4103.835846566134, 4106.938395924063, 4108.219438306712, 6275.18422605063, 6389.488840892717, 6396.616203027833, 6417.871642567123, 6428.132202152697, 4184.686148538872, 4189.141226048653, 4191.622753952159, 4191.769452680071, 4192.119654872511, 4228.04868904494, 4236.4704411948505, 4237.788110594458, 4240.6395456385935, 4241.427407299975, 4972.303877457159, 4974.676646017997, 4982.90034199314, 4985.601151907838, 4991.975040124848, 6119.908201376983, 6124.203853499106, 6126.571140020156, 6127.295361033449, 6132.754689812357, 1446.7902154187584, 1461.817272840895, 1462.722082804264, 1487.7322082025123, 1488.5162322245314, 922.5387306346827, 937.8827646544182, 938.6339327662217, 1034.4569101221919, 1061.2889364126054, 5792.362840087587, 5793.768249270066, 5814.76740930358, 5819.679917604572, 5859.53123750099, 6216.347283535164, 6221.440070393686, 6226.844328891814, 6227.9377206227355, 6238.0, 5797.88404231913, 5806.796762113306, 5829.88340233193, 5851.32709738377, 5852.297724273115, 3333.233356328058, 3363.6972672459597, 3365.5456513370873, 3367.983160084184, 3367.983160084184, 6500.772472963424, 6626.56927299724, 6695.196576410862, 6725.4619630430425, 6740.696668649923, 6420.325865784025, 6472.093906853044, 6476.870462590722, 6540.37866402691, 6545.083688283549, 6714.597124172566, 6717.56335838169, 6722.764703235363, 6722.9663851680425, 6724.394804467622, 6323.620582765049, 6324.588901721373, 6330.3669633036325, 6330.936690633035, 6333.65164915934, 5687.146927960839, 5923.200367950271, 6244.968775156095, 6276.1213430118905, 6292.276388215414, 6436.227652681709, 6490.3185165558825, 6666.200055993312, 6670.032845237316, 6677.265500795006, 4159.292920203512, 4205.990562264973, 4206.558311377261, 4207.221663992172, 4208.452901122834, 6267.341929097379, 6271.560990730787, 6277.937220627735, 6280.9685951569945, 6281.7173227203975, 6119.051547010221, 6127.325994140552, 6131.080337949344, 6131.478824299985, 6135.6932153393045, 5883.61756485827, 5954.4938680210935, 6123.469132716821, 6126.6017708848785, 6137.754489820357, 5830.679312637845, 5937.317208521075, 5940.079287710412, 5941.673207973603, 5950.880982380415, 6051.031834906394, 6065.636061836304, 6071.392860713893, 6075.179850720273, 6078.848028799139, 5958.493528050164, 5972.910406343822, 5974.9402505974385, 5976.372480889444, 5983.37174596661], "unit": "score", "name": "prototype.html", "value": 4542.291944125418}, {"lowerIsBetter": false, "replicates": [754.7811134770917, 759.3848982324314, 765.1965436291893, 826.8222332198576, 832.5439952141185, 13313.933430332847, 13416.798748018788, 13444.52944146955, 13459.125156864797, 13616.727665446697, 12632.368381580907, 12769.744605107891, 12846.100772945898, 13458.5289514867, 13490.25803580805, 834.8580741273983, 846.3483117999137, 851.4465597361702, 852.2457625001887, 852.8891244138254, 839.8320335932807, 840.1430540848331, 844.1347618690829, 845.4842546046906, 846.7078857794052, 870.751835726818, 874.1957399192726, 876.3558784293539, 877.8376000439939, 878.1043335797483, 869.8608222684372, 872.1975782280317, 872.2847265242503, 872.8559787635032, 875.9562021898944, 646.1567654211252, 646.9741210351581, 647.1651569475371, 647.5661306924372, 648.1347401219377, 12425.254484730855, 12491.188072775241, 12541.623751287385, 12552.560660376843, 12561.183523070971, 11532.423378831025, 11565.884341156649, 11665.300081995107, 11686.41567921609, 11927.880721192763, 9804.411735295906, 9897.406155630686, 9953.850692239623, 10062.295639305252, 10135.64525241613, 3463.8960831174854, 3538.610752817188, 3543.94684079739, 3549.361114999299, 3666.211764470652, 47448.76275618634, 47459.0, 47525.52474475245, 47528.524714752755, 49166.262506062434, 8303.95848020756, 8433.367497437717, 8613.913860861436, 8913.910860891308, 9134.588943497558, 16903.901246418944, 16963.406280780113, 17050.74423883643, 17070.658586828442, 17099.145042747812, 7599.430042746816, 7643.9617801911745, 7702.998610180641, 7720.266574675396, 7927.246911543394, 4241.236577416094, 4274.294741367702, 4289.592488713567, 4324.697271191049, 4440.2007638625355, 2984.253936515871, 2989.446952313829, 2992.1621945855195, 3005.1435340927733, 3018.0945716285028, 500.21965733455926, 516.6796586116632, 520.8177138001745, 524.1770420439873, 529.6213207556616, 591.6095377051125, 639.7153266796231, 641.6984017511762, 648.089434344737, 648.503894520692, 8086.0, 8120.796980075545, 8205.425620206648, 8298.8340233195, 8438.59150859255, 548.6324162810846, 634.7238951056305, 640.6764583885201, 644.9516286278454, 649.8570314530796, 3613.747037707335, 3727.6458736419995, 3838.8272527736317, 3995.260876737813, 4039.171969746195, 53969.0, 54027.45972540303, 54439.0, 54733.0, 55141.0, 45923.540764591926, 46218.53781462142, 46647.0, 48414.0, 48616.27075593869, 35378.64621353753, 42530.57469425328, 42883.0, 43126.78436607797, 43509.0, 8243.587820608825, 8279.95860020696, 8333.749987500385, 8464.407491475538, 8474.830503389774, 33034.0, 33245.16887077843, 34152.14619634529, 34282.31435371229, 35209.0, 39181.60818391779, 41691.58308416877, 42729.78635106805, 43307.0, 43540.56459435492, 11273.830892536454, 11402.885971140182, 11413.714657133305, 11625.302481851117, 11928.0457563393, 11581.363025033705, 12071.698207544881, 12305.815412768994, 12872.098953073195, 12962.351882405654, 11390.886091138982, 11652.94173529127, 11884.108691847972, 12094.334811585448, 12481.438335274754, 11577.768444631229, 11879.524819007143, 12144.453499592362, 12264.386780660752, 12306.446209920396, 11540.884591153981, 11772.941135294268, 11934.641960741192, 12112.454939527566, 12288.877111228774, 9166.312526560401, 9886.901130988887, 9968.352057116264, 10177.134943529883, 10251.948740256252, 9110.498922559324, 9390.642328327658, 9527.523623818919, 9882.209423246266, 10052.798944021224], "unit": "score", "name": "yui.html", "value": 6203.725165301285}], "name": "dromaeo_css", "value": 6725.018616121524}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 106.0, 110.0, 110.0, 113.0, 100.0, 109.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [153.0, 124.0, 156.0, 163.0, 130.0, 149.0, 153.0, 127.0, 179.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 146.1}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 160.0, 165.0, 163.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.9}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 92.0, 99.0, 73.0, 74.0, 72.0, 131.0, 96.0, 72.0], "unit": "score", "name": "audio-fft", "value": 85.4}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 140.0, 96.0, 96.0, 96.0, 138.0, 95.0, 98.0, 150.0], "unit": "score", "name": "audio-oscillator", "value": 110.2}, {"lowerIsBetter": true, "replicates": [119.0, 115.0, 114.0, 116.0, 116.0, 115.0, 115.0, 115.0, 115.0, 114.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 115.4}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 145.0, 147.0, 146.0, 145.0, 146.0, 147.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [59.0, 58.0, 59.0, 57.0, 58.0, 58.0, 59.0, 66.0, 57.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.8}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 69.0, 67.0, 69.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.6}, {"lowerIsBetter": true, "replicates": [89.0, 82.0, 83.0, 83.0, 84.0, 82.0, 82.0, 84.0, 82.0, 81.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.2}, {"lowerIsBetter": true, "replicates": [118.0, 120.0, 117.0, 117.0, 119.0, 125.0, 128.0, 121.0, 118.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.4}, {"lowerIsBetter": true, "replicates": [139.0, 143.0, 140.0, 153.0, 155.0, 138.0, 149.0, 152.0, 137.0, 148.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.4}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 74.0, 70.0, 68.0, 68.0, 68.0, 73.0, 67.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.1}], "name": "kraken", "value": 1513.8999999999999}]} 17:20:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1513.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6725.02"}}} 17:20:42 INFO - Return code: 0 17:20:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 17:20:42 INFO - # TBPL SUCCESS # 17:20:42 INFO - Running post-action listener: _resource_record_post_action 17:20:42 INFO - Running post-run listener: _resource_record_post_run 17:20:43 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 551346176; Read time: 12; Write time: 693312 17:20:43 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 250839040; Read time: 0; Write time: 373748 17:20:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296574976; Read time: 12; Write time: 318248 17:20:43 INFO - Running post-run listener: _upload_blobber_files 17:20:43 INFO - Blob upload gear active. 17:20:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 17:20:43 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 17:20:43 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'] 17:20:43 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 17:20:43 INFO - (blobuploader) - INFO - Open directory for files ... 17:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 17:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:44 INFO - (blobuploader) - INFO - Done attempting. 17:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:45 INFO - (blobuploader) - INFO - Done attempting. 17:20:45 INFO - (blobuploader) - INFO - Iteration through files over. 17:20:45 INFO - Return code: 0 17:20:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 17:20:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 17:20:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab68544895fdbd48ec21077f1ba06e91185f6d4a5667639924f6b543643b51b238c6548bebc447d2587421930f8b40267550aa33e72d303ed18d7516e59065f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9dd9d81c4113e7ccc02526061c9585180c0c981af7567efa9aa82a82495dd6dec1c72dfad1deb0b183ac6d0674315f8438f4d52564ff21a5a3581af490725c"} 17:20:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 17:20:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 17:20:45 INFO - Contents: 17:20:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab68544895fdbd48ec21077f1ba06e91185f6d4a5667639924f6b543643b51b238c6548bebc447d2587421930f8b40267550aa33e72d303ed18d7516e59065f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9dd9d81c4113e7ccc02526061c9585180c0c981af7567efa9aa82a82495dd6dec1c72dfad1deb0b183ac6d0674315f8438f4d52564ff21a5a3581af490725c"} 17:20:45 INFO - Running post-run listener: copy_logs_to_upload_dir 17:20:45 INFO - Copying logs to upload dir... 17:20:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 17:20:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1227.689144 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 27 secs) (at 2016-02-26 17:20:45.273293) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-26 17:20:45.276635) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab68544895fdbd48ec21077f1ba06e91185f6d4a5667639924f6b543643b51b238c6548bebc447d2587421930f8b40267550aa33e72d303ed18d7516e59065f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9dd9d81c4113e7ccc02526061c9585180c0c981af7567efa9aa82a82495dd6dec1c72dfad1deb0b183ac6d0674315f8438f4d52564ff21a5a3581af490725c"} build_url:https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/NyU6GMLTRVO25h1DBM8ydA/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab68544895fdbd48ec21077f1ba06e91185f6d4a5667639924f6b543643b51b238c6548bebc447d2587421930f8b40267550aa33e72d303ed18d7516e59065f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f9dd9d81c4113e7ccc02526061c9585180c0c981af7567efa9aa82a82495dd6dec1c72dfad1deb0b183ac6d0674315f8438f4d52564ff21a5a3581af490725c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-26 17:20:45.300963) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:20:45.301301) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456532611.649228-2065249793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-26 17:20:45.387000) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-26 17:20:45.387324) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-26 17:20:45.387614) ========= ========= Total master_lag: 0.29 =========