builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1455948087.1 results: success (0) buildid: 20160219193142 builduid: d57171c04da44282b0779a2fb093dec0 revision: 69ec3dc408a2a720cb2b8210fea33e3504aeec22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.099247) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.099648) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.099937) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006778 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.121618) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.121903) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.135022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.135297) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004705 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.183541) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.183839) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.184184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.184449) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-19 22:01:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2016-02-19 22:01:27 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.358539) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.358873) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021690 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:27.408506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 22:01:27.408816) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 69ec3dc408a2a720cb2b8210fea33e3504aeec22 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 69ec3dc408a2a720cb2b8210fea33e3504aeec22 --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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-19 22:01:27,715 truncating revision to first 12 chars 2016-02-19 22:01:27,715 Setting DEBUG logging. 2016-02-19 22:01:27,715 attempt 1/10 2016-02-19 22:01:27,715 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/69ec3dc408a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-19 22:01:28,463 unpacking tar archive at: mozilla-central-69ec3dc408a2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.003397 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 22:01:28.433317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:28.433774) ========= echo 69ec3dc408a2a720cb2b8210fea33e3504aeec22 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'69ec3dc408a2a720cb2b8210fea33e3504aeec22'] 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 69ec3dc408a2a720cb2b8210fea33e3504aeec22 program finished with exit code 0 elapsedTime=0.004572 script_repo_revision: '69ec3dc408a2a720cb2b8210fea33e3504aeec22' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:28.482411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:28.482687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 22:01:28.492934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-02-19 22:01:28.493223) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:01:28 INFO - MultiFileLogger online at 20160219 22:01:28 in /builds/slave/test-pgo 22:01:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:01:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:01:28 INFO - {'append_to_log': False, 22:01:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:01:28 INFO - 'blob_upload_branch': 'Firefox', 22:01:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:01:28 INFO - 'branch': 'Firefox', 22:01:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:28 INFO - 'config_files': ('talos/linux_config.py',), 22:01:28 INFO - 'default_actions': ('clobber', 22:01:28 INFO - 'read-buildbot-config', 22:01:28 INFO - 'download-and-extract', 22:01:28 INFO - 'populate-webroot', 22:01:28 INFO - 'create-virtualenv', 22:01:28 INFO - 'install', 22:01:28 INFO - 'run-tests'), 22:01:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:28 INFO - 'download_minidump_stackwalk': True, 22:01:28 INFO - 'download_symbols': 'ondemand', 22:01:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:28 INFO - 'installer_path': 'installer.exe', 22:01:28 INFO - 'log_level': 'info', 22:01:28 INFO - 'log_name': 'talos', 22:01:28 INFO - 'log_to_console': True, 22:01:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:01:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:01:28 INFO - 'opt_config_files': (), 22:01:28 INFO - 'pip_index': False, 22:01:28 INFO - 'sps_profile': False, 22:01:28 INFO - 'sps_profile_interval': 0, 22:01:28 INFO - 'suite': 'dromaeojs', 22:01:28 INFO - 'system_bits': '32', 22:01:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:28 INFO - 'title': 'talos-linux64-ix-060', 22:01:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:28 INFO - 'use_talos_json': True, 22:01:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:01:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:28 INFO - 'work_dir': 'build'} 22:01:28 INFO - ##### 22:01:28 INFO - ##### Running clobber step. 22:01:28 INFO - ##### 22:01:28 INFO - Running pre-action listener: _resource_record_pre_action 22:01:28 INFO - Running main action method: clobber 22:01:28 INFO - rmtree: /builds/slave/test-pgo/build 22:01:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:01:29 INFO - Running post-action listener: _resource_record_post_action 22:01:29 INFO - ##### 22:01:29 INFO - ##### Running read-buildbot-config step. 22:01:29 INFO - ##### 22:01:29 INFO - Running pre-action listener: _resource_record_pre_action 22:01:29 INFO - Running main action method: read_buildbot_config 22:01:29 INFO - Using buildbot properties: 22:01:29 INFO - { 22:01:29 INFO - "project": "", 22:01:29 INFO - "product": "firefox", 22:01:29 INFO - "script_repo_revision": "production", 22:01:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:01:29 INFO - "repository": "", 22:01:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:01:29 INFO - "buildid": "20160219193142", 22:01:29 INFO - "pgo_build": "True", 22:01:29 INFO - "basedir": "/builds/slave/test-pgo", 22:01:29 INFO - "buildnumber": 682, 22:01:29 INFO - "slavename": "talos-linux64-ix-060", 22:01:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:01:29 INFO - "platform": "ubuntu64_hw", 22:01:29 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:01:29 INFO - "branch": "mozilla-central", 22:01:29 INFO - "repo_path": "mozilla-central", 22:01:29 INFO - "revision": "69ec3dc408a2a720cb2b8210fea33e3504aeec22", 22:01:29 INFO - "stage_platform": "linux64-pgo", 22:01:29 INFO - "builduid": "d57171c04da44282b0779a2fb093dec0", 22:01:29 INFO - "slavebuilddir": "test" 22:01:29 INFO - } 22:01:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 22:01:29 INFO - Running post-action listener: _resource_record_post_action 22:01:29 INFO - ##### 22:01:29 INFO - ##### Running download-and-extract step. 22:01:29 INFO - ##### 22:01:29 INFO - Running pre-action listener: _resource_record_pre_action 22:01:29 INFO - Running main action method: download_and_extract 22:01:29 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:01: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')]} 22:01:29 INFO - https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/test_packages.json 22:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/test_packages.json 22:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:01:33 INFO - Downloaded 1448 bytes. 22:01:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:01:33 INFO - Using the following test package requirements: 22:01:33 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:01:33 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:01:33 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:01:33 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'jsshell-linux-x86_64.zip'], 22:01:33 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:01:33 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:01:33 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:01:33 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:01:33 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:01:33 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:01:33 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:33 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:01:33 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:33 INFO - https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:01:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:01:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:01:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:36 INFO - Downloaded 23349749 bytes. 22:01:36 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'] 22:01:36 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 22:01:37 INFO - Return code: 0 22:01:37 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 22:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:37 INFO - https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:01:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:01:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:01:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:38 INFO - Downloaded 23349749 bytes. 22:01:38 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'] 22:01:38 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 22:01:39 INFO - Return code: 0 22:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:39 INFO - https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:01:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:40 INFO - Downloaded 10707015 bytes. 22:01:40 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'] 22:01:40 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 22:01:40 INFO - Return code: 0 22:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:40 INFO - https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jqoh1bttQu-i2GmytFawZw/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 22:01:42 INFO - Downloaded 56598516 bytes. 22:01:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:01:42 INFO - mkdir: /builds/slave/test-pgo/properties 22:01:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:01:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:01:42 INFO - Contents: 22:01:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:01:43 INFO - Running post-action listener: _resource_record_post_action 22:01:43 INFO - Running post-action listener: set_extra_try_arguments 22:01:43 INFO - ##### 22:01:43 INFO - ##### Running populate-webroot step. 22:01:43 INFO - ##### 22:01:43 INFO - Running pre-action listener: _resource_record_pre_action 22:01:43 INFO - Running main action method: populate_webroot 22:01:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:43 INFO - '--tppagecycles', 22:01:43 INFO - '7'], 22:01:43 INFO - 'tests': ['tsvgm']}}, 22:01:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:01:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:01:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'tests': ['damp', 'tps']}, 22:01:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['damp', 'tps']}, 22:01:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:01:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:43 INFO - 'other': {'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'other_l64': {'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'other_nol64': {'tests': ['a11yr', 22:01:43 INFO - 'ts_paint', 22:01:43 INFO - 'tpaint', 22:01:43 INFO - 'sessionrestore', 22:01:43 INFO - 'sessionrestore_no_auto_restore']}, 22:01:43 INFO - 'svgr': {'tests': ['tsvgx', 22:01:43 INFO - 'tsvgr_opacity', 22:01:43 INFO - 'tart', 22:01:43 INFO - 'tscrollx', 22:01:43 INFO - 'cart']}, 22:01:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['tsvgx', 22:01:43 INFO - 'tsvgr_opacity', 22:01:43 INFO - 'tart', 22:01:43 INFO - 'tscrollx', 22:01:43 INFO - 'cart']}, 22:01:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'tests': ['tp5o']}, 22:01:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'talos_options': ['--e10s'], 22:01:43 INFO - 'tests': ['tp5o']}, 22:01:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'talos_options': ['--xperf_path', 22:01:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:43 INFO - 'tests': ['tp5n']}, 22:01:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:43 INFO - 'talos_options': ['--e10s', 22:01:43 INFO - '--xperf_path', 22:01:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:43 INFO - 'tests': ['tp5n']}}, 22:01:43 INFO - 'talos.zip': {'path': '', 22:01:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:43 INFO - Running post-action listener: _resource_record_post_action 22:01:43 INFO - ##### 22:01:43 INFO - ##### Running create-virtualenv step. 22:01:43 INFO - ##### 22:01:43 INFO - Running pre-action listener: _resource_record_pre_action 22:01:43 INFO - Running main action method: create_virtualenv 22:01:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:01:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 22:01:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:01:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:43 INFO - Using real prefix '/usr' 22:01:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:01:43 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:45 INFO - Installing pip.................done. 22:01:45 INFO - Return code: 0 22:01:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:01: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')]} 22:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 22:01: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 pip>=1.5 22:01:45 INFO - Using env: {'DISPLAY': ':0', 22:01:45 INFO - 'HOME': '/home/cltbld', 22:01:45 INFO - 'LANG': 'en_US.UTF-8', 22:01:45 INFO - 'LANGUAGE': 'en_US:en', 22:01:45 INFO - 'LOGNAME': 'cltbld', 22:01:45 INFO - 'MAIL': '/var/mail/cltbld', 22:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:45 INFO - 'MOZ_NO_REMOTE': '1', 22:01:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:45 INFO - 'NO_EM_RESTART': '1', 22:01:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:45 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:45 INFO - 'SHELL': '/bin/bash', 22:01:45 INFO - 'SHLVL': '1', 22:01:45 INFO - 'TERM': 'linux', 22:01:45 INFO - 'TMOUT': '86400', 22:01:45 INFO - 'USER': 'cltbld', 22:01:45 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:45 INFO - '_': '/tools/buildbot/bin/python'} 22:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:01:45 INFO - Cleaning up... 22:01:45 INFO - Return code: 0 22:01:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:01: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')]} 22:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 22:01: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 psutil>=0.7.1 22:01:45 INFO - Using env: {'DISPLAY': ':0', 22:01:45 INFO - 'HOME': '/home/cltbld', 22:01:45 INFO - 'LANG': 'en_US.UTF-8', 22:01:45 INFO - 'LANGUAGE': 'en_US:en', 22:01:45 INFO - 'LOGNAME': 'cltbld', 22:01:45 INFO - 'MAIL': '/var/mail/cltbld', 22:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:45 INFO - 'MOZ_NO_REMOTE': '1', 22:01:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:45 INFO - 'NO_EM_RESTART': '1', 22:01:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:45 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:45 INFO - 'SHELL': '/bin/bash', 22:01:45 INFO - 'SHLVL': '1', 22:01:45 INFO - 'TERM': 'linux', 22:01:45 INFO - 'TMOUT': '86400', 22:01:45 INFO - 'USER': 'cltbld', 22:01:45 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:45 INFO - '_': '/tools/buildbot/bin/python'} 22:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:46 INFO - Downloading/unpacking psutil>=0.7.1 22:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:48 INFO - Installing collected packages: psutil 22:01:48 INFO - Running setup.py install for psutil 22:01:48 INFO - building 'psutil._psutil_linux' extension 22:01:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:01:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:01:48 INFO - building 'psutil._psutil_posix' extension 22:01:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:01:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:01:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:48 INFO - Successfully installed psutil 22:01:48 INFO - Cleaning up... 22:01:48 INFO - Return code: 0 22:01:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:01: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')]} 22:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 22:01: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 mozsystemmonitor==0.0.0 22:01:48 INFO - Using env: {'DISPLAY': ':0', 22:01:48 INFO - 'HOME': '/home/cltbld', 22:01:48 INFO - 'LANG': 'en_US.UTF-8', 22:01:48 INFO - 'LANGUAGE': 'en_US:en', 22:01:48 INFO - 'LOGNAME': 'cltbld', 22:01:48 INFO - 'MAIL': '/var/mail/cltbld', 22:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:48 INFO - 'MOZ_NO_REMOTE': '1', 22:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:48 INFO - 'NO_EM_RESTART': '1', 22:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:48 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:48 INFO - 'SHELL': '/bin/bash', 22:01:48 INFO - 'SHLVL': '1', 22:01:48 INFO - 'TERM': 'linux', 22:01:48 INFO - 'TMOUT': '86400', 22:01:48 INFO - 'USER': 'cltbld', 22:01:48 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:48 INFO - '_': '/tools/buildbot/bin/python'} 22:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:01:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:01:50 INFO - Installing collected packages: mozsystemmonitor 22:01:50 INFO - Running setup.py install for mozsystemmonitor 22:01:50 INFO - Successfully installed mozsystemmonitor 22:01:50 INFO - Cleaning up... 22:01:50 INFO - Return code: 0 22:01:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:50 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:50 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 22:01:50 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 22:01:50 INFO - Using env: {'DISPLAY': ':0', 22:01:50 INFO - 'HOME': '/home/cltbld', 22:01:50 INFO - 'LANG': 'en_US.UTF-8', 22:01:50 INFO - 'LANGUAGE': 'en_US:en', 22:01:50 INFO - 'LOGNAME': 'cltbld', 22:01:50 INFO - 'MAIL': '/var/mail/cltbld', 22:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:50 INFO - 'MOZ_NO_REMOTE': '1', 22:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:50 INFO - 'NO_EM_RESTART': '1', 22:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:50 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:50 INFO - 'SHELL': '/bin/bash', 22:01:50 INFO - 'SHLVL': '1', 22:01:50 INFO - 'TERM': 'linux', 22:01:50 INFO - 'TMOUT': '86400', 22:01:50 INFO - 'USER': 'cltbld', 22:01:50 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:50 INFO - '_': '/tools/buildbot/bin/python'} 22:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:01:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:53 INFO - Downloading docopt-0.6.1.tar.gz 22:01:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:53 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:53 INFO - Running setup.py install for blobuploader 22:01:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:01:53 INFO - Running setup.py install for requests 22:01:53 INFO - Running setup.py install for docopt 22:01:54 INFO - Successfully installed blobuploader requests docopt 22:01:54 INFO - Cleaning up... 22:01:54 INFO - Return code: 0 22:01:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:54 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:54 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 22:01:54 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 22:01:54 INFO - Using env: {'DISPLAY': ':0', 22:01:54 INFO - 'HOME': '/home/cltbld', 22:01:54 INFO - 'LANG': 'en_US.UTF-8', 22:01:54 INFO - 'LANGUAGE': 'en_US:en', 22:01:54 INFO - 'LOGNAME': 'cltbld', 22:01:54 INFO - 'MAIL': '/var/mail/cltbld', 22:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:54 INFO - 'MOZ_NO_REMOTE': '1', 22:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:54 INFO - 'NO_EM_RESTART': '1', 22:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:54 INFO - 'SHELL': '/bin/bash', 22:01:54 INFO - 'SHLVL': '1', 22:01:54 INFO - 'TERM': 'linux', 22:01:54 INFO - 'TMOUT': '86400', 22:01:54 INFO - 'USER': 'cltbld', 22:01:54 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:54 INFO - '_': '/tools/buildbot/bin/python'} 22:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:01:54 INFO - Running setup.py (path:/tmp/pip-qfhWGD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:01:54 INFO - Running setup.py (path:/tmp/pip-Q5wrAT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:01:54 INFO - Running setup.py (path:/tmp/pip-5QbhLs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:01:54 INFO - Running setup.py (path:/tmp/pip-62u4gx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:01:54 INFO - Running setup.py (path:/tmp/pip-BDX2I2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:01:54 INFO - Running setup.py (path:/tmp/pip-vmA_jR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:01:54 INFO - Running setup.py (path:/tmp/pip-x6fe8H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:01:54 INFO - Running setup.py (path:/tmp/pip-xmQ1Kg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:01:54 INFO - Running setup.py (path:/tmp/pip-Wumgca-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:01:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:01:54 INFO - Running setup.py (path:/tmp/pip-DuV0LY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:01:55 INFO - Running setup.py (path:/tmp/pip-dnnCFf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:01:55 INFO - Running setup.py (path:/tmp/pip-jx7RzN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:01:55 INFO - Running setup.py (path:/tmp/pip-4pz9HP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:01:55 INFO - Running setup.py (path:/tmp/pip-4NF12h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:01:55 INFO - Running setup.py (path:/tmp/pip-jdRJ5x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:01:55 INFO - Running setup.py (path:/tmp/pip-W6pcRQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:01:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:01:55 INFO - Running setup.py (path:/tmp/pip-NXODwA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:01:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:55 INFO - Running setup.py install for manifestparser 22:01:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:01:55 INFO - Running setup.py install for mozcrash 22:01:55 INFO - Running setup.py install for mozdebug 22:01:55 INFO - Running setup.py install for mozdevice 22:01:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:01:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:01:55 INFO - Running setup.py install for mozfile 22:01:56 INFO - Running setup.py install for mozhttpd 22:01:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for mozinfo 22:01:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for mozInstall 22:01:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for mozleak 22:01:56 INFO - Running setup.py install for mozlog 22:01:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for moznetwork 22:01:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for mozprocess 22:01:56 INFO - Running setup.py install for mozprofile 22:01:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:01:56 INFO - Running setup.py install for mozrunner 22:01:56 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:01:57 INFO - Running setup.py install for mozscreenshot 22:01:57 INFO - Running setup.py install for moztest 22:01:57 INFO - Running setup.py install for mozversion 22:01:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:01:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:57 INFO - Cleaning up... 22:01:57 INFO - Return code: 0 22:01:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:57 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:01:57 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 22:01:57 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 22:01:57 INFO - Using env: {'DISPLAY': ':0', 22:01:57 INFO - 'HOME': '/home/cltbld', 22:01:57 INFO - 'LANG': 'en_US.UTF-8', 22:01:57 INFO - 'LANGUAGE': 'en_US:en', 22:01:57 INFO - 'LOGNAME': 'cltbld', 22:01:57 INFO - 'MAIL': '/var/mail/cltbld', 22:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:57 INFO - 'MOZ_NO_REMOTE': '1', 22:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:57 INFO - 'NO_EM_RESTART': '1', 22:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:01:57 INFO - 'PWD': '/builds/slave/test-pgo', 22:01:57 INFO - 'SHELL': '/bin/bash', 22:01:57 INFO - 'SHLVL': '1', 22:01:57 INFO - 'TERM': 'linux', 22:01:57 INFO - 'TMOUT': '86400', 22:01:57 INFO - 'USER': 'cltbld', 22:01:57 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:57 INFO - '_': '/tools/buildbot/bin/python'} 22:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:01:57 INFO - Running setup.py (path:/tmp/pip-DTX_v8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:01:57 INFO - Running setup.py (path:/tmp/pip-mJwt3t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:01:57 INFO - Running setup.py (path:/tmp/pip-aIceRA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:01:57 INFO - Running setup.py (path:/tmp/pip-NpOU13-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:01:57 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)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:01:57 INFO - Running setup.py (path:/tmp/pip-c2GYLk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:01:57 INFO - Running setup.py (path:/tmp/pip-iT1uog-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 22:01:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:01:57 INFO - Running setup.py (path:/tmp/pip-cZDB4S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:01:58 INFO - Running setup.py (path:/tmp/pip-4HSxld-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:01:58 INFO - Running setup.py (path:/tmp/pip-ZQ14gG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:01:58 INFO - Running setup.py (path:/tmp/pip-mGW2qY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:01:58 INFO - Running setup.py (path:/tmp/pip-Wy2z6D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:01:58 INFO - Running setup.py (path:/tmp/pip-S4oTWa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:01:58 INFO - Running setup.py (path:/tmp/pip-XGGHjW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:01:58 INFO - Running setup.py (path:/tmp/pip-MRFxyy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:01:58 INFO - Running setup.py (path:/tmp/pip-TyaabL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:01:58 INFO - Running setup.py (path:/tmp/pip-vRZthA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 22:01:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:01:58 INFO - Running setup.py (path:/tmp/pip-4SzOZC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:01:58 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)) 22:01:58 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)) 22:01:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:00 INFO - Downloading blessings-1.6.tar.gz 22:02:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:02:00 INFO - Installing collected packages: blessings 22:02:00 INFO - Running setup.py install for blessings 22:02:00 INFO - Successfully installed blessings 22:02:00 INFO - Cleaning up... 22:02:00 INFO - Return code: 0 22:02:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:02:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:02:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:02:01 INFO - Reading from file tmpfile_stdout 22:02:01 INFO - Current package versions: 22:02:01 INFO - argparse == 1.2.1 22:02:01 INFO - blessings == 1.6 22:02:01 INFO - blobuploader == 1.2.4 22:02:01 INFO - docopt == 0.6.1 22:02:01 INFO - manifestparser == 1.1 22:02:01 INFO - mozInstall == 1.12 22:02:01 INFO - mozcrash == 0.16 22:02:01 INFO - mozdebug == 0.1 22:02:01 INFO - mozdevice == 0.48 22:02:01 INFO - mozfile == 1.2 22:02:01 INFO - mozhttpd == 0.7 22:02:01 INFO - mozinfo == 0.9 22:02:01 INFO - mozleak == 0.1 22:02:01 INFO - mozlog == 3.1 22:02:01 INFO - moznetwork == 0.27 22:02:01 INFO - mozprocess == 0.22 22:02:01 INFO - mozprofile == 0.28 22:02:01 INFO - mozrunner == 6.11 22:02:01 INFO - mozscreenshot == 0.1 22:02:01 INFO - mozsystemmonitor == 0.0 22:02:01 INFO - moztest == 0.7 22:02:01 INFO - mozversion == 1.4 22:02:01 INFO - psutil == 3.1.1 22:02:01 INFO - requests == 1.2.3 22:02:01 INFO - wsgiref == 0.1.2 22:02:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:01 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:01 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 22:02:01 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 22:02:01 INFO - Using env: {'DISPLAY': ':0', 22:02:01 INFO - 'HOME': '/home/cltbld', 22:02:01 INFO - 'LANG': 'en_US.UTF-8', 22:02:01 INFO - 'LANGUAGE': 'en_US:en', 22:02:01 INFO - 'LOGNAME': 'cltbld', 22:02:01 INFO - 'MAIL': '/var/mail/cltbld', 22:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:01 INFO - 'MOZ_NO_REMOTE': '1', 22:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:01 INFO - 'NO_EM_RESTART': '1', 22:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:02:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:02:01 INFO - 'SHELL': '/bin/bash', 22:02:01 INFO - 'SHLVL': '1', 22:02:01 INFO - 'TERM': 'linux', 22:02:01 INFO - 'TMOUT': '86400', 22:02:01 INFO - 'USER': 'cltbld', 22:02:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:01 INFO - '_': '/tools/buildbot/bin/python'} 22:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:02:01 INFO - Cleaning up... 22:02:01 INFO - Return code: 0 22:02:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:01 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:01 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 22:02:01 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 22:02:01 INFO - Using env: {'DISPLAY': ':0', 22:02:01 INFO - 'HOME': '/home/cltbld', 22:02:01 INFO - 'LANG': 'en_US.UTF-8', 22:02:01 INFO - 'LANGUAGE': 'en_US:en', 22:02:01 INFO - 'LOGNAME': 'cltbld', 22:02:01 INFO - 'MAIL': '/var/mail/cltbld', 22:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:01 INFO - 'MOZ_NO_REMOTE': '1', 22:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:01 INFO - 'NO_EM_RESTART': '1', 22:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:02:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:02:01 INFO - 'SHELL': '/bin/bash', 22:02:01 INFO - 'SHLVL': '1', 22:02:01 INFO - 'TERM': 'linux', 22:02:01 INFO - 'TMOUT': '86400', 22:02:01 INFO - 'USER': 'cltbld', 22:02:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:01 INFO - '_': '/tools/buildbot/bin/python'} 22:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:01 INFO - Downloading/unpacking jsonschema 22:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:02:03 INFO - Downloading/unpacking functools32 (from jsonschema) 22:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:02:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:02:03 INFO - warning: no files found matching '*.txt' 22:02:03 INFO - no previously-included directories found matching 'build' 22:02:03 INFO - no previously-included directories found matching 'dist' 22:02:03 INFO - no previously-included directories found matching '.git*' 22:02:03 INFO - Installing collected packages: jsonschema, functools32 22:02:03 INFO - Running setup.py install for functools32 22:02:03 INFO - warning: no files found matching '*.txt' 22:02:03 INFO - no previously-included directories found matching 'build' 22:02:03 INFO - no previously-included directories found matching 'dist' 22:02:03 INFO - no previously-included directories found matching '.git*' 22:02:03 INFO - Successfully installed jsonschema functools32 22:02:03 INFO - Cleaning up... 22:02:03 INFO - Return code: 0 22:02:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:02:03 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 0x7fb158c77f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, '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': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:02:03 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 22:02:03 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 22:02:03 INFO - Using env: {'DISPLAY': ':0', 22:02:03 INFO - 'HOME': '/home/cltbld', 22:02:03 INFO - 'LANG': 'en_US.UTF-8', 22:02:03 INFO - 'LANGUAGE': 'en_US:en', 22:02:03 INFO - 'LOGNAME': 'cltbld', 22:02:03 INFO - 'MAIL': '/var/mail/cltbld', 22:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:03 INFO - 'MOZ_NO_REMOTE': '1', 22:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:03 INFO - 'NO_EM_RESTART': '1', 22:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:02:03 INFO - 'PWD': '/builds/slave/test-pgo', 22:02:03 INFO - 'SHELL': '/bin/bash', 22:02:03 INFO - 'SHLVL': '1', 22:02:03 INFO - 'TERM': 'linux', 22:02:03 INFO - 'TMOUT': '86400', 22:02:03 INFO - 'USER': 'cltbld', 22:02:03 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:03 INFO - '_': '/tools/buildbot/bin/python'} 22:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:02:03 INFO - Downloading/unpacking flake8 22:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:02:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:02:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:02:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:02:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:02:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:02:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:02:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:02:06 INFO - no previously-included directories found matching 'docs/_build' 22:02:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:02:06 INFO - Running setup.py install for pep8 22:02:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:02:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:02:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:02:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:02:06 INFO - no previously-included directories found matching 'docs/_build' 22:02:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:02:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:02:06 INFO - Cleaning up... 22:02:06 INFO - Return code: 0 22:02:06 INFO - Running post-action listener: _resource_record_post_action 22:02:06 INFO - Running post-action listener: _start_resource_monitoring 22:02:06 INFO - Starting resource monitoring. 22:02:06 INFO - ##### 22:02:06 INFO - ##### Running install step. 22:02:06 INFO - ##### 22:02:06 INFO - Running pre-action listener: _resource_record_pre_action 22:02:06 INFO - Running main action method: install 22:02:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:02:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:02:06 INFO - Reading from file tmpfile_stdout 22:02:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:02:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:02:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:02:07 INFO - Reading from file tmpfile_stdout 22:02:07 INFO - Output received: 22:02:07 INFO - Usage: mozinstall [options] installer 22:02:07 INFO - Options: 22:02:07 INFO - -h, --help show this help message and exit 22:02:07 INFO - -d DEST, --destination=DEST 22:02:07 INFO - Directory to install application into. [default: 22:02:07 INFO - "/builds/slave/test-pgo"] 22:02:07 INFO - --app=APP Application being installed. [default: firefox] 22:02:07 INFO - mkdir: /builds/slave/test-pgo/build/application 22:02:07 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'] 22:02:07 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 22:02:23 INFO - Reading from file tmpfile_stdout 22:02:23 INFO - Output received: 22:02:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:02:23 INFO - Running post-action listener: _resource_record_post_action 22:02:23 INFO - ##### 22:02:23 INFO - ##### Running run-tests step. 22:02:23 INFO - ##### 22:02:23 INFO - Running pre-action listener: _resource_record_pre_action 22:02:23 INFO - Running main action method: run_tests 22:02:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:02:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:02:23 INFO - Python 2.7.3 22:02:23 INFO - Return code: 0 22:02:23 INFO - grabbing minidump binary from tooltool 22:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:02:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233f8c0>, '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 0x23407c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2340c50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:02:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 22:02:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:02:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:02:23 INFO - Return code: 0 22:02:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:02:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:02:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:02:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:02:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:02:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:02:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:02:24 INFO - Return code: 0 22:02:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 22:02:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 22:02:24 INFO - Using env: {'DISPLAY': ':0', 22:02:24 INFO - 'HOME': '/home/cltbld', 22:02:24 INFO - 'LANG': 'en_US.UTF-8', 22:02:24 INFO - 'LANGUAGE': 'en_US:en', 22:02:24 INFO - 'LOGNAME': 'cltbld', 22:02:24 INFO - 'MAIL': '/var/mail/cltbld', 22:02:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:02:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:24 INFO - 'MOZ_NO_REMOTE': '1', 22:02:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:02:24 INFO - 'NO_EM_RESTART': '1', 22:02:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:02:24 INFO - 'PWD': '/builds/slave/test-pgo', 22:02:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:02:24 INFO - 'SHELL': '/bin/bash', 22:02:24 INFO - 'SHLVL': '1', 22:02:24 INFO - 'TERM': 'linux', 22:02:24 INFO - 'TMOUT': '86400', 22:02:24 INFO - 'USER': 'cltbld', 22:02:24 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721', 22:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:24 INFO - '_': '/tools/buildbot/bin/python'} 22:02:24 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 22:02:24 INFO - mozversion application_buildid: 20160219193142 22:02:24 INFO - mozversion application_changeset: 69ec3dc408a2a720cb2b8210fea33e3504aeec22 22:02:24 INFO - mozversion application_display_name: Nightly 22:02:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:02:24 INFO - mozversion application_name: Firefox 22:02:24 INFO - mozversion application_remotingname: firefox 22:02:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:02:24 INFO - mozversion application_vendor: Mozilla 22:02:24 INFO - mozversion application_version: 47.0a1 22:02:24 INFO - mozversion platform_buildid: 20160219193142 22:02:24 INFO - mozversion platform_changeset: 69ec3dc408a2a720cb2b8210fea33e3504aeec22 22:02:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:02:24 INFO - mozversion platform_version: 47.0a1 22:02:24 INFO - using testdate: 1455948144 22:02:24 INFO - actual date: 1455948144 22:02:24 INFO - starting webserver on 'localhost:40943' 22:02:24 INFO - SUITE-START | Running 2 tests 22:02:24 INFO - TEST-START | dromaeo_css 22:02:24 INFO - operating with platform_type : linux_ 22:02:24 INFO - Initialising browser for dromaeo_css test... 22:02:24 INFO - TEST-INFO | started process 20664 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKA8tVb/profile http://localhost:40943/getInfo.html) 22:02:28 INFO - PROCESS | 20664 | __metrics Screen width/height:1600/1200 22:02:28 INFO - PROCESS | 20664 | colorDepth:24 22:02:28 INFO - PROCESS | 20664 | Browser inner width/height: 1024/697 22:02:28 INFO - PROCESS | 20664 | __metrics 22:02:29 INFO - PROCESS | 20664 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:30 INFO - TEST-INFO | 20664: exit 0 22:02:30 INFO - Browser initialized. 22:02:30 INFO - Running cycle 1/1 for dromaeo_css test... 22:02:30 INFO - TEST-INFO | started process 20758 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKA8tVb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:42 INFO - PROCESS | 20758 | RSS: Main: 147296256 22:02:42 INFO - PROCESS | 20758 | 22:03:33 INFO - PROCESS | 20758 | 1455948213656 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] 22:05:41 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40943/tests/dromaeo/cssquery-ext.html) 22:05:42 INFO - PROCESS | 20758 | RSS: Main: 259883008 22:05:42 INFO - PROCESS | 20758 | 22:08:45 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-ext.html (next: http://localhost:40943/tests/dromaeo/cssquery-jquery.html) 22:08:45 INFO - PROCESS | 20758 | RSS: Main: 240676864 22:08:45 INFO - PROCESS | 20758 | 22:11:47 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40943/tests/dromaeo/cssquery-mootools.html) 22:11:48 INFO - PROCESS | 20758 | RSS: Main: 236294144 22:11:48 INFO - PROCESS | 20758 | 22:14:50 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40943/tests/dromaeo/cssquery-prototype.html) 22:14:50 INFO - PROCESS | 20758 | RSS: Main: 211972096 22:14:50 INFO - PROCESS | 20758 | 22:17:53 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40943/tests/dromaeo/cssquery-yui.html) 22:17:54 INFO - PROCESS | 20758 | RSS: Main: 213565440 22:17:54 INFO - PROCESS | 20758 | 22:20:56 INFO - PROCESS | 20758 | Cycle 1(1): loaded http://localhost:40943/tests/dromaeo/cssquery-yui.html (next: http://localhost:40943/tests/dromaeo/cssquery-dojo.html) 22:20:56 INFO - PROCESS | 20758 | RSS: Main: 302923776 22:20:56 INFO - PROCESS | 20758 | 22:20:56 INFO - PROCESS | 20758 | __start_tp_report 22:20:56 INFO - PROCESS | 20758 | _x_x_mozilla_page_load 22:20:56 INFO - PROCESS | 20758 | _x_x_mozilla_page_load_details 22:20:56 INFO - PROCESS | 20758 | |i|pagename|runs| 22:20:56 INFO - PROCESS | 20758 | |0;dojo.html;3106.316610345724;3138.042896916441;3138.2468207630177;3140.262038420969;3169.033444799337;4949.52979466951;5010.524000219978;5047.091523525769;5064.696118232909;5076.36545431821;5076.74616269186;5087.821926232583;5108.948910510894;5207.609429292799;5220.138677118281;4642.651801114913;4648.233041548149;4664.76676166192;4667.88330291742;4741.691790033654;4737.123632128068;4782.497837727024;4792.376990991166;4794.065157294329;4808.591269742059;4598.149342371656;4623.283391074388;4706.7646617669325;4709.740964246965;4764.857054288377;4687.95312046882;4690.4605970313505;4691.319758634979;4714.622830173578;4729.196036673774;4605.792739326705;4626.86119416418;4668.253079507296;4672.696274742165;4702.3181638662545;4784.976075119602;4918.336024636664;5040.873978150539;5052.545270925634;5081.237814327844;5473.507384335429;5512.972435137839;5516.420775818521;5518.641288316287;5520.944790552083;5401.891962160735;5444.537214336786;5546.86132846678;5558.777648894039;5560.499555040025;5104.234364845266;5123.077845987721;5127.79488820443;5130.743462826844;5170.819021334235;5562.860928476821;5566.415526369673;5568;5568.248286481273;5570.805021824217;5314.787408503694;5316.0696878046965;5384.757685904144;5389.7035663038905;5419.69927217465;5453.072977593819;5460.726963651801;5460.918086228709;5461.563074953994;5465.07093794056;5315.441878602768;5348.1442969124755;5353.9827432788425;5354.732263386815;5404.32445944257;5461.672299662033;5477.7261136943;5477.904419116193;5516.834494965157;5564.8330550083565;4220.092680073799;4221.197972385237;4221.324588065895;4235;4262.019735460862;3720.7581507201944;3797.582265950743;3838.6353296436796;3864.381698928158;3868.3423817951016;5015.673981191211;5060.291559181717;5060.924086138711;5062.164742817467;5081.618878584047;5441.129419292894;5454.563634909197;5455;5473.823128027493;5497.642653227527;4920.114379411669;5021.849344519597;5043.268726034705;5068.974655126701;5099.617528685363;4612.3542704021465;4618.861434156981;4651.093036857848;4660.650451216105;4696.976515117402;5499.120140777536;5626.071698169756;5630.380658127603;5636.098224284095;5642.689652069175;5448.237246785532;5449.073657478159;5466.4260252674485;5476.04169270384;5523.28197334344;5601.187827765033;5626.352969408406;5634.380218175916;5642.125470552071;5645.040343141758;5283.418823929288;5391.541718953933;5413.891722165614;5452.182172674131;5495.807646732345;5493.395726470165;5512.200730894078;5517.917231241454;5519.061759500974;5528.336599608073;5360.16917377807;5363.19552067193;5365.302510673588;5373.11343628297;5420.4850539198715;5244.475552444725;5276.551493122974;5356.544693700923;5373.758180881791;5426.457354264542;5167.405748338989;5223.7910483580235;5224.346956630421;5225.111731005814;5289.18017707256;5212.921806172834;5308.708021058879;5322.920156197738;5329.467053294639;5336.386315573759;5218.582513398843;5220.477952204901;5243.973780131075;5252.606054545848;5291.656042357235 22:20:56 INFO - PROCESS | 20758 | |1;ext.html;505.8887044850134;507.97714102865393;516.591892405;528.8175579425098;538.3701069748394;28445.43109137816;36573.08567285819;36759.632403675954;36815.631843681556;36834.631653683486;39370.803145984304;39763.403548946786;39820.402693959615;39868.60131398685;39907.802765917;8494.617742201615;8721;8730;8730.86903696442;8733.650653973866;8473.703420380289;8582.570871456433;8594.570271486433;8602.526861022641;8771.429857059271;8763.386562940597;8815.691450799224;8838.734837954871;8848.026717061119;8857.247133993618;8683.261922736576;8774.47353158806;8788.824223515494;8790.033096359428;8794.51630160341;8632;8748.125187481264;8759;8767.51778652174;8772.649094036231;39592.40611390831;40143.799281003405;40145.59854401448;40232.79883600563;40336.1932761346;3025.3495498467714;3268.297316077031;3338.749593780452;3558.2705545363137;3798.4302354646748;29547.70452295471;29838.55242171369;30057.69942300571;30201;30225.848870755726;3933.7836418996944;3953.3081710700512;4007.1184339445276;4093.4269202311702;4099.8360065597635;265025;265232;266142;266303.6684816583;266482;51591.74204128955;56918.7154064227;57595;57873;58079.70960145172;46242.76878615653;61764;62329.688351558856;62667.68666156731;62680.37319626745;14647.340869660891;14827.77758333626;14841.851581484047;14869.405223791144;14890.032147910351;9670.90329096714;10225.386476811414;10361.481925903674;11332.2067455279;11424.257423267461;18201.180946857425;18293.7255941161;18562;18957;19468.70796938047;6752.189737231465;6960.698166713191;6989.965050174818;7048.753293634698;7095.113110861143;883.7613844262141;949.1464653506785;952.8475443929077;979.647326962293;1049.92125590581;25173.370665733502;25347.746522534537;25525.61711574328;25686.871565642054;25810.35474113162;964.5032808103832;978.1099199728213;987.1806400687412;1005.5324274212526;1027.7841653252751;13512.662183445493;13694;13822.585322440344;13891.4443422264;13895.374708138159;305494;306114;309921;309955;310468;51764;52018.73990630098;52047.47952520502;52049.479505204465;52132.73933630308;1199.5201919232377;1205.4274219745726;1230.704630888598;1234.2471092633486;1245.059979715309;22459;24742.752572474044;25983.220503384928;26405.33986650349;26461.735382645926;42221.9889110534;42379;42668;42953;43012.13975720531;189918;190811;190873;343702;350050;27392.315192120357;31078.533821992234;31297.530537042418;31438;31996.68003319937;37553;37683.81158094192;38207.617923820406;38323;38494.615053849106;32138;35718.46422303616;35804.283914321044;36210;36390.818045909604;33161.66838331586;34714.13214669654;34912;35487.46768798522;35555.82222088873;34329.31341373209;34438.82780586081;34597.827010864785;34667.653323466446;34697.306053878274;31248.375032499676;31589.842050790518;31934.36131277408;31948.840255799503;32137.875174368786;23142.400254268487;23169.18907838285;23440.531189375775;23446;24601 22:20:57 INFO - PROCESS | 20758 | |2;jquery.html;348.6583148514456;349.3990336621012;350.3203784657764;352.80771979271304;353.1401038471321;13720.108192967451;14171.92914035431;16424.343026278948;17076.743848842278;17648.294068237272;16154.353825846982;16292.42976495823;17136.400225992093;17244.82755172448;17353.826461735378;5102.668326558771;5367.1680889462;5383.353997520303;5385.596080293974;5392.2181283713835;3926.803659817012;5265.183896496049;5665.8583535411735;5797.985352563305;5856.385079566648;5645.407232240617;5752.712364381785;5760.308762948452;5779.479846813786;5792.247007888968;5715.428457154251;5726.3987281335485;5734.713264336809;5754.165645981351;5758.769649214048;4981.352424184833;5003.149464591028;5063.088644044071;5090.49095090488;5101.923471147936;20177.495562610904;20439;20670.276540321167;20698.896505517525;20849.58300833975;2042.1831267493121;2132.776058513865;2217.0355895185644;2325.197806756666;2662.560677488212;12248.387580620933;12801.359932003455;12950.287734174617;12970.351482425935;13539;1844.1609067874085;1864.2263460663808;1875.6905110656726;1901.1634880652468;1922.8750131241563;60424.395756042315;60671;61220.38779612191;62657.373426266066;69279.30720692778;14746.41014359435;15053.559200036016;15485.148316842457;15597.532074037797;15628.765568516481;21048.473788155112;22693.88653056724;23389.41526461852;24780.50438991246;24849.751502484745;8296.211859873303;8502.362322825813;8549.145085491526;8686.087960764155;8793.296536277083;7263;7282.4902256841515;7290.817729556804;7326.3406293433845;7333.523320984119;7779.649915753805;7885.5662938538935;7887.329576985906;7888.7633370998965;7905.762827115196;2913.1115009922178;2950.8229506229263;2985.820850748962;2993.670696223414;3032.408680307363;779.8401327727802;869.5695630662863;875.4790899414838;957.7797106665366;971.2666936462925;16481.6703665926;16542;16728.66542669164;16750.581235468966;17160;439.923013472641;443.8158164361818;444.5621063252693;468.14563421755435;978.4765150644371;5061.7469126544265;6054.455099041107;6191.25704915413;6238.594491358138;9520.714378568655;407919.24767147074;412795;414647;416492;420630;66260.66869665717;66721.33278667151;67185.3281467179;72106.63946680233;75550;463;471.9716816990992;473.0208468470302;473.6116384564693;476.5115756349769;9823.656172033945;10049.949750251495;10050.396976181453;10339.379637221791;10565.418901960466;27661.170164895084;27904.58143127895;29939.700602994562;33196.834015829765;33363;37393.813030934674;38738.612613874626;44021;44171.77914110538;44222.77888610665;7750.496217746054;7880.605969701377;8044.839103217786;8066.677332906618;8081.272685458337;11557.190996630155;11762;12169.756604868031;12356.073294502768;12644;10433.32183408076;10557.73605659834;10636.680899573024;10754.193435492212;11014.889851101387;11030.50362733663;11052.392118433563;11105.555777769121;11217.71955701114;11277.210595258253;10953.726156846142;11131.666050018512;11202.66392008241;11222.438878056228;11306.4912078955;7850.528968261922;7953.32396746283;8058.113607503287;8096.635651395819;8169.959150204449;9339.766505837408;10647.414392208502;10702.357858528514;10864.565417383215;10968.835467468154 22:20:57 INFO - PROCESS | 20758 | |3;mootools.html;1315.2963164706885;1473.5284708893155;1485.420685932486;1629.9918500407503;1770.6812773700728;6784.491163162764;7266.745663901765;7385.446091543129;7402.925970740298;7416.369608583277;7310.524815886964;7458.440616953723;7471.1408188058385;7472.887906681404;7494.325510704036;1922.0774028466274;2074.170331867254;2092.184048221195;2157.46063484129;2202.6695995600626;2167.6315026445463;2179.139240000199;2182.15986845065;2185.945351366213;2188.091941844141;2163.7078994335798;2166.4367264511184;2169.501014766605;2176.9564608707897;2178.477165480281;2138.9251376201837;2171.6308227601266;2176.5211653436218;2181.1057466438842;2182.138055468089;2143.689165071072;2151.38685474639;2155.611989841828;2163.8377121715935;2179.836512261587;6377.1709677742065;6498.220213574401;7383.446241531907;7436.0333156689285;7437.3306402423505;4062.7968601569805;4458.684688017042;4503.437070366172;4538.818447262105;4555.020670555847;2892.7107289271116;2943.3524624582556;2991.820490770561;2994.505906525421;3016.0499442675487;917.5045475443253;934.4486752815872;943.193569498083;947.3842002698301;991.2565575818137;10188.134008609277;10265.435401052939;10290.536925838354;10306.690799275959;10633.57465701371;4205.243056249843;4215.262329092397;4218.514870789899;4295.205387003414;4302.032042790347;9115.498647574315;9132.634694612141;9133.497657628894;9148.222401095982;9164.221041211438;4088;4520.61574766142;4764.4282686077195;4768.856934291976;4781.928271075936;4112.773797441169;4120.361343991686;4159.563245859141;4172;4182.623563879265;3572.8034958077037;3583.498310236569;3596.478510615946;3610.801405922647;3626.9818650906577;1088.9346639201674;1093.6773651772746;1097.0839349143555;1097.8078836203633;1128.8758236593967;888.493558671564;933.3606479561522;975.078550769516;986.7089208683456;994.0159242350021;6830.829229269209;6916.619585922823;6980.685869135901;6993.405560527311;6999.650017499104;466.41697877652933;466.6849876333461;472.77070620749595;500.4320096690283;505.3001592794007;5417.377001644862;5430.348358197041;5440.292761940922;5446.482584154499;5464.453554644504;8935.687250946186;9340.906590934139;9418.858717119248;9452.10205030521;9466.432014079177;9093.31800114994;9251.167394934488;9261.259099272176;9274.582643781046;9289.581968811419;6802.115724955823;6820.249772525019;6883.174019117738;6894.896576551356;7179.210286868441;6888.586684798928;6942.028116063654;6971.233164352018;6972.6513674315065;7010.369066784014;7612.429067820047;7918.802029949297;7920.326772224195;7931.444798864024;8086.87869681967;8044.477108987897;8347.707830225912;8369.163083691583;8373.037100733494;8389.412741108064;6076.939230607814;6198.845028874314;6214.471769899609;6816.522843400914;6866.313368663094;6658.3674550917585;7158.6420678964805;7335.266473352622;7355.77932662021;7386.039814824038;6607.57050791697;6684.83287917806;6787.117674702257;6790.388865002174;6946.235914049552;6591.934080659324;6643.966780166262;6667.299933507106;6747.730090796314;6835.829104272433;6700.5309628327495;6898.6205758681745;6958.095447591983;6964.477664175106;6978.162620485574;6325;6345.587536810092;6382.20222472191;6383.744650213939;6398.200224971879;6396.520260980538;6440.388163124496;6454.612723236621;6468.029795530708;6485.54601177913 22:20:57 INFO - PROCESS | 20758 | |4;prototype.html;327.4891169775151;328.26796244375026;328.4219773199169;332.40001796756854;337.47185654450783;5649.604527683064;5809.448102430273;5869.32502762182;5875.471207591316;5886.911696324553;5655;5812.50593699535;5813.941860581393;5821.301443826736;5821.796237131702;4541.4096167498265;4545.659075569329;4546.181687296285;4555.476120246164;4558.247889098295;4346;4429.77851107445;4497.527759585246;4511.503734589192;4563.452385713719;4442.733435993835;4459.843905463309;4466.151431228072;4489.304157855538;4500.97749511252;4505.03141824506;4505.526919673436;4511.120331535352;4512.571305725967;4516.16450956574;3968.265870813909;3975.2447035063533;3978.76127432355;3982.1637456134245;3983.780892050936;5427.4301198374;5573.08044172711;5594.328680558318;5600.747966341524;5610.354809196913;5140.434552199255;5142.897142057175;5143.819966301162;5150.356205474316;5153.0466863630345;5950.761969521214;6218.626882387071;6223.097650840648;6224.221972253468;6226.782062627786;4202.285611446062;4204.810783514749;4210.536840947493;4244.978775106105;4259.467566554181;6370.490360771174;6444.677766111676;6450.419462248373;6461.6446095464735;6462.903056454157;4355.455568053993;4361.956380436155;4362.040351122748;4369.825206991748;4370.8854242168045;4308.2460569400855;4350.978245108754;4376.277914144131;4385.859676484073;4389.912201755947;5104.464031276662;5112.335396398519;5125.308083408767;5126.436092029874;5129.769160387791;5945.048792193228;6095.847603809941;6228.439440450381;6236.750529978841;6240.219972503437;1530.4719871644265;1562.0471512377442;1565.107888503542;1565.7025165218572;1566.843315668424;1003.1824063388315;1026.461107918349;1027.6043723166636;1041.3699711674478;1042.2912419554775;5816.680082595498;5862.208601838697;5866.23738913947;5868.471837534642;5875.9118613220835;6387.329330420239;6391.296957334689;6394;6408.231012278557;6411;5857;5861.648301101947;5868.559858010666;5879.706014699248;5881.9705901470215;3363.814990175515;3458.2564748579175;3469.063352894704;3469.670381313771;3474.922773940036;6606.537542371988;6634.734610615618;6649.401553860079;6657.267700552935;6658.300878407697;6528.281888992207;6580.374864387875;6580.539362244693;6586;6589.110470086477;6430.871382572323;6572.211334639874;6651.268360480343;6671.39957403836;6677.165354330708;6388.488920886225;6407.583507072025;6408.359164083555;6431;6434.806955791147;6368.203974503187;6382.042693595812;6391.93608063932;6397.712102955285;6404.135441715309;6522.249941256627;6629.7679581214425;6632.303608121078;6641.701123449553;6652;4346.043870348518;4416.889577760582;4422.7346359218545;4426.468823741172;4427.490838553479;6202.193714817045;6278.309385967448;6376.776812811529;6422.454091402283;6425.068365086942;6226.5641405101205;6266.279377871603;6277.497800175884;6280.717367718372;6284.088807123033;6258.311585725475;6261.749530018748;6262;6265.49876009909;6270.028145637433;6040.365761595145;6133.4786543142545;6147.477464415575;6151.108089326933;6170.259568851767;6263.874722505433;6279.058141278662;6282.649230415483;6304.148939893239;6308.243010838729;6104.9389506106145;6107.083937409424;6110.938890611037;6123.173371594778;6136.539759517965 22:20:57 INFO - PROCESS | 20758 | |5;yui.html;629.7764293675745;806.9596520173992;808.2846680687593;816.1389733830806;821.9917800821994;13706.725865482684;13956.2324072176;14001.579952601438;14005;14009.159450432968;12884.871151288484;12937.094403391766;12947.741045179115;12969.546065887673;12977.935110324435;673.4713250098673;681.9863602727943;803.5580430763086;804.4569915307188;825.1748251748252;817.7383237364046;822.235321151329;823.8640624296984;825.4345773145385;830.4767996162409;864.9567521623925;866.0213958227195;866.2766589897443;867.8090820019618;869.6695255802786;701.3477465956659;849.2569002123142;864.4035615425337;865.5066612031145;865.9437136586101;633.4805459523193;637.0857819029707;637.2703254773305;637.9457746091588;638.0524920493064;12874.16317939331;12882.420291086924;12898.677533061655;12913.483460661562;12929.935350323189;12559.120861539695;12932.159409638343;12982.480700771957;13024.54414095502;13065.477380904753;10318.968103189696;10717.249792514527;10758.139348852073;10882.72793180169;11082.667519974413;3586.964130358689;3651.379265524841;3665.8533658653623;3672.1003354178397;3745.213505163919;47225;48205;48234.27648585274;49087.26369104466;49088.01823963536;10092.747681308027;10098.192144628412;10317.84523232152;10614.628488002883;10738;18818.81181188198;18824.905875470537;18842;18844.623107538046;18858.905705471385;7343.77968660941;7389.741359052515;7480.438967077491;7579.924200758032;7581.090269167625;4258.467691538558;4324.654027677778;4329.935050974238;4350.2387082260475;4411.536788637211;3156.4002839460886;3189.4737368333963;3204.3110731192905;3211.855466504013;3221.951670724941;497.4354108087347;538.4857461124617;544.4256309593338;606.6360183889719;613.362103412455;481.30932112418094;557.7768892443055;601.6119602856133;603.6106711171358;618.7957973868613;8009.8398032040195;8013.719519816778;8052.758417247492;8092.676292948334;8141.715039973691;613.5613036678707;618.9597676151036;631.7757196195284;640.5516138702833;644.4522156167315;3665.9266814664093;3773.509443772347;3832.693384529287;3963.246983073207;4046.7976601169826;49918.25122623164;51376.229356559685;51386.74306628518;51732.482675173516;52098.73950630223;43330.783346083066;43471;44444.55555444469;44630;45412.77293613577;39612.00969975716;39727.60272397239;40017.19965600672;40148.197036059115;40704.59295407008;8055.436119471581;8129.9187008129165;8149.429539932148;8350.79123021905;8390.370722195981;32041.83979080183;32124;32868.50697239591;33025.50461743028;33290.167745806546;36479;37020.44469332908;40614.59385406108;41058;41117.79441102775;10636.787264254517;10658.89341106579;11308.321500709984;11311.321320720783;11571.768564628828;11757.118216133653;12142.453589588313;12328.32194229326;12538.122331437076;12552.184108032949;10617;10734.51694673726;10746.301490403099;10824.458777061272;11083.224174308043;10343.379397235889;10531.31546449509;10549.472526373496;10614.203934705083;10743.946280268548;10202.28583999113;10464.790704185722;10475.423851687925;10605.787884242118;10710.410927398756;9394.906050939404;9666.303594415343;9704.2721795867;9798.951005244928;10055.748606284902;8728.214460698568;8762.904601996855;8907.421017633833;9220.907790922005;9230.400023998418 22:20:57 INFO - PROCESS | 20758 | __end_tp_report 22:20:57 INFO - PROCESS | 20758 | __start_cc_report 22:20:57 INFO - PROCESS | 20758 | _x_x_mozilla_cycle_collect,934 22:20:57 INFO - PROCESS | 20758 | __end_cc_report 22:20:57 INFO - PROCESS | 20758 | __startTimestamp1455949256993__endTimestamp 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | ------- Summary: start ------- 22:20:57 INFO - PROCESS | 20758 | Number of tests: 6 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#0] dojo.html Cycles:165 Average:5076.78 Median:5243.97 stddev:540.86 (10.3%) stddev-sans-first:519.96 22:20:57 INFO - PROCESS | 20758 | Values: 3106.3 3138.0 3138.2 3140.3 3169.0 4949.5 5010.5 5047.1 5064.7 5076.4 5076.7 5087.8 5108.9 5207.6 5220.1 4642.7 4648.2 4664.8 4667.9 4741.7 4737.1 4782.5 4792.4 4794.1 4808.6 4598.1 4623.3 4706.8 4709.7 4764.9 4688.0 4690.5 4691.3 4714.6 4729.2 4605.8 4626.9 4668.3 4672.7 4702.3 4785.0 4918.3 5040.9 5052.5 5081.2 5473.5 5513.0 5516.4 5518.6 5520.9 5401.9 5444.5 5546.9 5558.8 5560.5 5104.2 5123.1 5127.8 5130.7 5170.8 5562.9 5566.4 5568.0 5568.2 5570.8 5314.8 5316.1 5384.8 5389.7 5419.7 5453.1 5460.7 5460.9 5461.6 5465.1 5315.4 5348.1 5354.0 5354.7 5404.3 5461.7 5477.7 5477.9 5516.8 5564.8 4220.1 4221.2 4221.3 4235.0 4262.0 3720.8 3797.6 3838.6 3864.4 3868.3 5015.7 5060.3 5060.9 5062.2 5081.6 5441.1 5454.6 5455.0 5473.8 5497.6 4920.1 5021.8 5043.3 5069.0 5099.6 4612.4 4618.9 4651.1 4660.7 4697.0 5499.1 5626.1 5630.4 5636.1 5642.7 5448.2 5449.1 5466.4 5476.0 5523.3 5601.2 5626.4 5634.4 5642.1 5645.0 5283.4 5391.5 5413.9 5452.2 5495.8 5493.4 5512.2 5517.9 5519.1 5528.3 5360.2 5363.2 5365.3 5373.1 5420.5 5244.5 5276.6 5356.5 5373.8 5426.5 5167.4 5223.8 5224.3 5225.1 5289.2 5212.9 5308.7 5322.9 5329.5 5336.4 5218.6 5220.5 5244.0 5252.6 5291.7 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#1] ext.html Cycles:180 Average:43924.31 Median:36300.41 stddev:73431.24 (202.3%) stddev-sans-first:73564.87 22:20:57 INFO - PROCESS | 20758 | Values: 505.9 508.0 516.6 528.8 538.4 28445.4 36573.1 36759.6 36815.6 36834.6 39370.8 39763.4 39820.4 39868.6 39907.8 8494.6 8721.0 8730.0 8730.9 8733.7 8473.7 8582.6 8594.6 8602.5 8771.4 8763.4 8815.7 8838.7 8848.0 8857.2 8683.3 8774.5 8788.8 8790.0 8794.5 8632.0 8748.1 8759.0 8767.5 8772.6 39592.4 40143.8 40145.6 40232.8 40336.2 3025.3 3268.3 3338.7 3558.3 3798.4 29547.7 29838.6 30057.7 30201.0 30225.8 3933.8 3953.3 4007.1 4093.4 4099.8 265025.0 265232.0 266142.0 266303.7 266482.0 51591.7 56918.7 57595.0 57873.0 58079.7 46242.8 61764.0 62329.7 62667.7 62680.4 14647.3 14827.8 14841.9 14869.4 14890.0 9670.9 10225.4 10361.5 11332.2 11424.3 18201.2 18293.7 18562.0 18957.0 19468.7 6752.2 6960.7 6990.0 7048.8 7095.1 883.8 949.1 952.8 979.6 1049.9 25173.4 25347.7 25525.6 25686.9 25810.4 964.5 978.1 987.2 1005.5 1027.8 13512.7 13694.0 13822.6 13891.4 13895.4 305494.0 306114.0 309921.0 309955.0 310468.0 51764.0 52018.7 52047.5 52049.5 52132.7 1199.5 1205.4 1230.7 1234.2 1245.1 22459.0 24742.8 25983.2 26405.3 26461.7 42222.0 42379.0 42668.0 42953.0 43012.1 189918.0 190811.0 190873.0 343702.0 350050.0 27392.3 31078.5 31297.5 31438.0 31996.7 37553.0 37683.8 38207.6 38323.0 38494.6 32138.0 35718.5 35804.3 36210.0 36390.8 33161.7 34714.1 34912.0 35487.5 35555.8 34329.3 34438.8 34597.8 34667.7 34697.3 31248.4 31589.8 31934.4 31948.8 32137.9 23142.4 23169.2 23440.5 23446.0 24601.0 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#2] jquery.html Cycles:180 Average:24699.51 Median:205923.03 stddev:67847.41 (32.9%) stddev-sans-first:68013.10 22:20:57 INFO - PROCESS | 20758 | Values: 348.7 349.4 350.3 352.8 353.1 13720.1 14171.9 16424.3 17076.7 17648.3 16154.4 16292.4 17136.4 17244.8 17353.8 5102.7 5367.2 5383.4 5385.6 5392.2 3926.8 5265.2 5665.9 5798.0 5856.4 5645.4 5752.7 5760.3 5779.5 5792.2 5715.4 5726.4 5734.7 5754.2 5758.8 4981.4 5003.1 5063.1 5090.5 5101.9 20177.5 20439.0 20670.3 20698.9 20849.6 2042.2 2132.8 2217.0 2325.2 2662.6 12248.4 12801.4 12950.3 12970.4 13539.0 1844.2 1864.2 1875.7 1901.2 1922.9 60424.4 60671.0 61220.4 62657.4 69279.3 14746.4 15053.6 15485.1 15597.5 15628.8 21048.5 22693.9 23389.4 24780.5 24849.8 8296.2 8502.4 8549.1 8686.1 8793.3 7263.0 7282.5 7290.8 7326.3 7333.5 7779.6 7885.6 7887.3 7888.8 7905.8 2913.1 2950.8 2985.8 2993.7 3032.4 779.8 869.6 875.5 957.8 971.3 16481.7 16542.0 16728.7 16750.6 17160.0 439.9 443.8 444.6 468.1 978.5 5061.7 6054.5 6191.3 6238.6 9520.7 407919.2 412795.0 414647.0 416492.0 420630.0 66260.7 66721.3 67185.3 72106.6 75550.0 463.0 472.0 473.0 473.6 476.5 9823.7 10049.9 10050.4 10339.4 10565.4 27661.2 27904.6 29939.7 33196.8 33363.0 37393.8 38738.6 44021.0 44171.8 44222.8 7750.5 7880.6 8044.8 8066.7 8081.3 11557.2 11762.0 12169.8 12356.1 12644.0 10433.3 10557.7 10636.7 10754.2 11014.9 11030.5 11052.4 11105.6 11217.7 11277.2 10953.7 11131.7 11202.7 11222.4 11306.5 7850.5 7953.3 8058.1 8096.6 8170.0 9339.8 10647.4 10702.4 10864.6 10968.8 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#3] mootools.html Cycles:180 Average:5243.32 Median:6447.50 stddev:2776.41 (43.1%) stddev-sans-first:2768.50 22:20:57 INFO - PROCESS | 20758 | Values: 1315.3 1473.5 1485.4 1630.0 1770.7 6784.5 7266.7 7385.4 7402.9 7416.4 7310.5 7458.4 7471.1 7472.9 7494.3 1922.1 2074.2 2092.2 2157.5 2202.7 2167.6 2179.1 2182.2 2185.9 2188.1 2163.7 2166.4 2169.5 2177.0 2178.5 2138.9 2171.6 2176.5 2181.1 2182.1 2143.7 2151.4 2155.6 2163.8 2179.8 6377.2 6498.2 7383.4 7436.0 7437.3 4062.8 4458.7 4503.4 4538.8 4555.0 2892.7 2943.4 2991.8 2994.5 3016.0 917.5 934.4 943.2 947.4 991.3 10188.1 10265.4 10290.5 10306.7 10633.6 4205.2 4215.3 4218.5 4295.2 4302.0 9115.5 9132.6 9133.5 9148.2 9164.2 4088.0 4520.6 4764.4 4768.9 4781.9 4112.8 4120.4 4159.6 4172.0 4182.6 3572.8 3583.5 3596.5 3610.8 3627.0 1088.9 1093.7 1097.1 1097.8 1128.9 888.5 933.4 975.1 986.7 994.0 6830.8 6916.6 6980.7 6993.4 6999.7 466.4 466.7 472.8 500.4 505.3 5417.4 5430.3 5440.3 5446.5 5464.5 8935.7 9340.9 9418.9 9452.1 9466.4 9093.3 9251.2 9261.3 9274.6 9289.6 6802.1 6820.2 6883.2 6894.9 7179.2 6888.6 6942.0 6971.2 6972.7 7010.4 7612.4 7918.8 7920.3 7931.4 8086.9 8044.5 8347.7 8369.2 8373.0 8389.4 6076.9 6198.8 6214.5 6816.5 6866.3 6658.4 7158.6 7335.3 7355.8 7386.0 6607.6 6684.8 6787.1 6790.4 6946.2 6591.9 6644.0 6667.3 6747.7 6835.8 6700.5 6898.6 6958.1 6964.5 6978.2 6325.0 6345.6 6382.2 6383.7 6398.2 6396.5 6440.4 6454.6 6468.0 6485.5 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#4] prototype.html Cycles:180 Average:5184.74 Median:5864.22 stddev:1565.88 (26.7%) stddev-sans-first:1527.24 22:20:57 INFO - PROCESS | 20758 | Values: 327.5 328.3 328.4 332.4 337.5 5649.6 5809.4 5869.3 5875.5 5886.9 5655.0 5812.5 5813.9 5821.3 5821.8 4541.4 4545.7 4546.2 4555.5 4558.2 4346.0 4429.8 4497.5 4511.5 4563.5 4442.7 4459.8 4466.2 4489.3 4501.0 4505.0 4505.5 4511.1 4512.6 4516.2 3968.3 3975.2 3978.8 3982.2 3983.8 5427.4 5573.1 5594.3 5600.7 5610.4 5140.4 5142.9 5143.8 5150.4 5153.0 5950.8 6218.6 6223.1 6224.2 6226.8 4202.3 4204.8 4210.5 4245.0 4259.5 6370.5 6444.7 6450.4 6461.6 6462.9 4355.5 4362.0 4362.0 4369.8 4370.9 4308.2 4351.0 4376.3 4385.9 4389.9 5104.5 5112.3 5125.3 5126.4 5129.8 5945.0 6095.8 6228.4 6236.8 6240.2 1530.5 1562.0 1565.1 1565.7 1566.8 1003.2 1026.5 1027.6 1041.4 1042.3 5816.7 5862.2 5866.2 5868.5 5875.9 6387.3 6391.3 6394.0 6408.2 6411.0 5857.0 5861.6 5868.6 5879.7 5882.0 3363.8 3458.3 3469.1 3469.7 3474.9 6606.5 6634.7 6649.4 6657.3 6658.3 6528.3 6580.4 6580.5 6586.0 6589.1 6430.9 6572.2 6651.3 6671.4 6677.2 6388.5 6407.6 6408.4 6431.0 6434.8 6368.2 6382.0 6391.9 6397.7 6404.1 6522.2 6629.8 6632.3 6641.7 6652.0 4346.0 4416.9 4422.7 4426.5 4427.5 6202.2 6278.3 6376.8 6422.5 6425.1 6226.6 6266.3 6277.5 6280.7 6284.1 6258.3 6261.7 6262.0 6265.5 6270.0 6040.4 6133.5 6147.5 6151.1 6170.3 6263.9 6279.1 6282.6 6304.1 6308.2 6104.9 6107.1 6110.9 6123.2 6136.5 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - PROCESS | 20758 | [#5] yui.html Cycles:180 Average:12992.08 Median:22688.13 stddev:14360.14 (63.3%) stddev-sans-first:14370.41 22:20:57 INFO - PROCESS | 20758 | Values: 629.8 807.0 808.3 816.1 822.0 13706.7 13956.2 14001.6 14005.0 14009.2 12884.9 12937.1 12947.7 12969.5 12977.9 673.5 682.0 803.6 804.5 825.2 817.7 822.2 823.9 825.4 830.5 865.0 866.0 866.3 867.8 869.7 701.3 849.3 864.4 865.5 865.9 633.5 637.1 637.3 637.9 638.1 12874.2 12882.4 12898.7 12913.5 12929.9 12559.1 12932.2 12982.5 13024.5 13065.5 10319.0 10717.2 10758.1 10882.7 11082.7 3587.0 3651.4 3665.9 3672.1 3745.2 47225.0 48205.0 48234.3 49087.3 49088.0 10092.7 10098.2 10317.8 10614.6 10738.0 18818.8 18824.9 18842.0 18844.6 18858.9 7343.8 7389.7 7480.4 7579.9 7581.1 4258.5 4324.7 4329.9 4350.2 4411.5 3156.4 3189.5 3204.3 3211.9 3222.0 497.4 538.5 544.4 606.6 613.4 481.3 557.8 601.6 603.6 618.8 8009.8 8013.7 8052.8 8092.7 8141.7 613.6 619.0 631.8 640.6 644.5 3665.9 3773.5 3832.7 3963.2 4046.8 49918.3 51376.2 51386.7 51732.5 52098.7 43330.8 43471.0 44444.6 44630.0 45412.8 39612.0 39727.6 40017.2 40148.2 40704.6 8055.4 8129.9 8149.4 8350.8 8390.4 32041.8 32124.0 32868.5 33025.5 33290.2 36479.0 37020.4 40614.6 41058.0 41117.8 10636.8 10658.9 11308.3 11311.3 11571.8 11757.1 12142.5 12328.3 12538.1 12552.2 10617.0 10734.5 10746.3 10824.5 11083.2 10343.4 10531.3 10549.5 10614.2 10743.9 10202.3 10464.8 10475.4 10605.8 10710.4 9394.9 9666.3 9704.3 9799.0 10055.7 8728.2 8762.9 8907.4 9220.9 9230.4 22:20:57 INFO - PROCESS | 20758 | -------- Summary: end -------- 22:20:57 INFO - PROCESS | 20758 | 22:20:57 INFO - TEST-INFO | 20758: exit 0 22:20:57 INFO - TEST-OK | dromaeo_css | took 1113028ms 22:20:57 INFO - TEST-START | kraken 22:20:57 INFO - operating with platform_type : linux_ 22:20:57 INFO - Initialising browser for kraken test... 22:20:57 INFO - TEST-INFO | started process 20952 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7tFja4/profile http://localhost:40943/getInfo.html) 22:21:01 INFO - PROCESS | 20952 | __metrics Screen width/height:1600/1200 22:21:01 INFO - PROCESS | 20952 | colorDepth:24 22:21:01 INFO - PROCESS | 20952 | Browser inner width/height: 1024/697 22:21:01 INFO - PROCESS | 20952 | __metrics 22:21:02 INFO - PROCESS | 20952 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:21:03 INFO - TEST-INFO | 20952: exit 0 22:21:03 INFO - Browser initialized. 22:21:03 INFO - Running cycle 1/1 for kraken test... 22:21:03 INFO - TEST-INFO | started process 21036 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7tFja4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:15 INFO - PROCESS | 21036 | RSS: Main: 148168704 22:21:15 INFO - PROCESS | 21036 | 22:21:17 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40943/tests/kraken/driver.html?testName=audio-beat-detection) 22:21:18 INFO - PROCESS | 21036 | RSS: Main: 191496192 22:21:18 INFO - PROCESS | 21036 | 22:21:20 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40943/tests/kraken/driver.html?testName=audio-dft) 22:21:21 INFO - PROCESS | 21036 | RSS: Main: 181133312 22:21:21 INFO - PROCESS | 21036 | 22:21:24 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40943/tests/kraken/driver.html?testName=audio-fft) 22:21:24 INFO - PROCESS | 21036 | RSS: Main: 165859328 22:21:24 INFO - PROCESS | 21036 | 22:21:26 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40943/tests/kraken/driver.html?testName=audio-oscillator) 22:21:27 INFO - PROCESS | 21036 | RSS: Main: 168148992 22:21:27 INFO - PROCESS | 21036 | 22:21:29 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40943/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:21:30 INFO - PROCESS | 21036 | RSS: Main: 168939520 22:21:30 INFO - PROCESS | 21036 | 22:21:33 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40943/tests/kraken/driver.html?testName=imaging-darkroom) 22:21:33 INFO - PROCESS | 21036 | RSS: Main: 169713664 22:21:33 INFO - PROCESS | 21036 | 22:21:37 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40943/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:37 INFO - PROCESS | 21036 | RSS: Main: 170565632 22:21:37 INFO - PROCESS | 21036 | 22:21:40 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40943/tests/kraken/driver.html?testName=json-parse-financial) 22:21:41 INFO - PROCESS | 21036 | RSS: Main: 171888640 22:21:41 INFO - PROCESS | 21036 | 22:21:42 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40943/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:43 INFO - PROCESS | 21036 | RSS: Main: 185974784 22:21:43 INFO - PROCESS | 21036 | 22:21:45 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:45 INFO - PROCESS | 21036 | RSS: Main: 180023296 22:21:45 INFO - PROCESS | 21036 | 22:21:47 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:48 INFO - PROCESS | 21036 | RSS: Main: 191754240 22:21:48 INFO - PROCESS | 21036 | 22:21:50 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:51 INFO - PROCESS | 21036 | RSS: Main: 197623808 22:21:51 INFO - PROCESS | 21036 | 22:21:53 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:54 INFO - PROCESS | 21036 | RSS: Main: 195125248 22:21:54 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | Cycle 1(1): loaded http://localhost:40943/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40943/tests/kraken/driver.html?testName=ai-astar) 22:21:56 INFO - PROCESS | 21036 | RSS: Main: 255815680 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | __start_tp_report 22:21:56 INFO - PROCESS | 21036 | _x_x_mozilla_page_load 22:21:56 INFO - PROCESS | 21036 | _x_x_mozilla_page_load_details 22:21:56 INFO - PROCESS | 21036 | |i|pagename|runs| 22:21:56 INFO - PROCESS | 21036 | |0;ai-astar;112;113;109;111;113;112;101;111;112;112 22:21:56 INFO - PROCESS | 21036 | |1;audio-beat-detection;151;126;159;164;132;154;149;128;162;127 22:21:56 INFO - PROCESS | 21036 | |2;audio-dft;162;163;163;161;159;160;160;168;166;161 22:21:56 INFO - PROCESS | 21036 | |3;audio-fft;74;73;102;94;73;74;73;113;102;76 22:21:56 INFO - PROCESS | 21036 | |4;audio-oscillator;96;97;142;95;97;97;138;95;96;136 22:21:56 INFO - PROCESS | 21036 | |5;imaging-gaussian-blur;119;116;116;116;114;115;115;115;116;115 22:21:56 INFO - PROCESS | 21036 | |6;imaging-darkroom;146;145;145;145;148;148;145;145;146;145 22:21:56 INFO - PROCESS | 21036 | |7;imaging-desaturate;96;96;96;96;96;96;96;95;96;96 22:21:56 INFO - PROCESS | 21036 | |8;json-parse-financial;58;58;59;58;59;58;58;69;57;56 22:21:56 INFO - PROCESS | 21036 | |9;json-stringify-tinderbox;67;68;67;67;68;67;68;68;67;67 22:21:56 INFO - PROCESS | 21036 | |10;stanford-crypto-aes;81;84;84;82;84;83;91;83;83;85 22:21:56 INFO - PROCESS | 21036 | |11;stanford-crypto-ccm;118;121;115;117;125;117;122;116;122;126 22:21:56 INFO - PROCESS | 21036 | |12;stanford-crypto-pbkdf2;165;152;151;153;153;157;154;159;139;153 22:21:56 INFO - PROCESS | 21036 | |13;stanford-crypto-sha256-iterative;70;70;77;67;68;68;69;68;69;68 22:21:56 INFO - PROCESS | 21036 | __end_tp_report 22:21:56 INFO - PROCESS | 21036 | __start_cc_report 22:21:56 INFO - PROCESS | 21036 | _x_x_mozilla_cycle_collect,960 22:21:56 INFO - PROCESS | 21036 | __end_cc_report 22:21:56 INFO - PROCESS | 21036 | __startTimestamp1455949316530__endTimestamp 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | ------- Summary: start ------- 22:21:56 INFO - PROCESS | 21036 | Number of tests: 14 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.57 (3.2%) stddev-sans-first:3.75 22:21:56 INFO - PROCESS | 21036 | Values: 112.0 113.0 109.0 111.0 113.0 112.0 101.0 111.0 112.0 112.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:152.50 stddev:15.35 (10.1%) stddev-sans-first:16.14 22:21:56 INFO - PROCESS | 21036 | Values: 151.0 126.0 159.0 164.0 132.0 154.0 149.0 128.0 162.0 127.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:2.83 (1.7%) stddev-sans-first:3.00 22:21:56 INFO - PROCESS | 21036 | Values: 162.0 163.0 163.0 161.0 159.0 160.0 160.0 168.0 166.0 161.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#3] audio-fft Cycles:10 Average:85.40 Median:73.50 stddev:15.62 (21.3%) stddev-sans-first:16.02 22:21:56 INFO - PROCESS | 21036 | Values: 74.0 73.0 102.0 94.0 73.0 74.0 73.0 113.0 102.0 76.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:96.00 stddev:20.60 (21.5%) stddev-sans-first:21.32 22:21:56 INFO - PROCESS | 21036 | Values: 96.0 97.0 142.0 95.0 97.0 97.0 138.0 95.0 96.0 136.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:116.00 stddev:1.34 (1.2%) stddev-sans-first:0.71 22:21:56 INFO - PROCESS | 21036 | Values: 119.0 116.0 116.0 116.0 114.0 115.0 115.0 115.0 116.0 115.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:145.50 stddev:1.23 (0.8%) stddev-sans-first:1.30 22:21:56 INFO - PROCESS | 21036 | Values: 146.0 145.0 145.0 145.0 148.0 148.0 145.0 145.0 146.0 145.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:21:56 INFO - PROCESS | 21036 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:3.62 (6.2%) stddev-sans-first:3.82 22:21:56 INFO - PROCESS | 21036 | Values: 58.0 58.0 59.0 58.0 59.0 58.0 58.0 69.0 57.0 56.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 22:21:56 INFO - PROCESS | 21036 | Values: 67.0 68.0 67.0 67.0 68.0 67.0 68.0 68.0 67.0 67.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:2.71 (3.2%) stddev-sans-first:2.65 22:21:56 INFO - PROCESS | 21036 | Values: 81.0 84.0 84.0 82.0 84.0 83.0 91.0 83.0 83.0 85.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:121.50 stddev:3.84 (3.2%) stddev-sans-first:4.01 22:21:56 INFO - PROCESS | 21036 | Values: 118.0 121.0 115.0 117.0 125.0 117.0 122.0 116.0 122.0 126.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:153.50 stddev:6.62 (4.3%) stddev-sans-first:5.59 22:21:56 INFO - PROCESS | 21036 | Values: 165.0 152.0 151.0 153.0 153.0 157.0 154.0 159.0 139.0 153.0 22:21:56 INFO - PROCESS | 21036 | 22:21:56 INFO - PROCESS | 21036 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.00 stddev:2.84 (4.1%) stddev-sans-first:3.00 22:21:56 INFO - PROCESS | 21036 | Values: 70.0 70.0 77.0 67.0 68.0 68.0 69.0 68.0 69.0 68.0 22:21:56 INFO - PROCESS | 21036 | -------- Summary: end -------- 22:21:56 INFO - PROCESS | 21036 | 22:21:57 INFO - TEST-INFO | 21036: exit 0 22:21:57 INFO - TEST-OK | kraken | took 59923ms 22:21:57 INFO - SUITE-END | took 1172s 22:21:57 INFO - Completed test suite (00:19:33) 22:21:57 INFO - Working with test: dromaeo_css 22:21:57 INFO - Generating results file: dromaeo_css 22:21:57 INFO - Working with test: kraken 22:21:57 INFO - Generating results file: kraken 22:21:57 INFO - javascript benchmark 22:21:57 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:21:57 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 22:21:57 INFO - process_Request line: dromaeo_css 6690.80 graph.html#tests=[[72,1,35]] 22:21:57 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:21:57 INFO - process_Request line: kraken 1523.10 graph.html#tests=[[232,1,35]] 22:21:57 INFO - RETURN: dromaeo_css: 6690.80 22:21:57 INFO - RETURN: kraken: 1523.10 22:21:57 INFO - javascript benchmark 22:21:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3106.316610345724, 3138.042896916441, 3138.2468207630177, 3140.262038420969, 3169.033444799337, 4949.52979466951, 5010.524000219978, 5047.091523525769, 5064.696118232909, 5076.36545431821, 5076.74616269186, 5087.821926232583, 5108.948910510894, 5207.609429292799, 5220.138677118281, 4642.651801114913, 4648.233041548149, 4664.76676166192, 4667.88330291742, 4741.691790033654, 4737.123632128068, 4782.497837727024, 4792.376990991166, 4794.065157294329, 4808.591269742059, 4598.149342371656, 4623.283391074388, 4706.7646617669325, 4709.740964246965, 4764.857054288377, 4687.95312046882, 4690.4605970313505, 4691.319758634979, 4714.622830173578, 4729.196036673774, 4605.792739326705, 4626.86119416418, 4668.253079507296, 4672.696274742165, 4702.3181638662545, 4784.976075119602, 4918.336024636664, 5040.873978150539, 5052.545270925634, 5081.237814327844, 5473.507384335429, 5512.972435137839, 5516.420775818521, 5518.641288316287, 5520.944790552083, 5401.891962160735, 5444.537214336786, 5546.86132846678, 5558.777648894039, 5560.499555040025, 5104.234364845266, 5123.077845987721, 5127.79488820443, 5130.743462826844, 5170.819021334235, 5562.860928476821, 5566.415526369673, 5568.0, 5568.248286481273, 5570.805021824217, 5314.787408503694, 5316.0696878046965, 5384.757685904144, 5389.7035663038905, 5419.69927217465, 5453.072977593819, 5460.726963651801, 5460.918086228709, 5461.563074953994, 5465.07093794056, 5315.441878602768, 5348.1442969124755, 5353.9827432788425, 5354.732263386815, 5404.32445944257, 5461.672299662033, 5477.7261136943, 5477.904419116193, 5516.834494965157, 5564.8330550083565, 4220.092680073799, 4221.197972385237, 4221.324588065895, 4235.0, 4262.019735460862, 3720.7581507201944, 3797.582265950743, 3838.6353296436796, 3864.381698928158, 3868.3423817951016, 5015.673981191211, 5060.291559181717, 5060.924086138711, 5062.164742817467, 5081.618878584047, 5441.129419292894, 5454.563634909197, 5455.0, 5473.823128027493, 5497.642653227527, 4920.114379411669, 5021.849344519597, 5043.268726034705, 5068.974655126701, 5099.617528685363, 4612.3542704021465, 4618.861434156981, 4651.093036857848, 4660.650451216105, 4696.976515117402, 5499.120140777536, 5626.071698169756, 5630.380658127603, 5636.098224284095, 5642.689652069175, 5448.237246785532, 5449.073657478159, 5466.4260252674485, 5476.04169270384, 5523.28197334344, 5601.187827765033, 5626.352969408406, 5634.380218175916, 5642.125470552071, 5645.040343141758, 5283.418823929288, 5391.541718953933, 5413.891722165614, 5452.182172674131, 5495.807646732345, 5493.395726470165, 5512.200730894078, 5517.917231241454, 5519.061759500974, 5528.336599608073, 5360.16917377807, 5363.19552067193, 5365.302510673588, 5373.11343628297, 5420.4850539198715, 5244.475552444725, 5276.551493122974, 5356.544693700923, 5373.758180881791, 5426.457354264542, 5167.405748338989, 5223.7910483580235, 5224.346956630421, 5225.111731005814, 5289.18017707256, 5212.921806172834, 5308.708021058879, 5322.920156197738, 5329.467053294639, 5336.386315573759, 5218.582513398843, 5220.477952204901, 5243.973780131075, 5252.606054545848, 5291.656042357235], "unit": "score", "name": "dojo.html", "value": 5043.421280724494}, {"lowerIsBetter": false, "replicates": [505.8887044850134, 507.97714102865393, 516.591892405, 528.8175579425098, 538.3701069748394, 28445.43109137816, 36573.08567285819, 36759.632403675954, 36815.631843681556, 36834.631653683486, 39370.803145984304, 39763.403548946786, 39820.402693959615, 39868.60131398685, 39907.802765917, 8494.617742201615, 8721.0, 8730.0, 8730.86903696442, 8733.650653973866, 8473.703420380289, 8582.570871456433, 8594.570271486433, 8602.526861022641, 8771.429857059271, 8763.386562940597, 8815.691450799224, 8838.734837954871, 8848.026717061119, 8857.247133993618, 8683.261922736576, 8774.47353158806, 8788.824223515494, 8790.033096359428, 8794.51630160341, 8632.0, 8748.125187481264, 8759.0, 8767.51778652174, 8772.649094036231, 39592.40611390831, 40143.799281003405, 40145.59854401448, 40232.79883600563, 40336.1932761346, 3025.3495498467714, 3268.297316077031, 3338.749593780452, 3558.2705545363137, 3798.4302354646748, 29547.70452295471, 29838.55242171369, 30057.69942300571, 30201.0, 30225.848870755726, 3933.7836418996944, 3953.3081710700512, 4007.1184339445276, 4093.4269202311702, 4099.8360065597635, 265025.0, 265232.0, 266142.0, 266303.6684816583, 266482.0, 51591.74204128955, 56918.7154064227, 57595.0, 57873.0, 58079.70960145172, 46242.76878615653, 61764.0, 62329.688351558856, 62667.68666156731, 62680.37319626745, 14647.340869660891, 14827.77758333626, 14841.851581484047, 14869.405223791144, 14890.032147910351, 9670.90329096714, 10225.386476811414, 10361.481925903674, 11332.2067455279, 11424.257423267461, 18201.180946857425, 18293.7255941161, 18562.0, 18957.0, 19468.70796938047, 6752.189737231465, 6960.698166713191, 6989.965050174818, 7048.753293634698, 7095.113110861143, 883.7613844262141, 949.1464653506785, 952.8475443929077, 979.647326962293, 1049.92125590581, 25173.370665733502, 25347.746522534537, 25525.61711574328, 25686.871565642054, 25810.35474113162, 964.5032808103832, 978.1099199728213, 987.1806400687412, 1005.5324274212526, 1027.7841653252751, 13512.662183445493, 13694.0, 13822.585322440344, 13891.4443422264, 13895.374708138159, 305494.0, 306114.0, 309921.0, 309955.0, 310468.0, 51764.0, 52018.73990630098, 52047.47952520502, 52049.479505204465, 52132.73933630308, 1199.5201919232377, 1205.4274219745726, 1230.704630888598, 1234.2471092633486, 1245.059979715309, 22459.0, 24742.752572474044, 25983.220503384928, 26405.33986650349, 26461.735382645926, 42221.9889110534, 42379.0, 42668.0, 42953.0, 43012.13975720531, 189918.0, 190811.0, 190873.0, 343702.0, 350050.0, 27392.315192120357, 31078.533821992234, 31297.530537042418, 31438.0, 31996.68003319937, 37553.0, 37683.81158094192, 38207.617923820406, 38323.0, 38494.615053849106, 32138.0, 35718.46422303616, 35804.283914321044, 36210.0, 36390.818045909604, 33161.66838331586, 34714.13214669654, 34912.0, 35487.46768798522, 35555.82222088873, 34329.31341373209, 34438.82780586081, 34597.827010864785, 34667.653323466446, 34697.306053878274, 31248.375032499676, 31589.842050790518, 31934.36131277408, 31948.840255799503, 32137.875174368786, 23142.400254268487, 23169.18907838285, 23440.531189375775, 23446.0, 24601.0], "unit": "score", "name": "ext.html", "value": 17624.170670376472}, {"lowerIsBetter": false, "replicates": [348.6583148514456, 349.3990336621012, 350.3203784657764, 352.80771979271304, 353.1401038471321, 13720.108192967451, 14171.92914035431, 16424.343026278948, 17076.743848842278, 17648.294068237272, 16154.353825846982, 16292.42976495823, 17136.400225992093, 17244.82755172448, 17353.826461735378, 5102.668326558771, 5367.1680889462, 5383.353997520303, 5385.596080293974, 5392.2181283713835, 3926.803659817012, 5265.183896496049, 5665.8583535411735, 5797.985352563305, 5856.385079566648, 5645.407232240617, 5752.712364381785, 5760.308762948452, 5779.479846813786, 5792.247007888968, 5715.428457154251, 5726.3987281335485, 5734.713264336809, 5754.165645981351, 5758.769649214048, 4981.352424184833, 5003.149464591028, 5063.088644044071, 5090.49095090488, 5101.923471147936, 20177.495562610904, 20439.0, 20670.276540321167, 20698.896505517525, 20849.58300833975, 2042.1831267493121, 2132.776058513865, 2217.0355895185644, 2325.197806756666, 2662.560677488212, 12248.387580620933, 12801.359932003455, 12950.287734174617, 12970.351482425935, 13539.0, 1844.1609067874085, 1864.2263460663808, 1875.6905110656726, 1901.1634880652468, 1922.8750131241563, 60424.395756042315, 60671.0, 61220.38779612191, 62657.373426266066, 69279.30720692778, 14746.41014359435, 15053.559200036016, 15485.148316842457, 15597.532074037797, 15628.765568516481, 21048.473788155112, 22693.88653056724, 23389.41526461852, 24780.50438991246, 24849.751502484745, 8296.211859873303, 8502.362322825813, 8549.145085491526, 8686.087960764155, 8793.296536277083, 7263.0, 7282.4902256841515, 7290.817729556804, 7326.3406293433845, 7333.523320984119, 7779.649915753805, 7885.5662938538935, 7887.329576985906, 7888.7633370998965, 7905.762827115196, 2913.1115009922178, 2950.8229506229263, 2985.820850748962, 2993.670696223414, 3032.408680307363, 779.8401327727802, 869.5695630662863, 875.4790899414838, 957.7797106665366, 971.2666936462925, 16481.6703665926, 16542.0, 16728.66542669164, 16750.581235468966, 17160.0, 439.923013472641, 443.8158164361818, 444.5621063252693, 468.14563421755435, 978.4765150644371, 5061.7469126544265, 6054.455099041107, 6191.25704915413, 6238.594491358138, 9520.714378568655, 407919.24767147074, 412795.0, 414647.0, 416492.0, 420630.0, 66260.66869665717, 66721.33278667151, 67185.3281467179, 72106.63946680233, 75550.0, 463.0, 471.9716816990992, 473.0208468470302, 473.6116384564693, 476.5115756349769, 9823.656172033945, 10049.949750251495, 10050.396976181453, 10339.379637221791, 10565.418901960466, 27661.170164895084, 27904.58143127895, 29939.700602994562, 33196.834015829765, 33363.0, 37393.813030934674, 38738.612613874626, 44021.0, 44171.77914110538, 44222.77888610665, 7750.496217746054, 7880.605969701377, 8044.839103217786, 8066.677332906618, 8081.272685458337, 11557.190996630155, 11762.0, 12169.756604868031, 12356.073294502768, 12644.0, 10433.32183408076, 10557.73605659834, 10636.680899573024, 10754.193435492212, 11014.889851101387, 11030.50362733663, 11052.392118433563, 11105.555777769121, 11217.71955701114, 11277.210595258253, 10953.726156846142, 11131.666050018512, 11202.66392008241, 11222.438878056228, 11306.4912078955, 7850.528968261922, 7953.32396746283, 8058.113607503287, 8096.635651395819, 8169.959150204449, 9339.766505837408, 10647.414392208502, 10702.357858528514, 10864.565417383215, 10968.835467468154], "unit": "score", "name": "jquery.html", "value": 8377.945016208194}, {"lowerIsBetter": false, "replicates": [1315.2963164706885, 1473.5284708893155, 1485.420685932486, 1629.9918500407503, 1770.6812773700728, 6784.491163162764, 7266.745663901765, 7385.446091543129, 7402.925970740298, 7416.369608583277, 7310.524815886964, 7458.440616953723, 7471.1408188058385, 7472.887906681404, 7494.325510704036, 1922.0774028466274, 2074.170331867254, 2092.184048221195, 2157.46063484129, 2202.6695995600626, 2167.6315026445463, 2179.139240000199, 2182.15986845065, 2185.945351366213, 2188.091941844141, 2163.7078994335798, 2166.4367264511184, 2169.501014766605, 2176.9564608707897, 2178.477165480281, 2138.9251376201837, 2171.6308227601266, 2176.5211653436218, 2181.1057466438842, 2182.138055468089, 2143.689165071072, 2151.38685474639, 2155.611989841828, 2163.8377121715935, 2179.836512261587, 6377.1709677742065, 6498.220213574401, 7383.446241531907, 7436.0333156689285, 7437.3306402423505, 4062.7968601569805, 4458.684688017042, 4503.437070366172, 4538.818447262105, 4555.020670555847, 2892.7107289271116, 2943.3524624582556, 2991.820490770561, 2994.505906525421, 3016.0499442675487, 917.5045475443253, 934.4486752815872, 943.193569498083, 947.3842002698301, 991.2565575818137, 10188.134008609277, 10265.435401052939, 10290.536925838354, 10306.690799275959, 10633.57465701371, 4205.243056249843, 4215.262329092397, 4218.514870789899, 4295.205387003414, 4302.032042790347, 9115.498647574315, 9132.634694612141, 9133.497657628894, 9148.222401095982, 9164.221041211438, 4088.0, 4520.61574766142, 4764.4282686077195, 4768.856934291976, 4781.928271075936, 4112.773797441169, 4120.361343991686, 4159.563245859141, 4172.0, 4182.623563879265, 3572.8034958077037, 3583.498310236569, 3596.478510615946, 3610.801405922647, 3626.9818650906577, 1088.9346639201674, 1093.6773651772746, 1097.0839349143555, 1097.8078836203633, 1128.8758236593967, 888.493558671564, 933.3606479561522, 975.078550769516, 986.7089208683456, 994.0159242350021, 6830.829229269209, 6916.619585922823, 6980.685869135901, 6993.405560527311, 6999.650017499104, 466.41697877652933, 466.6849876333461, 472.77070620749595, 500.4320096690283, 505.3001592794007, 5417.377001644862, 5430.348358197041, 5440.292761940922, 5446.482584154499, 5464.453554644504, 8935.687250946186, 9340.906590934139, 9418.858717119248, 9452.10205030521, 9466.432014079177, 9093.31800114994, 9251.167394934488, 9261.259099272176, 9274.582643781046, 9289.581968811419, 6802.115724955823, 6820.249772525019, 6883.174019117738, 6894.896576551356, 7179.210286868441, 6888.586684798928, 6942.028116063654, 6971.233164352018, 6972.6513674315065, 7010.369066784014, 7612.429067820047, 7918.802029949297, 7920.326772224195, 7931.444798864024, 8086.87869681967, 8044.477108987897, 8347.707830225912, 8369.163083691583, 8373.037100733494, 8389.412741108064, 6076.939230607814, 6198.845028874314, 6214.471769899609, 6816.522843400914, 6866.313368663094, 6658.3674550917585, 7158.6420678964805, 7335.266473352622, 7355.77932662021, 7386.039814824038, 6607.57050791697, 6684.83287917806, 6787.117674702257, 6790.388865002174, 6946.235914049552, 6591.934080659324, 6643.966780166262, 6667.299933507106, 6747.730090796314, 6835.829104272433, 6700.5309628327495, 6898.6205758681745, 6958.095447591983, 6964.477664175106, 6978.162620485574, 6325.0, 6345.587536810092, 6382.20222472191, 6383.744650213939, 6398.200224971879, 6396.520260980538, 6440.388163124496, 6454.612723236621, 6468.029795530708, 6485.54601177913], "unit": "score", "name": "mootools.html", "value": 4204.901147904632}, {"lowerIsBetter": false, "replicates": [327.4891169775151, 328.26796244375026, 328.4219773199169, 332.40001796756854, 337.47185654450783, 5649.604527683064, 5809.448102430273, 5869.32502762182, 5875.471207591316, 5886.911696324553, 5655.0, 5812.50593699535, 5813.941860581393, 5821.301443826736, 5821.796237131702, 4541.4096167498265, 4545.659075569329, 4546.181687296285, 4555.476120246164, 4558.247889098295, 4346.0, 4429.77851107445, 4497.527759585246, 4511.503734589192, 4563.452385713719, 4442.733435993835, 4459.843905463309, 4466.151431228072, 4489.304157855538, 4500.97749511252, 4505.03141824506, 4505.526919673436, 4511.120331535352, 4512.571305725967, 4516.16450956574, 3968.265870813909, 3975.2447035063533, 3978.76127432355, 3982.1637456134245, 3983.780892050936, 5427.4301198374, 5573.08044172711, 5594.328680558318, 5600.747966341524, 5610.354809196913, 5140.434552199255, 5142.897142057175, 5143.819966301162, 5150.356205474316, 5153.0466863630345, 5950.761969521214, 6218.626882387071, 6223.097650840648, 6224.221972253468, 6226.782062627786, 4202.285611446062, 4204.810783514749, 4210.536840947493, 4244.978775106105, 4259.467566554181, 6370.490360771174, 6444.677766111676, 6450.419462248373, 6461.6446095464735, 6462.903056454157, 4355.455568053993, 4361.956380436155, 4362.040351122748, 4369.825206991748, 4370.8854242168045, 4308.2460569400855, 4350.978245108754, 4376.277914144131, 4385.859676484073, 4389.912201755947, 5104.464031276662, 5112.335396398519, 5125.308083408767, 5126.436092029874, 5129.769160387791, 5945.048792193228, 6095.847603809941, 6228.439440450381, 6236.750529978841, 6240.219972503437, 1530.4719871644265, 1562.0471512377442, 1565.107888503542, 1565.7025165218572, 1566.843315668424, 1003.1824063388315, 1026.461107918349, 1027.6043723166636, 1041.3699711674478, 1042.2912419554775, 5816.680082595498, 5862.208601838697, 5866.23738913947, 5868.471837534642, 5875.9118613220835, 6387.329330420239, 6391.296957334689, 6394.0, 6408.231012278557, 6411.0, 5857.0, 5861.648301101947, 5868.559858010666, 5879.706014699248, 5881.9705901470215, 3363.814990175515, 3458.2564748579175, 3469.063352894704, 3469.670381313771, 3474.922773940036, 6606.537542371988, 6634.734610615618, 6649.401553860079, 6657.267700552935, 6658.300878407697, 6528.281888992207, 6580.374864387875, 6580.539362244693, 6586.0, 6589.110470086477, 6430.871382572323, 6572.211334639874, 6651.268360480343, 6671.39957403836, 6677.165354330708, 6388.488920886225, 6407.583507072025, 6408.359164083555, 6431.0, 6434.806955791147, 6368.203974503187, 6382.042693595812, 6391.93608063932, 6397.712102955285, 6404.135441715309, 6522.249941256627, 6629.7679581214425, 6632.303608121078, 6641.701123449553, 6652.0, 4346.043870348518, 4416.889577760582, 4422.7346359218545, 4426.468823741172, 4427.490838553479, 6202.193714817045, 6278.309385967448, 6376.776812811529, 6422.454091402283, 6425.068365086942, 6226.5641405101205, 6266.279377871603, 6277.497800175884, 6280.717367718372, 6284.088807123033, 6258.311585725475, 6261.749530018748, 6262.0, 6265.49876009909, 6270.028145637433, 6040.365761595145, 6133.4786543142545, 6147.477464415575, 6151.108089326933, 6170.259568851767, 6263.874722505433, 6279.058141278662, 6282.649230415483, 6304.148939893239, 6308.243010838729, 6104.9389506106145, 6107.083937409424, 6110.938890611037, 6123.173371594778, 6136.539759517965], "unit": "score", "name": "prototype.html", "value": 4674.179671408177}, {"lowerIsBetter": false, "replicates": [629.7764293675745, 806.9596520173992, 808.2846680687593, 816.1389733830806, 821.9917800821994, 13706.725865482684, 13956.2324072176, 14001.579952601438, 14005.0, 14009.159450432968, 12884.871151288484, 12937.094403391766, 12947.741045179115, 12969.546065887673, 12977.935110324435, 673.4713250098673, 681.9863602727943, 803.5580430763086, 804.4569915307188, 825.1748251748252, 817.7383237364046, 822.235321151329, 823.8640624296984, 825.4345773145385, 830.4767996162409, 864.9567521623925, 866.0213958227195, 866.2766589897443, 867.8090820019618, 869.6695255802786, 701.3477465956659, 849.2569002123142, 864.4035615425337, 865.5066612031145, 865.9437136586101, 633.4805459523193, 637.0857819029707, 637.2703254773305, 637.9457746091588, 638.0524920493064, 12874.16317939331, 12882.420291086924, 12898.677533061655, 12913.483460661562, 12929.935350323189, 12559.120861539695, 12932.159409638343, 12982.480700771957, 13024.54414095502, 13065.477380904753, 10318.968103189696, 10717.249792514527, 10758.139348852073, 10882.72793180169, 11082.667519974413, 3586.964130358689, 3651.379265524841, 3665.8533658653623, 3672.1003354178397, 3745.213505163919, 47225.0, 48205.0, 48234.27648585274, 49087.26369104466, 49088.01823963536, 10092.747681308027, 10098.192144628412, 10317.84523232152, 10614.628488002883, 10738.0, 18818.81181188198, 18824.905875470537, 18842.0, 18844.623107538046, 18858.905705471385, 7343.77968660941, 7389.741359052515, 7480.438967077491, 7579.924200758032, 7581.090269167625, 4258.467691538558, 4324.654027677778, 4329.935050974238, 4350.2387082260475, 4411.536788637211, 3156.4002839460886, 3189.4737368333963, 3204.3110731192905, 3211.855466504013, 3221.951670724941, 497.4354108087347, 538.4857461124617, 544.4256309593338, 606.6360183889719, 613.362103412455, 481.30932112418094, 557.7768892443055, 601.6119602856133, 603.6106711171358, 618.7957973868613, 8009.8398032040195, 8013.719519816778, 8052.758417247492, 8092.676292948334, 8141.715039973691, 613.5613036678707, 618.9597676151036, 631.7757196195284, 640.5516138702833, 644.4522156167315, 3665.9266814664093, 3773.509443772347, 3832.693384529287, 3963.246983073207, 4046.7976601169826, 49918.25122623164, 51376.229356559685, 51386.74306628518, 51732.482675173516, 52098.73950630223, 43330.783346083066, 43471.0, 44444.55555444469, 44630.0, 45412.77293613577, 39612.00969975716, 39727.60272397239, 40017.19965600672, 40148.197036059115, 40704.59295407008, 8055.436119471581, 8129.9187008129165, 8149.429539932148, 8350.79123021905, 8390.370722195981, 32041.83979080183, 32124.0, 32868.50697239591, 33025.50461743028, 33290.167745806546, 36479.0, 37020.44469332908, 40614.59385406108, 41058.0, 41117.79441102775, 10636.787264254517, 10658.89341106579, 11308.321500709984, 11311.321320720783, 11571.768564628828, 11757.118216133653, 12142.453589588313, 12328.32194229326, 12538.122331437076, 12552.184108032949, 10617.0, 10734.51694673726, 10746.301490403099, 10824.458777061272, 11083.224174308043, 10343.379397235889, 10531.31546449509, 10549.472526373496, 10614.203934705083, 10743.946280268548, 10202.28583999113, 10464.790704185722, 10475.423851687925, 10605.787884242118, 10710.410927398756, 9394.906050939404, 9666.303594415343, 9704.2721795867, 9798.951005244928, 10055.748606284902, 8728.214460698568, 8762.904601996855, 8907.421017633833, 9220.907790922005, 9230.400023998418], "unit": "score", "name": "yui.html", "value": 6129.0834618027175}], "name": "dromaeo_css", "value": 6690.800153688439}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 113.0, 109.0, 111.0, 113.0, 112.0, 101.0, 111.0, 112.0, 112.0], "unit": "score", "name": "ai-astar", "value": 110.6}, {"lowerIsBetter": true, "replicates": [151.0, 126.0, 159.0, 164.0, 132.0, 154.0, 149.0, 128.0, 162.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 145.2}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 163.0, 161.0, 159.0, 160.0, 160.0, 168.0, 166.0, 161.0], "unit": "score", "name": "audio-dft", "value": 162.3}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 102.0, 94.0, 73.0, 74.0, 73.0, 113.0, 102.0, 76.0], "unit": "score", "name": "audio-fft", "value": 85.4}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 142.0, 95.0, 97.0, 97.0, 138.0, 95.0, 96.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 108.9}, {"lowerIsBetter": true, "replicates": [119.0, 116.0, 116.0, 116.0, 114.0, 115.0, 115.0, 115.0, 116.0, 115.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 115.7}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 145.0, 148.0, 148.0, 145.0, 145.0, 146.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 69.0, 57.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.0}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [81.0, 84.0, 84.0, 82.0, 84.0, 83.0, 91.0, 83.0, 83.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.0}, {"lowerIsBetter": true, "replicates": [118.0, 121.0, 115.0, 117.0, 125.0, 117.0, 122.0, 116.0, 122.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.9}, {"lowerIsBetter": true, "replicates": [165.0, 152.0, 151.0, 153.0, 153.0, 157.0, 154.0, 159.0, 139.0, 153.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 153.6}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 77.0, 67.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1523.1000000000004}]} 22:21:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1523.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6690.80"}}} 22:21:57 INFO - Return code: 0 22:21:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:21:57 INFO - # TBPL SUCCESS # 22:21:57 INFO - Running post-action listener: _resource_record_post_action 22:21:57 INFO - Running post-run listener: _resource_record_post_run 22:21:58 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 550834176; Read time: 0; Write time: 446008 22:21:58 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 240574464; Read time: 0; Write time: 249316 22:21:58 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 298303488; Read time: 0; Write time: 193160 22:21:58 INFO - Running post-run listener: _upload_blobber_files 22:21:58 INFO - Blob upload gear active. 22:21:58 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:21:58 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:21:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 22:21:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 22:21:59 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:00 INFO - (blobuploader) - INFO - Done attempting. 22:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:22:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:01 INFO - (blobuploader) - INFO - Done attempting. 22:22:01 INFO - (blobuploader) - INFO - Iteration through files over. 22:22:01 INFO - Return code: 0 22:22:01 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:22:01 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:22:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f37562758e01d278144f41f8d04a5fd8075c8b6263686f94bf7d4e1e7910d18f5be47beb149954616f1148a7a29f1e609d0da0ced2e96c26f772eed391c797c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5613282cada8e735e6ce2e3ea6a0f3ab60a8a072324125be67c8510bd7b0217343b22453d972179dd7919b456f85307ec90d9abab17a9167ac4cdeb9313f92ef"} 22:22:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:22:01 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:22:01 INFO - Contents: 22:22:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f37562758e01d278144f41f8d04a5fd8075c8b6263686f94bf7d4e1e7910d18f5be47beb149954616f1148a7a29f1e609d0da0ced2e96c26f772eed391c797c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5613282cada8e735e6ce2e3ea6a0f3ab60a8a072324125be67c8510bd7b0217343b22453d972179dd7919b456f85307ec90d9abab17a9167ac4cdeb9313f92ef"} 22:22:01 INFO - Running post-run listener: copy_logs_to_upload_dir 22:22:01 INFO - Copying logs to upload dir... 22:22:01 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 22:22:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.872873 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-02-19 22:22:02.333474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-19 22:22:02.340514) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f37562758e01d278144f41f8d04a5fd8075c8b6263686f94bf7d4e1e7910d18f5be47beb149954616f1148a7a29f1e609d0da0ced2e96c26f772eed391c797c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5613282cada8e735e6ce2e3ea6a0f3ab60a8a072324125be67c8510bd7b0217343b22453d972179dd7919b456f85307ec90d9abab17a9167ac4cdeb9313f92ef"} build_url:https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011527 build_url: 'https://queue.taskcluster.net/v1/task/Jqoh1bttQu-i2GmytFawZw/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/Firefox/sha512/f37562758e01d278144f41f8d04a5fd8075c8b6263686f94bf7d4e1e7910d18f5be47beb149954616f1148a7a29f1e609d0da0ced2e96c26f772eed391c797c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5613282cada8e735e6ce2e3ea6a0f3ab60a8a072324125be67c8510bd7b0217343b22453d972179dd7919b456f85307ec90d9abab17a9167ac4cdeb9313f92ef"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-19 22:22:02.365882) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:22:02.369220) ========= 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=337fe3f4b1febe37b85fccc40000019e-1455944318.756122-357297721 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004206 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 22:22:02.417684) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 22:22:02.417983) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 22:22:02.421300) ========= ========= Total master_lag: 1.19 =========