builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1456617051.22 results: success (0) buildid: 20160227133013 builduid: 10207a6c8ffc41bc8cae5b711620d604 revision: 7314001ba54ce6358b7090c488130738d50858fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.220901) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.221329) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.221668) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005726 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.270623) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.270967) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.360298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.360604) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004594 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.411735) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.412075) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.412460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.412817) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-27 15:50:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.2M=0s 2016-02-27 15:50:51 (35.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174499 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.602514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.602832) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017292 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.653629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:51.654162) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7314001ba54ce6358b7090c488130738d50858fb --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 7314001ba54ce6358b7090c488130738d50858fb --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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-27 15:50:51,771 truncating revision to first 12 chars 2016-02-27 15:50:51,771 Setting DEBUG logging. 2016-02-27 15:50:51,771 attempt 1/10 2016-02-27 15:50:51,771 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/7314001ba54c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-27 15:50:52,032 unpacking tar archive at: mozilla-inbound-7314001ba54c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.560598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:52.231512) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:52.232249) ========= echo 7314001ba54ce6358b7090c488130738d50858fb in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'7314001ba54ce6358b7090c488130738d50858fb'] 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 7314001ba54ce6358b7090c488130738d50858fb program finished with exit code 0 elapsedTime=0.004821 script_repo_revision: '7314001ba54ce6358b7090c488130738d50858fb' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:52.284051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:52.284395) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 15:50:52.301279) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-02-27 15:50:52.301581) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:52 INFO - MultiFileLogger online at 20160227 15:50:52 in /builds/slave/test-pgo 15:50:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 15:50:52 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:50:52 INFO - {'append_to_log': False, 15:50:52 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:50:52 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 15:50:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:50:52 INFO - 'branch': 'Mozilla-Inbound', 15:50:52 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:52 INFO - 'config_files': ('talos/linux_config.py',), 15:50:52 INFO - 'default_actions': ('clobber', 15:50:52 INFO - 'read-buildbot-config', 15:50:52 INFO - 'download-and-extract', 15:50:52 INFO - 'populate-webroot', 15:50:52 INFO - 'create-virtualenv', 15:50:52 INFO - 'install', 15:50:52 INFO - 'run-tests'), 15:50:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:52 INFO - 'download_minidump_stackwalk': True, 15:50:52 INFO - 'download_symbols': 'ondemand', 15:50:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:52 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:52 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:52 INFO - 'installer_path': 'installer.exe', 15:50:52 INFO - 'log_level': 'info', 15:50:52 INFO - 'log_name': 'talos', 15:50:52 INFO - 'log_to_console': True, 15:50:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:52 INFO - 'opt_config_files': (), 15:50:52 INFO - 'pip_index': False, 15:50:52 INFO - 'sps_profile': False, 15:50:52 INFO - 'sps_profile_interval': 0, 15:50:52 INFO - 'suite': 'dromaeojs', 15:50:52 INFO - 'system_bits': '32', 15:50:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:52 INFO - 'title': 'talos-linux64-ix-038', 15:50:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:52 INFO - 'use_talos_json': True, 15:50:52 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:50:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:52 INFO - 'work_dir': 'build'} 15:50:52 INFO - ##### 15:50:52 INFO - ##### Running clobber step. 15:50:52 INFO - ##### 15:50:52 INFO - Running pre-action listener: _resource_record_pre_action 15:50:52 INFO - Running main action method: clobber 15:50:52 INFO - rmtree: /builds/slave/test-pgo/build 15:50:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:50:53 INFO - Running post-action listener: _resource_record_post_action 15:50:53 INFO - ##### 15:50:53 INFO - ##### Running read-buildbot-config step. 15:50:53 INFO - ##### 15:50:53 INFO - Running pre-action listener: _resource_record_pre_action 15:50:53 INFO - Running main action method: read_buildbot_config 15:50:53 INFO - Using buildbot properties: 15:50:53 INFO - { 15:50:53 INFO - "project": "", 15:50:53 INFO - "product": "firefox", 15:50:53 INFO - "script_repo_revision": "production", 15:50:53 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 15:50:53 INFO - "repository": "", 15:50:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 15:50:53 INFO - "buildid": "20160227133013", 15:50:53 INFO - "pgo_build": "True", 15:50:53 INFO - "basedir": "/builds/slave/test-pgo", 15:50:53 INFO - "buildnumber": 751, 15:50:53 INFO - "slavename": "talos-linux64-ix-038", 15:50:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:50:53 INFO - "platform": "ubuntu64_hw", 15:50:53 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 15:50:53 INFO - "branch": "mozilla-inbound", 15:50:53 INFO - "repo_path": "integration/mozilla-inbound", 15:50:53 INFO - "revision": "7314001ba54ce6358b7090c488130738d50858fb", 15:50:53 INFO - "stage_platform": "linux64-pgo", 15:50:53 INFO - "builduid": "10207a6c8ffc41bc8cae5b711620d604", 15:50:53 INFO - "slavebuilddir": "test" 15:50:53 INFO - } 15:50:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 15:50:53 INFO - Running post-action listener: _resource_record_post_action 15:50:53 INFO - ##### 15:50:53 INFO - ##### Running download-and-extract step. 15:50:53 INFO - ##### 15:50:53 INFO - Running pre-action listener: _resource_record_pre_action 15:50:53 INFO - Running main action method: download_and_extract 15:50:53 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:53 INFO - https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:50:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/test_packages.json 15:50:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/test_packages.json 15:50:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:50:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:51:00 INFO - Downloaded 1448 bytes. 15:51:00 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:51:00 INFO - Using the following test package requirements: 15:51:00 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 15:51:00 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:51:00 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:51:00 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'jsshell-linux-x86_64.zip'], 15:51:00 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:51:00 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 15:51:00 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:51:00 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:51:00 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:51:00 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 15:51:00 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 15:51:00 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:51:00 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:00 INFO - https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:51:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 15:51:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 15:51:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:11 INFO - Downloaded 23371823 bytes. 15:51:11 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'] 15:51:11 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 15:51:13 INFO - Return code: 0 15:51:13 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 15:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:13 INFO - https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:51:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 15:51:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 15:51:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:15 INFO - Downloaded 23371823 bytes. 15:51:15 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'] 15:51:15 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 15:51:17 INFO - Return code: 0 15:51:17 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')]} 15:51:17 INFO - https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:51:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 15:51:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 15:51:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:19 INFO - Downloaded 10713410 bytes. 15:51:19 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'] 15:51:19 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 15:51:19 INFO - Return code: 0 15:51:19 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')]} 15:51:19 INFO - https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:51:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 15:51:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 15:51:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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 15:51:21 INFO - Downloaded 56632378 bytes. 15:51:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 15:51:21 INFO - mkdir: /builds/slave/test-pgo/properties 15:51:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:51:21 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:51:21 INFO - Contents: 15:51:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 15:51:21 INFO - Running post-action listener: _resource_record_post_action 15:51:21 INFO - Running post-action listener: set_extra_try_arguments 15:51:21 INFO - ##### 15:51:21 INFO - ##### Running populate-webroot step. 15:51:21 INFO - ##### 15:51:21 INFO - Running pre-action listener: _resource_record_pre_action 15:51:21 INFO - Running main action method: populate_webroot 15:51:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:21 INFO - '--tppagecycles', 15:51:21 INFO - '7'], 15:51:21 INFO - 'tests': ['tsvgm']}}, 15:51:21 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:51:21 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:21 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:51:21 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'tests': ['damp', 'tps']}, 15:51:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['damp', 'tps']}, 15:51:21 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:51:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:21 INFO - 'other': {'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'other_l64': {'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'other_nol64': {'tests': ['a11yr', 15:51:21 INFO - 'ts_paint', 15:51:21 INFO - 'tpaint', 15:51:21 INFO - 'sessionrestore', 15:51:21 INFO - 'sessionrestore_no_auto_restore']}, 15:51:21 INFO - 'svgr': {'tests': ['tsvgx', 15:51:21 INFO - 'tsvgr_opacity', 15:51:21 INFO - 'tart', 15:51:21 INFO - 'tscrollx', 15:51:21 INFO - 'cart']}, 15:51:21 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['tsvgx', 15:51:21 INFO - 'tsvgr_opacity', 15:51:21 INFO - 'tart', 15:51:21 INFO - 'tscrollx', 15:51:21 INFO - 'cart']}, 15:51:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'tests': ['tp5o']}, 15:51:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'talos_options': ['--e10s'], 15:51:21 INFO - 'tests': ['tp5o']}, 15:51:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'talos_options': ['--xperf_path', 15:51:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:21 INFO - 'tests': ['tp5n']}, 15:51:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:21 INFO - 'talos_options': ['--e10s', 15:51:21 INFO - '--xperf_path', 15:51:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:21 INFO - 'tests': ['tp5n']}}, 15:51:21 INFO - 'talos.zip': {'path': '', 15:51:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:21 INFO - Running post-action listener: _resource_record_post_action 15:51:21 INFO - ##### 15:51:21 INFO - ##### Running create-virtualenv step. 15:51:21 INFO - ##### 15:51:21 INFO - Running pre-action listener: _resource_record_pre_action 15:51:21 INFO - Running main action method: create_virtualenv 15:51:21 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:51:21 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 15:51:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:51:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:22 INFO - Using real prefix '/usr' 15:51:22 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:51:22 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:24 INFO - Installing pip.................done. 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:24 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 15:51:24 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 15:51:24 INFO - Using env: {'DISPLAY': ':0', 15:51:24 INFO - 'HOME': '/home/cltbld', 15:51:24 INFO - 'LANG': 'en_US.UTF-8', 15:51:24 INFO - 'LANGUAGE': 'en_US:en', 15:51:24 INFO - 'LOGNAME': 'cltbld', 15:51:24 INFO - 'MAIL': '/var/mail/cltbld', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:24 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:24 INFO - 'SHELL': '/bin/bash', 15:51:24 INFO - 'SHLVL': '1', 15:51:24 INFO - 'TERM': 'linux', 15:51:24 INFO - 'TMOUT': '86400', 15:51:24 INFO - 'USER': 'cltbld', 15:51:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:24 INFO - '_': '/tools/buildbot/bin/python'} 15:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:25 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 15:51:25 INFO - Cleaning up... 15:51:25 INFO - Return code: 0 15:51:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:25 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:25 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 15:51:25 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 15:51:25 INFO - Using env: {'DISPLAY': ':0', 15:51:25 INFO - 'HOME': '/home/cltbld', 15:51:25 INFO - 'LANG': 'en_US.UTF-8', 15:51:25 INFO - 'LANGUAGE': 'en_US:en', 15:51:25 INFO - 'LOGNAME': 'cltbld', 15:51:25 INFO - 'MAIL': '/var/mail/cltbld', 15:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:25 INFO - 'MOZ_NO_REMOTE': '1', 15:51:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:25 INFO - 'NO_EM_RESTART': '1', 15:51:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:25 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:25 INFO - 'SHELL': '/bin/bash', 15:51:25 INFO - 'SHLVL': '1', 15:51:25 INFO - 'TERM': 'linux', 15:51:25 INFO - 'TMOUT': '86400', 15:51:25 INFO - 'USER': 'cltbld', 15:51:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:25 INFO - '_': '/tools/buildbot/bin/python'} 15:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:25 INFO - Downloading/unpacking psutil>=0.7.1 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:27 INFO - Installing collected packages: psutil 15:51:27 INFO - Running setup.py install for psutil 15:51:27 INFO - building 'psutil._psutil_linux' extension 15:51:27 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 15:51:29 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 15:51:29 INFO - building 'psutil._psutil_posix' extension 15:51:29 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 15:51:29 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 15:51:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:29 INFO - Successfully installed psutil 15:51:29 INFO - Cleaning up... 15:51:29 INFO - Return code: 0 15:51:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:51: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')]} 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:51:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:51:29 INFO - Using env: {'DISPLAY': ':0', 15:51:29 INFO - 'HOME': '/home/cltbld', 15:51:29 INFO - 'LANG': 'en_US.UTF-8', 15:51:29 INFO - 'LANGUAGE': 'en_US:en', 15:51:29 INFO - 'LOGNAME': 'cltbld', 15:51:29 INFO - 'MAIL': '/var/mail/cltbld', 15:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:29 INFO - 'MOZ_NO_REMOTE': '1', 15:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:29 INFO - 'NO_EM_RESTART': '1', 15:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:29 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:29 INFO - 'SHELL': '/bin/bash', 15:51:29 INFO - 'SHLVL': '1', 15:51:29 INFO - 'TERM': 'linux', 15:51:29 INFO - 'TMOUT': '86400', 15:51:29 INFO - 'USER': 'cltbld', 15:51:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:29 INFO - '_': '/tools/buildbot/bin/python'} 15:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:29 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:51:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:51:31 INFO - Installing collected packages: mozsystemmonitor 15:51:31 INFO - Running setup.py install for mozsystemmonitor 15:51:31 INFO - Successfully installed mozsystemmonitor 15:51:31 INFO - Cleaning up... 15:51:31 INFO - Return code: 0 15:51:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:31 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 15:51:31 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 15:51:31 INFO - Using env: {'DISPLAY': ':0', 15:51:31 INFO - 'HOME': '/home/cltbld', 15:51:31 INFO - 'LANG': 'en_US.UTF-8', 15:51:31 INFO - 'LANGUAGE': 'en_US:en', 15:51:31 INFO - 'LOGNAME': 'cltbld', 15:51:31 INFO - 'MAIL': '/var/mail/cltbld', 15:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:31 INFO - 'MOZ_NO_REMOTE': '1', 15:51:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:31 INFO - 'NO_EM_RESTART': '1', 15:51:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:31 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:31 INFO - 'SHELL': '/bin/bash', 15:51:31 INFO - 'SHLVL': '1', 15:51:31 INFO - 'TERM': 'linux', 15:51:31 INFO - 'TMOUT': '86400', 15:51:31 INFO - 'USER': 'cltbld', 15:51:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:31 INFO - '_': '/tools/buildbot/bin/python'} 15:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:31 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:31 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 15:51:31 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 15:51:31 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 15:51:31 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 15:51:31 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 15:51:31 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 15:51:33 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:33 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 15:51:33 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 15:51:33 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 15:51:33 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 15:51:33 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 15:51:33 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 15:51:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:51:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:34 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 15:51:34 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 15:51:34 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 15:51:34 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 15:51:34 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 15:51:34 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 15:51:34 INFO - Downloading docopt-0.6.1.tar.gz 15:51:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:34 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:34 INFO - Running setup.py install for blobuploader 15:51:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:34 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:51:34 INFO - Running setup.py install for requests 15:51:35 INFO - Running setup.py install for docopt 15:51:35 INFO - Successfully installed blobuploader requests docopt 15:51:35 INFO - Cleaning up... 15:51:35 INFO - Return code: 0 15:51:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:35 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:35 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 15:51:35 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 15:51:35 INFO - Using env: {'DISPLAY': ':0', 15:51:35 INFO - 'HOME': '/home/cltbld', 15:51:35 INFO - 'LANG': 'en_US.UTF-8', 15:51:35 INFO - 'LANGUAGE': 'en_US:en', 15:51:35 INFO - 'LOGNAME': 'cltbld', 15:51:35 INFO - 'MAIL': '/var/mail/cltbld', 15:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:35 INFO - 'MOZ_NO_REMOTE': '1', 15:51:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:35 INFO - 'NO_EM_RESTART': '1', 15:51:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:35 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:35 INFO - 'SHELL': '/bin/bash', 15:51:35 INFO - 'SHLVL': '1', 15:51:35 INFO - 'TERM': 'linux', 15:51:35 INFO - 'TMOUT': '86400', 15:51:35 INFO - 'USER': 'cltbld', 15:51:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:35 INFO - '_': '/tools/buildbot/bin/python'} 15:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:35 INFO - Running setup.py (path:/tmp/pip-Up5BY4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:35 INFO - Running setup.py (path:/tmp/pip-MMj3qs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:35 INFO - Running setup.py (path:/tmp/pip-K3lj2w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:35 INFO - Running setup.py (path:/tmp/pip-m4Ce1G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:51:35 INFO - Running setup.py (path:/tmp/pip-uBi8qM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:35 INFO - Running setup.py (path:/tmp/pip-OF5sUQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:35 INFO - Running setup.py (path:/tmp/pip-TzsyDY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:35 INFO - Running setup.py (path:/tmp/pip-OKM7Ff-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:51:35 INFO - Running setup.py (path:/tmp/pip-JspgAU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:51:36 INFO - Running setup.py (path:/tmp/pip-xjXpXw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:36 INFO - Running setup.py (path:/tmp/pip-1XHHS4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:36 INFO - Running setup.py (path:/tmp/pip-Pspg5B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:36 INFO - Running setup.py (path:/tmp/pip-4pmRIV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:36 INFO - Running setup.py (path:/tmp/pip-ksvgrp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:36 INFO - Running setup.py (path:/tmp/pip-acHQCp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:51:36 INFO - Running setup.py (path:/tmp/pip-Emm9L1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:51:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:51:36 INFO - Running setup.py (path:/tmp/pip-i9BajW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:51:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:36 INFO - Running setup.py install for manifestparser 15:51:36 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:51:36 INFO - Running setup.py install for mozcrash 15:51:36 INFO - Running setup.py install for mozdebug 15:51:36 INFO - Running setup.py install for mozdevice 15:51:37 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozfile 15:51:37 INFO - Running setup.py install for mozhttpd 15:51:37 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozinfo 15:51:37 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozInstall 15:51:37 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozleak 15:51:37 INFO - Running setup.py install for mozlog 15:51:37 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for moznetwork 15:51:37 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozprocess 15:51:37 INFO - Running setup.py install for mozprofile 15:51:37 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:51:37 INFO - Running setup.py install for mozrunner 15:51:38 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:51:38 INFO - Running setup.py install for mozscreenshot 15:51:38 INFO - Running setup.py install for moztest 15:51:38 INFO - Running setup.py install for mozversion 15:51:38 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:51:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:38 INFO - Cleaning up... 15:51:38 INFO - Return code: 0 15:51:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:38 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:38 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 15:51:38 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 15:51:38 INFO - Using env: {'DISPLAY': ':0', 15:51:38 INFO - 'HOME': '/home/cltbld', 15:51:38 INFO - 'LANG': 'en_US.UTF-8', 15:51:38 INFO - 'LANGUAGE': 'en_US:en', 15:51:38 INFO - 'LOGNAME': 'cltbld', 15:51:38 INFO - 'MAIL': '/var/mail/cltbld', 15:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:38 INFO - 'MOZ_NO_REMOTE': '1', 15:51:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:38 INFO - 'NO_EM_RESTART': '1', 15:51:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:38 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:38 INFO - 'SHELL': '/bin/bash', 15:51:38 INFO - 'SHLVL': '1', 15:51:38 INFO - 'TERM': 'linux', 15:51:38 INFO - 'TMOUT': '86400', 15:51:38 INFO - 'USER': 'cltbld', 15:51:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:38 INFO - '_': '/tools/buildbot/bin/python'} 15:51:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:38 INFO - Running setup.py (path:/tmp/pip-07eOKS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:38 INFO - Running setup.py (path:/tmp/pip-CjrrH6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:38 INFO - Running setup.py (path:/tmp/pip-RH9XzX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:38 INFO - Running setup.py (path:/tmp/pip-PqsJC9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:51:39 INFO - Running setup.py (path:/tmp/pip-lnKOWX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:39 INFO - Running setup.py (path:/tmp/pip-exX5Zp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:39 INFO - Running setup.py (path:/tmp/pip-6drsdn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:39 INFO - Running setup.py (path:/tmp/pip-GgV2pC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:51:39 INFO - Running setup.py (path:/tmp/pip-c580wb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:51:39 INFO - Running setup.py (path:/tmp/pip-He7L8_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:39 INFO - Running setup.py (path:/tmp/pip-Ce_bux-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:39 INFO - Running setup.py (path:/tmp/pip-h2ta7u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:39 INFO - Running setup.py (path:/tmp/pip-hfb6Hj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:39 INFO - Running setup.py (path:/tmp/pip-Ivu3q3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:39 INFO - Running setup.py (path:/tmp/pip-VmbncP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:51:39 INFO - Running setup.py (path:/tmp/pip-ZlaHLs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:51:39 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)) 15:51:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:51:39 INFO - Running setup.py (path:/tmp/pip-nRGTyz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:51:39 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)) 15:51:39 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)) 15:51:39 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)) 15:51:39 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)) 15:51:39 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)) 15:51:39 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)) 15:51:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:41 INFO - Downloading blessings-1.6.tar.gz 15:51:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:41 INFO - Installing collected packages: blessings 15:51:41 INFO - Running setup.py install for blessings 15:51:41 INFO - Successfully installed blessings 15:51:41 INFO - Cleaning up... 15:51:42 INFO - Return code: 0 15:51:42 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:51:42 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:51:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:51:42 INFO - Reading from file tmpfile_stdout 15:51:42 INFO - Current package versions: 15:51:42 INFO - argparse == 1.2.1 15:51:42 INFO - blessings == 1.6 15:51:42 INFO - blobuploader == 1.2.4 15:51:42 INFO - docopt == 0.6.1 15:51:42 INFO - manifestparser == 1.1 15:51:42 INFO - mozInstall == 1.12 15:51:42 INFO - mozcrash == 0.16 15:51:42 INFO - mozdebug == 0.1 15:51:42 INFO - mozdevice == 0.48 15:51:42 INFO - mozfile == 1.2 15:51:42 INFO - mozhttpd == 0.7 15:51:42 INFO - mozinfo == 0.9 15:51:42 INFO - mozleak == 0.1 15:51:42 INFO - mozlog == 3.1 15:51:42 INFO - moznetwork == 0.27 15:51:42 INFO - mozprocess == 0.22 15:51:42 INFO - mozprofile == 0.28 15:51:42 INFO - mozrunner == 6.11 15:51:42 INFO - mozscreenshot == 0.1 15:51:42 INFO - mozsystemmonitor == 0.0 15:51:42 INFO - moztest == 0.7 15:51:42 INFO - mozversion == 1.4 15:51:42 INFO - psutil == 3.1.1 15:51:42 INFO - requests == 1.2.3 15:51:42 INFO - wsgiref == 0.1.2 15:51:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:42 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 15:51:42 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 15:51:42 INFO - Using env: {'DISPLAY': ':0', 15:51:42 INFO - 'HOME': '/home/cltbld', 15:51:42 INFO - 'LANG': 'en_US.UTF-8', 15:51:42 INFO - 'LANGUAGE': 'en_US:en', 15:51:42 INFO - 'LOGNAME': 'cltbld', 15:51:42 INFO - 'MAIL': '/var/mail/cltbld', 15:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:42 INFO - 'MOZ_NO_REMOTE': '1', 15:51:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:42 INFO - 'NO_EM_RESTART': '1', 15:51:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:42 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:42 INFO - 'SHELL': '/bin/bash', 15:51:42 INFO - 'SHLVL': '1', 15:51:42 INFO - 'TERM': 'linux', 15:51:42 INFO - 'TMOUT': '86400', 15:51:42 INFO - 'USER': 'cltbld', 15:51:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:42 INFO - '_': '/tools/buildbot/bin/python'} 15:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 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)) 15:51:42 INFO - Cleaning up... 15:51:42 INFO - Return code: 0 15:51:42 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:42 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:42 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 15:51:42 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 15:51:42 INFO - Using env: {'DISPLAY': ':0', 15:51:42 INFO - 'HOME': '/home/cltbld', 15:51:42 INFO - 'LANG': 'en_US.UTF-8', 15:51:42 INFO - 'LANGUAGE': 'en_US:en', 15:51:42 INFO - 'LOGNAME': 'cltbld', 15:51:42 INFO - 'MAIL': '/var/mail/cltbld', 15:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:42 INFO - 'MOZ_NO_REMOTE': '1', 15:51:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:42 INFO - 'NO_EM_RESTART': '1', 15:51:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:42 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:42 INFO - 'SHELL': '/bin/bash', 15:51:42 INFO - 'SHLVL': '1', 15:51:42 INFO - 'TERM': 'linux', 15:51:42 INFO - 'TMOUT': '86400', 15:51:42 INFO - 'USER': 'cltbld', 15:51:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:42 INFO - '_': '/tools/buildbot/bin/python'} 15:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:42 INFO - Downloading/unpacking jsonschema 15:51:42 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 15:51:42 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 15:51:42 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 15:51:42 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 15:51:42 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 15:51:42 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 15:51:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:44 INFO - Downloading/unpacking functools32 (from jsonschema) 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:44 INFO - warning: no files found matching '*.txt' 15:51:44 INFO - no previously-included directories found matching 'build' 15:51:44 INFO - no previously-included directories found matching 'dist' 15:51:44 INFO - no previously-included directories found matching '.git*' 15:51:44 INFO - Installing collected packages: jsonschema, functools32 15:51:44 INFO - Running setup.py install for functools32 15:51:44 INFO - warning: no files found matching '*.txt' 15:51:44 INFO - no previously-included directories found matching 'build' 15:51:44 INFO - no previously-included directories found matching 'dist' 15:51:44 INFO - no previously-included directories found matching '.git*' 15:51:44 INFO - Successfully installed jsonschema functools32 15:51:44 INFO - Cleaning up... 15:51:44 INFO - Return code: 0 15:51:44 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:51:44 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')]} 15:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:44 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')]} 15:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:44 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 0x7fcfcca72f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x186b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1938670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, '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': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', '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 15:51:44 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 15:51:44 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 15:51:44 INFO - Using env: {'DISPLAY': ':0', 15:51:44 INFO - 'HOME': '/home/cltbld', 15:51:44 INFO - 'LANG': 'en_US.UTF-8', 15:51:44 INFO - 'LANGUAGE': 'en_US:en', 15:51:44 INFO - 'LOGNAME': 'cltbld', 15:51:44 INFO - 'MAIL': '/var/mail/cltbld', 15:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:44 INFO - 'MOZ_NO_REMOTE': '1', 15:51:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:44 INFO - 'NO_EM_RESTART': '1', 15:51:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:51:44 INFO - 'PWD': '/builds/slave/test-pgo', 15:51:44 INFO - 'SHELL': '/bin/bash', 15:51:44 INFO - 'SHLVL': '1', 15:51:44 INFO - 'TERM': 'linux', 15:51:44 INFO - 'TMOUT': '86400', 15:51:44 INFO - 'USER': 'cltbld', 15:51:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:44 INFO - '_': '/tools/buildbot/bin/python'} 15:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:45 INFO - Downloading/unpacking flake8 15:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:51:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:51:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:47 INFO - no previously-included directories found matching 'docs/_build' 15:51:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:51:47 INFO - Running setup.py install for pep8 15:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:47 INFO - no previously-included directories found matching 'docs/_build' 15:51:47 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:51:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:51:47 INFO - Cleaning up... 15:51:47 INFO - Return code: 0 15:51:47 INFO - Running post-action listener: _resource_record_post_action 15:51:47 INFO - Running post-action listener: _start_resource_monitoring 15:51:47 INFO - Starting resource monitoring. 15:51:47 INFO - ##### 15:51:47 INFO - ##### Running install step. 15:51:47 INFO - ##### 15:51:47 INFO - Running pre-action listener: _resource_record_pre_action 15:51:47 INFO - Running main action method: install 15:51:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:51:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:51:48 INFO - Reading from file tmpfile_stdout 15:51:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:51:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:51:48 INFO - Reading from file tmpfile_stdout 15:51:48 INFO - Output received: 15:51:48 INFO - Usage: mozinstall [options] installer 15:51:48 INFO - Options: 15:51:48 INFO - -h, --help show this help message and exit 15:51:48 INFO - -d DEST, --destination=DEST 15:51:48 INFO - Directory to install application into. [default: 15:51:48 INFO - "/builds/slave/test-pgo"] 15:51:48 INFO - --app=APP Application being installed. [default: firefox] 15:51:48 INFO - mkdir: /builds/slave/test-pgo/build/application 15:51:48 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'] 15:51:48 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 15:52:04 INFO - Reading from file tmpfile_stdout 15:52:04 INFO - Output received: 15:52:04 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:52:04 INFO - Running post-action listener: _resource_record_post_action 15:52:04 INFO - ##### 15:52:04 INFO - ##### Running run-tests step. 15:52:04 INFO - ##### 15:52:04 INFO - Running pre-action listener: _resource_record_pre_action 15:52:04 INFO - Running main action method: run_tests 15:52:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:52:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:52:04 INFO - Python 2.7.3 15:52:04 INFO - Return code: 0 15:52:04 INFO - grabbing minidump binary from tooltool 15:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:04 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 0x19c78c0>, '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 0x19c87c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c8c50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:52:04 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 15:52:04 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 15:52:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:04 INFO - Return code: 0 15:52:04 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:52:04 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:52:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:52:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:52:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:52:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:52:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:52:05 INFO - Return code: 0 15:52:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:52:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 15:52:05 INFO - Using env: {'DISPLAY': ':0', 15:52:05 INFO - 'HOME': '/home/cltbld', 15:52:05 INFO - 'LANG': 'en_US.UTF-8', 15:52:05 INFO - 'LANGUAGE': 'en_US:en', 15:52:05 INFO - 'LOGNAME': 'cltbld', 15:52:05 INFO - 'MAIL': '/var/mail/cltbld', 15:52:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:52:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:05 INFO - 'MOZ_NO_REMOTE': '1', 15:52:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:52:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:52:05 INFO - 'NO_EM_RESTART': '1', 15:52:05 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:52:05 INFO - 'PWD': '/builds/slave/test-pgo', 15:52:05 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:52:05 INFO - 'SHELL': '/bin/bash', 15:52:05 INFO - 'SHLVL': '1', 15:52:05 INFO - 'TERM': 'linux', 15:52:05 INFO - 'TMOUT': '86400', 15:52:05 INFO - 'USER': 'cltbld', 15:52:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620', 15:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:05 INFO - '_': '/tools/buildbot/bin/python'} 15:52:05 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:52:05 INFO - mozversion application_buildid: 20160227133013 15:52:05 INFO - mozversion application_changeset: 7314001ba54ce6358b7090c488130738d50858fb 15:52:05 INFO - mozversion application_display_name: Nightly 15:52:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:05 INFO - mozversion application_name: Firefox 15:52:05 INFO - mozversion application_remotingname: firefox 15:52:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:52:05 INFO - mozversion application_vendor: Mozilla 15:52:05 INFO - mozversion application_version: 47.0a1 15:52:05 INFO - mozversion platform_buildid: 20160227133013 15:52:05 INFO - mozversion platform_changeset: 7314001ba54ce6358b7090c488130738d50858fb 15:52:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 15:52:05 INFO - mozversion platform_version: 47.0a1 15:52:05 INFO - using testdate: 1456617125 15:52:05 INFO - actual date: 1456617125 15:52:05 INFO - starting webserver on 'localhost:47494' 15:52:05 INFO - SUITE-START | Running 2 tests 15:52:05 INFO - TEST-START | dromaeo_css 15:52:05 INFO - operating with platform_type : linux_ 15:52:05 INFO - Initialising browser for dromaeo_css test... 15:52:05 INFO - TEST-INFO | started process 3327 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt9RKqg/profile http://localhost:47494/getInfo.html) 15:52:16 INFO - PROCESS | 3327 | __metrics Screen width/height:1600/1200 15:52:16 INFO - PROCESS | 3327 | colorDepth:24 15:52:16 INFO - PROCESS | 3327 | Browser inner width/height: 1024/697 15:52:16 INFO - PROCESS | 3327 | __metrics 15:52:17 INFO - PROCESS | 3327 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:17 INFO - PROCESS | 3327 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 15:52:18 INFO - TEST-INFO | 3327: exit 0 15:52:18 INFO - Browser initialized. 15:52:18 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:18 INFO - TEST-INFO | started process 3422 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpt9RKqg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:30 INFO - PROCESS | 3422 | RSS: Main: 150536192 15:52:30 INFO - PROCESS | 3422 | 15:53:21 INFO - PROCESS | 3422 | 1456617201581 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] 15:55:29 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47494/tests/dromaeo/cssquery-ext.html) 15:55:30 INFO - PROCESS | 3422 | RSS: Main: 242208768 15:55:30 INFO - PROCESS | 3422 | 15:58:32 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-ext.html (next: http://localhost:47494/tests/dromaeo/cssquery-jquery.html) 15:58:33 INFO - PROCESS | 3422 | RSS: Main: 233078784 15:58:33 INFO - PROCESS | 3422 | 16:01:35 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47494/tests/dromaeo/cssquery-mootools.html) 16:01:36 INFO - PROCESS | 3422 | RSS: Main: 232935424 16:01:36 INFO - PROCESS | 3422 | 16:04:37 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47494/tests/dromaeo/cssquery-prototype.html) 16:04:38 INFO - PROCESS | 3422 | RSS: Main: 216322048 16:04:38 INFO - PROCESS | 3422 | 16:07:41 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47494/tests/dromaeo/cssquery-yui.html) 16:07:41 INFO - PROCESS | 3422 | RSS: Main: 213803008 16:07:41 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | Cycle 1(1): loaded http://localhost:47494/tests/dromaeo/cssquery-yui.html (next: http://localhost:47494/tests/dromaeo/cssquery-dojo.html) 16:10:44 INFO - PROCESS | 3422 | RSS: Main: 282013696 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | __start_tp_report 16:10:44 INFO - PROCESS | 3422 | _x_x_mozilla_page_load 16:10:44 INFO - PROCESS | 3422 | _x_x_mozilla_page_load_details 16:10:44 INFO - PROCESS | 3422 | |i|pagename|runs| 16:10:44 INFO - PROCESS | 3422 | |0;dojo.html;3061.5101583746577;3069.570260163577;3137.686231376861;3156.7316778073864;3164.367126574685;4892.3884514435695;4932.210846264594;4934.037862616791;4951.975240123803;4966.478519755423;4978.3279257300255;4994.400671919375;5008.7996480140755;5055.924161137576;5107.872303192422;4545.795439205243;4581.427321584802;4650.162970665279;4668.159731248374;4691.0617876424685;4596.21864283071;4612.377329060584;4678.321643361711;4687.648426368019;4697.342372067913;4494.235979883411;4581.885452863672;4649.511801260886;4663.417072865892;4699.389079419653;4578.427696537933;4582.633389328879;4585.403897493338;4599.402077729907;4642.117997580449;4482.372467854503;4577.038821847416;4577.06170235101;4587.770611469413;4631.143238500888;4844.709317440965;4935.925961110586;4947.158982972904;4951.603871690256;4957.677750946213;5318;5321.467853214686;5328.3605967284175;5329.840104796862;5336.946630533684;5247.921281180786;5358.866028349284;5368.570514358881;5417.539509141728;5438.782448702047;5082.949170508285;5104.795808167632;5109.974450127763;5148.768305426265;5156.793728250866;5483.643563168461;5499.917501237484;5502.752376143083;5506.779728810883;5510.201020851954;5224.947750522446;5261.394939581997;5268.868278292996;5281.313429254249;5285.9735701322015;5394.08300588893;5397.568194544427;5412.025835349675;5429.918551221735;5467.507924286833;5359.5712343012465;5368.087425137736;5379.623426360117;5398.514133727984;5450.809221677222;5280.419153893069;5302.09864323066;5360.973195133999;5392.460753924576;5394.757235924393;4126.216018956388;4140.192662430857;4140.668746500333;4172.102997855475;4176.916461670749;3746.8126593670754;3759.9248015039548;3762.9059273517832;3770.905727356838;3797.582265950743;4920.015996800583;4960.528749768766;5057.570106540985;5073.239014147907;5150.665206761549;5342.332208473941;5400.91898621521;5402.432744561765;5403.459654034565;5430.266913966643;5105.106606343875;5110.054639891632;5110.514501122388;5113.8721531961255;5156.561692256125;4481.462224533077;4577;4625.583697467244;4630.814767409333;4646.303054541778;5607.355154157242;5629.915551266734;5634;5636.605437619327;5646.153077038477;5326.707031113326;5365.356157261153;5367.060764366299;5370.275012873369;5415.702136382379;5475.780968761205;5558.694271815089;5622.2128901954575;5654.6889921054735;5687.658740475585;5322.148456247137;5328.147496400557;5328.440513746001;5330.8933821324135;5331;5270.49930256625;5370.087085195604;5370.516653501204;5376.569874410116;5427.050266203478;5228.660137091077;5234.633575649668;5259.47405259471;5301.840944771663;5302.522772950453;5234.555062819703;5242.5281724644965;5249.921251181159;5250.763715632882;5281.102212623787;5063.544281014726;5138.691678499302;5179.300794392709;5180.352455943007;5183.663061900965;5264.9736751315995;5282.762275697526;5285.497877701612;5339.652922560022;5345.919811202757;5144;5178.145605974972;5183.585313175013;5185.585153187812;5188.455212202664 16:10:44 INFO - PROCESS | 3422 | |1;ext.html;481.2900971067676;510.056395668014;524.8163142899986;537.3873783886368;554.0691638048079;28526.71473285269;29021.41957160858;36085.27829443413;36584;36586.4512032319;40244.597554024454;40536.594634053654;40558.79720601393;40589;40786.38820417696;8410.327173826094;8603.870941935877;8617.48295102296;8621.13788621136;8623.784405389884;7585.1656317805;10244.692659220198;10497.842532361983;10500.2649814513;10512.894871051307;10115.443650599214;10215.131713804336;10222.948885255564;10225.130863876579;10232.795344093114;10024.097831195191;10032.347897386646;10099.29304948654;10108.140808031327;10116.747081323027;10032.398056116657;10172.643957461454;10191.063697897376;10193.541290641864;10491.16070714341;39250.41124383137;39816.20367592661;39816.800915995234;39839.8008009961;39877;3075.9538606920914;3118.5945827042337;3238.8218647974354;3267.313864088544;3638.909027274339;28833;29421;29666.55500167499;29861.402771944657;30450.84774576135;3895.104126050996;3942.763434193929;3954.307996100671;4030;4082.795860206978;274887.6255618729;276365;277162;277874;278398;60066.69966650226;60203.09695354573;60479.395206047375;60526.69736651376;60529.69735151296;63352.683236584446;64998.350016500175;66133.66933165303;66150.33849661538;66154.66922665355;15314.310856011283;15509.457168999032;15530.145841978574;15565.766513502307;15573.377064917277;10977.176711746652;11403.942980285046;11419.086473082134;11510.769784604261;11762.176647634755;19392.981880871746;19530.609387812165;19538.609227815363;19743.50641233952;19760.20959161646;7598.430117741192;7805.33654639362;7855.5286682799215;7879.960600196962;7960.840783184304;817.8691409374471;823.7364043506141;843.4306842881031;881.7927786970057;886.3795343259744;24335.391615209406;24987.875060624945;24992.500149996897;25224.621630675556;25295.36761580975;898.5327629632554;899.2805755395657;932.342698397628;938.5213541093955;967.9032096790264;12822.166559173624;12909.155390386626;13206.537771177962;13217.206967581977;13220.471181152838;302243;305928;307215;307478;308367;50457;51716.48283517192;52180;52203.73898130485;52253.216201757;1175.4122938530734;1244.9688757781128;1246.2584762066738;1338.625184948216;1350.7298540291783;17688.91155544214;24362.26913192607;24450.51098978046;24494.755052449247;24866.274238571474;49347.75326123346;49718.751406242736;49991;50006.49993500018;50013.24980125374;272033;272906;275091;275749;276423;27322;28496.715032849406;29837.55243671303;30336.089917302517;31611.20971975719;40311.596884031955;40586.391204131374;40778;41180.38229426621;41373.37939930843;31523;33640.663593364734;33642.831785840914;34009.829950850086;34177.65822341844;33578.160545986546;33886.66113338934;33925.49111763375;34264.657353427145;34505.654943450245;27427.31431714223;27576;27686;28565.28586785347;28565.857170714015;28405.289867752646;31121.68878311188;31191.220219493785;31298.53052204264;31342.68657313398;21619.891900540395;22266.88866555657;22356.552868942206;22516.774832252122;22587.661185082565 16:10:44 INFO - PROCESS | 3422 | |2;jquery.html;330.9222332751804;337.26476281705874;337.4684871327659;338.8018009464463;340.8006316304961;16203.270852811622;16441.342346306148;17026.489205323844;17428.82571174288;17813.82186178138;15209.619759506018;15469;16706;17333.133343332847;17348.39280625175;5879.52963762899;5964.2842858857;6014.037753959367;6037.909431358533;6061.302950160722;5888.352281249059;6051.27384713835;6077.969610151965;6095.420935011167;6097.786577469789;6061.212042434478;6065.969670151665;6086.056661217496;6393.648349340784;6666.166729158856;6610.40506354428;6624.072629831828;6636.369544893228;6658.234303055138;6661.566998145129;6278.560500764948;6349.174607301067;6388.265349484822;6392.968035159794;6462.289148193695;21279.893600532054;22114.22600208985;22154.778452215432;22845.771542284532;22951.08195672171;2251.6059689554263;2355.9293221203393;2480.5907025340975;2989.1331513860955;3104.4256812489534;13859.653508662263;13885.86114138866;14224;14461.78307325391;14862.851371486255;1976.160131943918;2068.4208421641965;2122.490602255463;2196.1544805250096;2265.0826415301663;68430.3156968429;74537;75627;76385.61807190928;76719.61640191819;14924.179170145746;15067.47263845782;15682.607934801721;15947.44183953556;15976.76034859478;24244.272671819872;24521.87739061293;24623.87688061584;24667.383315417257;24687.753122468905;9036.32227582934;9182.586783594754;9239.353245272901;9243.352965292364;9390.201832844285;7862.135165131831;7875.842483150305;7904.2095790421645;7993.600319984093;8021.839563208703;7877.56673382958;7878.803029924297;7960.56216908064;7987.520748755093;7997.920020799834;2889.1765846734;2995.7453616442413;3007.7593792496987;3034.347615262729;3065.4788685923445;731.9707211711471;765.4412279036335;780.4614815777096;793.6388943030908;828.0725587342215;14538.636534086732;15195.240237988057;15278.256827077437;15658.217089145497;15950.840491595167;792.8652129138061;798.5089170160426;816.5713000674574;825.2325337436242;828.5070383122032;8786.86819697705;8881.42270752412;8934.86597701035;8937.329700272505;9092.318076144316;404766;407093;408166;447587;449678;60590.697046514484;73782;74427.25572744312;74884.62557687177;76310.6184469074;1001.7545701303227;1002.3033991375924;1018.9490525473697;1046.1473898772629;1048.7220886465093;11585.246958947642;13411.865881341062;13991.58025259244;14038.78941815894;14160.645983850487;33012.50481242831;33485.16287092842;33714.494282586274;33881.32237355288;34195.48706769351;43294.35058474063;44317.33523997078;44612.776936115115;44675.53244675636;44684.55315446934;7317.451191160791;7416.592087435243;7430.665620047002;7574.5076570022775;7753.108392534705;11050.944745276543;12083.818742719042;12335.75328493443;12401.62795116148;12903.096783225084;9976.650817221362;10174.491275436347;10338.431386273527;10516.68449946503;10587.153027757608;10420.895791041992;10502.789944201226;10844.45777711127;10883.836742448711;10999.835002475129;7562.7521458960855;10114.645987390406;10419.791604168026;10520.579176832842;10540.156787456832;7403.925960740324;7461.664225109775;7470.775876723707;7572.242775722604;7627.275408836152;9430.858537121812;10246.77977614511;10304;10467.738306542398;10477.842832357368 16:10:44 INFO - PROCESS | 3422 | |3;mootools.html;1298.396245745728;1439.4817865568398;1448.3989144505028;1572.9606759831004;1708.666809972056;6875.44996400288;6901.654917254136;6945.479089068327;6957.269486703899;7011.649417529122;6937;7087.929120708792;7094.326039026284;7102.147742270922;7104;1886.500077479469;2097.276439628326;2119.4277545062823;2140.7538133114736;2143.2070134050423;2112.493001679601;2114.4925217947734;2120.54408302215;2122.0981083039724;2122.4269447249235;2070.213318938801;2115.132795553823;2130.2437634639714;2130.28635407138;2131.296672098204;2087.4155236533643;2094.9057292421953;2105.1789801977243;2107.515271487566;2124.649432843579;2107.947301317464;2113.0596884386455;2118.1633254864396;2120.3532922458644;2130.0095455613214;7240.312170343862;7269.236730143364;7284.9271507284775;7292.963535182344;7299.963500182518;4281.293586558214;4380.759058251795;4396.692231543793;4398.3842262083335;4401.625861801751;2903.404802015582;2917.343597690524;2944.926376840575;2950.350922796981;3066.4173806976605;866.7876370289256;871.8605023196258;892.9998401789971;952.8856537215579;1774.8935063896208;9312.674056408061;9345;9361.251099911991;9382;9396.201322887562;3622.166901612644;3747.2880152770886;3779.263043706451;3794.2221844521805;3807.390817469247;7616.7334143304715;7657.349125324298;7666.080070391588;7682.769516914502;7683.346915512132;3932.528096628423;4301.41930839339;4302.548232435612;4304.440422745086;4346.130773845243;3654.3604869147794;3685.9078523037138;3689.8524059037873;3878.4182372644327;3983.5817239190046;3102.0848849589424;3103.937921241608;3163.6994485524297;3167.17653410115;3183.793053451518;951.1962391778935;959.3572306554626;962.9422234665942;963.0754475703263;966.7051549277397;1280.5454063807401;1286.298967062953;1311.9803202951964;1326.7346530693899;1327.0909427042313;6763.12079429671;6795.796126116125;6918.861622767517;6920.169579650475;6921.8961715574305;440.594652919315;440.72674941535814;442.6812694859696;444.8198479615761;458.2210242587614;5269.815556455505;5356.223347614652;5368.194770784336;5401.621886467988;5403.270558474555;8161.347092232607;8247.639139541909;8309.626066827008;8520.659173633108;8805.735827925173;8350.24847763692;8471.49171049739;8509.106543812934;8551.871721924175;8568.742937711879;6582.341765823379;6641.535092543476;6790.94740315252;6800.897986530206;6880.449564034965;6737.461003119836;6759.391654751096;6767.289434609295;6803.863922721419;6807.251202367835;7624.885626715492;7749.535027898344;7848.803779905548;7919.445638805229;7921.247481489249;7748.535087894745;7968.322692571196;7979.441439099207;8018.599070046358;8100.392470564849;5632.577556683347;6206.565540412128;6416.197975253093;6554.868902622016;6562.146920900253;6862.451003919575;6919.308069193242;6925;6930.653467326512;6942.861142777015;6123.908141377794;6166.969165154145;6273.592216505913;6274.121622972696;6424.839379015375;6118.479929206067;6151.477124444473;6180.505559555315;6194.938050619436;6311.179546659088;6377.649229292433;6414.454771344302;6426.389492998158;6431;6431.260405053479;5988.700564971647;6057;6058.545609079249;6059.697015149137;6061.72722227492;5831.446012628793;5886.705664716833;5895.263092113486;5896.086106653475;5898.616589921641 16:10:44 INFO - PROCESS | 3422 | |4;prototype.html;295.53748383779384;298.2484139967281;298.92078599171225;304.03923601419507;305.39684123855403;5787.50806181474;5803.216565763621;5815.4475324844125;5844.883102337951;5936.109583562467;5762.884742305151;5763.9135412968735;5792.942070579293;5807.361190269067;5809.825705228839;4234.491860976672;4258.744475331475;4350.934735978963;4361.149575832714;4430.911381772355;4331.8700438986725;4343.78281085946;4354.978225108885;4372.453443319585;4376.97811510942;4284.907348626111;4298.247806633843;4304.332828411601;4305.052888364555;4310.56894310568;4190.811413486385;4251.447311849471;4356.150550642626;4357.520672725997;4358;3749.981250093732;3762.6425489578446;3763.8682646107254;3769.886903392875;3772.2078363543687;5570.888582228333;5573.526250268732;5575.330960284751;5580.162975553659;5581.358143813472;5048.1670524363435;5051.535054834094;5054.393472783253;5054.620903432257;5059.696418214919;6137.41694539018;6138;6139.846503837395;6139.969300153471;6141.692915354214;3980.263651224504;4106.979465102685;4163.417121602978;4167.1457351242925;4174.227767862955;6390.744370225186;6407.615543067431;6427.582207156566;6432.58188217769;6437.356264373572;4225.450691410097;4227.365895115758;4228.19664263795;4228.260054490452;4233.809478573472;4275.5296917339065;4279.7218180818145;4279.97860010698;4282.700210985264;4283.87148385549;5027.597792176617;5030.522100400456;5032.119379108642;5033.245013248042;5036.294918711384;6179.258488981351;6196;6197.380261973857;6199.535034872312;6202.100695399142;1447.3631602095045;1482.3403585404392;1482.6071091160852;1482.7998220240345;1483.970320593582;929.6049179098991;939.9342046056711;962.3600305796567;994.5872462927948;998.2962011781676;5885.205497257901;5912.881742365129;5920.407959204132;5921.052631578927;5933.376995415506;6292.748290068437;6297.748090076438;6299.275583307978;6299.653519056405;6300.684965751694;5899.852503687356;5906.468417842415;5907.881842363128;5910.704464776744;5911.024680927685;3347.447671134284;3389.508521264403;3391.7286617070577;3401.2347221875375;3410.471376936579;6582.440492558187;6629.701663425158;6652.767153149616;6803.455723542104;6807.421369183675;6554.508411869129;6574.112494813235;6581.14445122131;6591.34086591337;6596.967015164893;6765.932340676531;6770.119884414995;6770.289119642367;6772.762953296611;6778.93221067783;6411.262704788823;6481.5138864584405;6489.286178520453;6489.610623362613;6491.35086491366;6200.968995154995;6353.110564520878;6361.395667411589;6371.55399122057;6381.298057213796;6741.359570840762;6761.91809310508;6771;6787.66061696923;6809.182898052265;4156.521999970042;4193.958060419357;4202.642775364128;4203.957960420479;4206;6405.167328247298;6410.070539771614;6414.326495718069;6474.1583594134345;6480.740770369132;6183.690815459119;6186.81439556814;6203.875922481616;6204.472619827366;6229.657368844576;6069.484093852072;6238.189035425366;6244.594101383395;6246.531510136667;6246.875062498633;6108.6945652716295;6125.724342404514;6128.417800308964;6130.141780150693;6136.048912418582;6193;6264.404881536247;6265.749370025148;6269.122322874892;6278.968605156945;5857.677827719346;5930.851728706817;6074.483668888017;6105.45050945417;6108.511319094373 16:10:44 INFO - PROCESS | 3422 | |5;yui.html;650.8047585724283;689.8516818883938;704.9577025378475;719.5358993449225;725.3326939215921;13439;13482.5281115161;13503.662408439794;13549.119307245035;13550.322483875816;10824.567017319318;11765.117616178799;11803.70490737732;11805.173637845353;11818;796.2475460689636;807.5881300536739;809.639710328901;813.5078277642001;814.0556953933439;803.5941849366062;807.5437377881478;810.4245985350408;811.4765975945542;814.7800093974623;694.6908625661582;841.2344766262702;845.5096044294326;846.7798372423183;848.9363297752662;684.216572025031;686.4988558352405;832.5587438657521;838.5597561280316;839.9076101628816;618.6906546726636;619.3032838057186;619.5074915442234;619.9442050215503;619.9597026193328;13415;13444.193348399029;13473.663158421117;13487.86512134876;13566.525171618947;12822.551210707581;13059.54291599799;13191.340432978408;13256.93371533136;13347.065705400626;10117.291789574732;10365.74085647865;10437.79124417515;10763.784724305548;10910.018098371105;3682.116292089906;3782.583915769263;3790.2040571480024;3842.884713458601;3863.420486926955;50687.49312506865;51443.228351574384;51493;51621;51627.483725163016;10528.789424211473;10620.893791062144;10640.148788096934;11012.50443730034;11210;19348.032598370024;19352.32266870653;19372.225110995685;19391.321303754583;19431.125599348063;7461.515001524884;7545.622718864144;7609.086909570887;7621.580813055224;7626.694932202649;3972.56301806801;4244.639205667491;4262.445882035316;4284.485861696554;4292.935605965913;3081.4915538935825;3098.550710147016;3107.0678796361003;3127.921801954924;3141.167590588496;542.6093212886715;549.9862503437446;561.3348182403864;564.1565859040678;569.2258528401371;570.9571782116358;576.4350936082578;582.2954225387329;588.3733823477983;588.8145234251197;7766.92233077662;7904.881426778603;8038.959805200937;8152.883960994128;8208.09710931797;430.9159713855768;465.42519987815024;555.5777609017096;659.5086660437909;673.6867356679168;3828.2343531293855;3835.6931445484292;3918.8628398005935;4002.3396139636634;4031.23406552754;52337.21494177591;56885.43114568802;57088.42911570831;57302.14046789301;57326;47235.29147062797;47956;48167.51832481701;48483;48890.755546222754;42921.785391072845;42995.57004429916;43140.13719725588;44009.11981760347;44299.11401771947;8083.232092951161;8143.144969778088;8152.592370381576;8163.959180204061;8499.957500212708;33121.668783311856;33137.171570710925;33394.4990825133;33585.32829343351;34559.82720086384;38424.2315153701;41816.37275440932;42252.78873605612;43069.138617228105;43614;10531.736706582396;10649.201309901944;11064.170187235828;11224.831627525431;11633.360165190657;11991.400429978641;12022;12124.151309408257;12270.4478298475;12682.682932926382;9926.255530835071;10022.949885250528;10075;10121.544530496292;10930.945345273223;11751.941240294085;11926.522939082339;12033.278003319829;12101.636950891487;12257.203281786655;10932.111587044543;11009.724756881142;11099.778004439704;11380.203385762918;11585.942070289595;9910.405375677483;10005.249606279413;10056.69829905104;10220.693379198636;10319.122874555747;8388.775246757908;8422.199891010345;8802.559872006503;8957.820843583222;9081.091890810865 16:10:44 INFO - PROCESS | 3422 | __end_tp_report 16:10:44 INFO - PROCESS | 3422 | __start_cc_report 16:10:44 INFO - PROCESS | 3422 | _x_x_mozilla_cycle_collect,776 16:10:44 INFO - PROCESS | 3422 | __end_cc_report 16:10:44 INFO - PROCESS | 3422 | __startTimestamp1456618244574__endTimestamp 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | ------- Summary: start ------- 16:10:44 INFO - PROCESS | 3422 | Number of tests: 6 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#0] dojo.html Cycles:165 Average:5009.39 Median:5183.66 stddev:534.51 (10.3%) stddev-sans-first:513.84 16:10:44 INFO - PROCESS | 3422 | Values: 3061.5 3069.6 3137.7 3156.7 3164.4 4892.4 4932.2 4934.0 4952.0 4966.5 4978.3 4994.4 5008.8 5055.9 5107.9 4545.8 4581.4 4650.2 4668.2 4691.1 4596.2 4612.4 4678.3 4687.6 4697.3 4494.2 4581.9 4649.5 4663.4 4699.4 4578.4 4582.6 4585.4 4599.4 4642.1 4482.4 4577.0 4577.1 4587.8 4631.1 4844.7 4935.9 4947.2 4951.6 4957.7 5318.0 5321.5 5328.4 5329.8 5336.9 5247.9 5358.9 5368.6 5417.5 5438.8 5082.9 5104.8 5110.0 5148.8 5156.8 5483.6 5499.9 5502.8 5506.8 5510.2 5224.9 5261.4 5268.9 5281.3 5286.0 5394.1 5397.6 5412.0 5429.9 5467.5 5359.6 5368.1 5379.6 5398.5 5450.8 5280.4 5302.1 5361.0 5392.5 5394.8 4126.2 4140.2 4140.7 4172.1 4176.9 3746.8 3759.9 3762.9 3770.9 3797.6 4920.0 4960.5 5057.6 5073.2 5150.7 5342.3 5400.9 5402.4 5403.5 5430.3 5105.1 5110.1 5110.5 5113.9 5156.6 4481.5 4577.0 4625.6 4630.8 4646.3 5607.4 5629.9 5634.0 5636.6 5646.2 5326.7 5365.4 5367.1 5370.3 5415.7 5475.8 5558.7 5622.2 5654.7 5687.7 5322.1 5328.1 5328.4 5330.9 5331.0 5270.5 5370.1 5370.5 5376.6 5427.1 5228.7 5234.6 5259.5 5301.8 5302.5 5234.6 5242.5 5249.9 5250.8 5281.1 5063.5 5138.7 5179.3 5180.4 5183.7 5265.0 5282.8 5285.5 5339.7 5345.9 5144.0 5178.1 5183.6 5185.6 5188.5 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#1] ext.html Cycles:180 Average:45060.92 Median:155721.48 stddev:74877.88 (48.1%) stddev-sans-first:75013.11 16:10:44 INFO - PROCESS | 3422 | Values: 481.3 510.1 524.8 537.4 554.1 28526.7 29021.4 36085.3 36584.0 36586.5 40244.6 40536.6 40558.8 40589.0 40786.4 8410.3 8603.9 8617.5 8621.1 8623.8 7585.2 10244.7 10497.8 10500.3 10512.9 10115.4 10215.1 10222.9 10225.1 10232.8 10024.1 10032.3 10099.3 10108.1 10116.7 10032.4 10172.6 10191.1 10193.5 10491.2 39250.4 39816.2 39816.8 39839.8 39877.0 3076.0 3118.6 3238.8 3267.3 3638.9 28833.0 29421.0 29666.6 29861.4 30450.8 3895.1 3942.8 3954.3 4030.0 4082.8 274887.6 276365.0 277162.0 277874.0 278398.0 60066.7 60203.1 60479.4 60526.7 60529.7 63352.7 64998.4 66133.7 66150.3 66154.7 15314.3 15509.5 15530.1 15565.8 15573.4 10977.2 11403.9 11419.1 11510.8 11762.2 19393.0 19530.6 19538.6 19743.5 19760.2 7598.4 7805.3 7855.5 7880.0 7960.8 817.9 823.7 843.4 881.8 886.4 24335.4 24987.9 24992.5 25224.6 25295.4 898.5 899.3 932.3 938.5 967.9 12822.2 12909.2 13206.5 13217.2 13220.5 302243.0 305928.0 307215.0 307478.0 308367.0 50457.0 51716.5 52180.0 52203.7 52253.2 1175.4 1245.0 1246.3 1338.6 1350.7 17688.9 24362.3 24450.5 24494.8 24866.3 49347.8 49718.8 49991.0 50006.5 50013.2 272033.0 272906.0 275091.0 275749.0 276423.0 27322.0 28496.7 29837.6 30336.1 31611.2 40311.6 40586.4 40778.0 41180.4 41373.4 31523.0 33640.7 33642.8 34009.8 34177.7 33578.2 33886.7 33925.5 34264.7 34505.7 27427.3 27576.0 27686.0 28565.3 28565.9 28405.3 31121.7 31191.2 31298.5 31342.7 21619.9 22266.9 22356.6 22516.8 22587.7 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#2] jquery.html Cycles:180 Average:25855.11 Median:17268.14 stddev:69563.42 (402.8%) stddev-sans-first:69732.17 16:10:44 INFO - PROCESS | 3422 | Values: 330.9 337.3 337.5 338.8 340.8 16203.3 16441.3 17026.5 17428.8 17813.8 15209.6 15469.0 16706.0 17333.1 17348.4 5879.5 5964.3 6014.0 6037.9 6061.3 5888.4 6051.3 6078.0 6095.4 6097.8 6061.2 6066.0 6086.1 6393.6 6666.2 6610.4 6624.1 6636.4 6658.2 6661.6 6278.6 6349.2 6388.3 6393.0 6462.3 21279.9 22114.2 22154.8 22845.8 22951.1 2251.6 2355.9 2480.6 2989.1 3104.4 13859.7 13885.9 14224.0 14461.8 14862.9 1976.2 2068.4 2122.5 2196.2 2265.1 68430.3 74537.0 75627.0 76385.6 76719.6 14924.2 15067.5 15682.6 15947.4 15976.8 24244.3 24521.9 24623.9 24667.4 24687.8 9036.3 9182.6 9239.4 9243.4 9390.2 7862.1 7875.8 7904.2 7993.6 8021.8 7877.6 7878.8 7960.6 7987.5 7997.9 2889.2 2995.7 3007.8 3034.3 3065.5 732.0 765.4 780.5 793.6 828.1 14538.6 15195.2 15278.3 15658.2 15950.8 792.9 798.5 816.6 825.2 828.5 8786.9 8881.4 8934.9 8937.3 9092.3 404766.0 407093.0 408166.0 447587.0 449678.0 60590.7 73782.0 74427.3 74884.6 76310.6 1001.8 1002.3 1018.9 1046.1 1048.7 11585.2 13411.9 13991.6 14038.8 14160.6 33012.5 33485.2 33714.5 33881.3 34195.5 43294.4 44317.3 44612.8 44675.5 44684.6 7317.5 7416.6 7430.7 7574.5 7753.1 11050.9 12083.8 12335.8 12401.6 12903.1 9976.7 10174.5 10338.4 10516.7 10587.2 10420.9 10502.8 10844.5 10883.8 10999.8 7562.8 10114.6 10419.8 10520.6 10540.2 7403.9 7461.7 7470.8 7572.2 7627.3 9430.9 10246.8 10304.0 10467.7 10477.8 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#3] mootools.html Cycles:180 Average:4965.05 Median:6173.74 stddev:2557.06 (41.4%) stddev-sans-first:2549.38 16:10:44 INFO - PROCESS | 3422 | Values: 1298.4 1439.5 1448.4 1573.0 1708.7 6875.4 6901.7 6945.5 6957.3 7011.6 6937.0 7087.9 7094.3 7102.1 7104.0 1886.5 2097.3 2119.4 2140.8 2143.2 2112.5 2114.5 2120.5 2122.1 2122.4 2070.2 2115.1 2130.2 2130.3 2131.3 2087.4 2094.9 2105.2 2107.5 2124.6 2107.9 2113.1 2118.2 2120.4 2130.0 7240.3 7269.2 7284.9 7293.0 7300.0 4281.3 4380.8 4396.7 4398.4 4401.6 2903.4 2917.3 2944.9 2950.4 3066.4 866.8 871.9 893.0 952.9 1774.9 9312.7 9345.0 9361.3 9382.0 9396.2 3622.2 3747.3 3779.3 3794.2 3807.4 7616.7 7657.3 7666.1 7682.8 7683.3 3932.5 4301.4 4302.5 4304.4 4346.1 3654.4 3685.9 3689.9 3878.4 3983.6 3102.1 3103.9 3163.7 3167.2 3183.8 951.2 959.4 962.9 963.1 966.7 1280.5 1286.3 1312.0 1326.7 1327.1 6763.1 6795.8 6918.9 6920.2 6921.9 440.6 440.7 442.7 444.8 458.2 5269.8 5356.2 5368.2 5401.6 5403.3 8161.3 8247.6 8309.6 8520.7 8805.7 8350.2 8471.5 8509.1 8551.9 8568.7 6582.3 6641.5 6790.9 6800.9 6880.4 6737.5 6759.4 6767.3 6803.9 6807.3 7624.9 7749.5 7848.8 7919.4 7921.2 7748.5 7968.3 7979.4 8018.6 8100.4 5632.6 6206.6 6416.2 6554.9 6562.1 6862.5 6919.3 6925.0 6930.7 6942.9 6123.9 6167.0 6273.6 6274.1 6424.8 6118.5 6151.5 6180.5 6194.9 6311.2 6377.6 6414.5 6426.4 6431.0 6431.3 5988.7 6057.0 6058.5 6059.7 6061.7 5831.4 5886.7 5895.3 5896.1 5898.6 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#4] prototype.html Cycles:180 Average:5139.71 Median:5911.95 stddev:1610.34 (27.2%) stddev-sans-first:1573.27 16:10:44 INFO - PROCESS | 3422 | Values: 295.5 298.2 298.9 304.0 305.4 5787.5 5803.2 5815.4 5844.9 5936.1 5762.9 5763.9 5792.9 5807.4 5809.8 4234.5 4258.7 4350.9 4361.1 4430.9 4331.9 4343.8 4355.0 4372.5 4377.0 4284.9 4298.2 4304.3 4305.1 4310.6 4190.8 4251.4 4356.2 4357.5 4358.0 3750.0 3762.6 3763.9 3769.9 3772.2 5570.9 5573.5 5575.3 5580.2 5581.4 5048.2 5051.5 5054.4 5054.6 5059.7 6137.4 6138.0 6139.8 6140.0 6141.7 3980.3 4107.0 4163.4 4167.1 4174.2 6390.7 6407.6 6427.6 6432.6 6437.4 4225.5 4227.4 4228.2 4228.3 4233.8 4275.5 4279.7 4280.0 4282.7 4283.9 5027.6 5030.5 5032.1 5033.2 5036.3 6179.3 6196.0 6197.4 6199.5 6202.1 1447.4 1482.3 1482.6 1482.8 1484.0 929.6 939.9 962.4 994.6 998.3 5885.2 5912.9 5920.4 5921.1 5933.4 6292.7 6297.7 6299.3 6299.7 6300.7 5899.9 5906.5 5907.9 5910.7 5911.0 3347.4 3389.5 3391.7 3401.2 3410.5 6582.4 6629.7 6652.8 6803.5 6807.4 6554.5 6574.1 6581.1 6591.3 6597.0 6765.9 6770.1 6770.3 6772.8 6778.9 6411.3 6481.5 6489.3 6489.6 6491.4 6201.0 6353.1 6361.4 6371.6 6381.3 6741.4 6761.9 6771.0 6787.7 6809.2 4156.5 4194.0 4202.6 4204.0 4206.0 6405.2 6410.1 6414.3 6474.2 6480.7 6183.7 6186.8 6203.9 6204.5 6229.7 6069.5 6238.2 6244.6 6246.5 6246.9 6108.7 6125.7 6128.4 6130.1 6136.0 6193.0 6264.4 6265.7 6269.1 6279.0 5857.7 5930.9 6074.5 6105.5 6108.5 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | [#5] yui.html Cycles:180 Average:13543.28 Median:23607.36 stddev:15530.28 (65.8%) stddev-sans-first:15543.67 16:10:44 INFO - PROCESS | 3422 | Values: 650.8 689.9 705.0 719.5 725.3 13439.0 13482.5 13503.7 13549.1 13550.3 10824.6 11765.1 11803.7 11805.2 11818.0 796.2 807.6 809.6 813.5 814.1 803.6 807.5 810.4 811.5 814.8 694.7 841.2 845.5 846.8 848.9 684.2 686.5 832.6 838.6 839.9 618.7 619.3 619.5 619.9 620.0 13415.0 13444.2 13473.7 13487.9 13566.5 12822.6 13059.5 13191.3 13256.9 13347.1 10117.3 10365.7 10437.8 10763.8 10910.0 3682.1 3782.6 3790.2 3842.9 3863.4 50687.5 51443.2 51493.0 51621.0 51627.5 10528.8 10620.9 10640.1 11012.5 11210.0 19348.0 19352.3 19372.2 19391.3 19431.1 7461.5 7545.6 7609.1 7621.6 7626.7 3972.6 4244.6 4262.4 4284.5 4292.9 3081.5 3098.6 3107.1 3127.9 3141.2 542.6 550.0 561.3 564.2 569.2 571.0 576.4 582.3 588.4 588.8 7766.9 7904.9 8039.0 8152.9 8208.1 430.9 465.4 555.6 659.5 673.7 3828.2 3835.7 3918.9 4002.3 4031.2 52337.2 56885.4 57088.4 57302.1 57326.0 47235.3 47956.0 48167.5 48483.0 48890.8 42921.8 42995.6 43140.1 44009.1 44299.1 8083.2 8143.1 8152.6 8164.0 8500.0 33121.7 33137.2 33394.5 33585.3 34559.8 38424.2 41816.4 42252.8 43069.1 43614.0 10531.7 10649.2 11064.2 11224.8 11633.4 11991.4 12022.0 12124.2 12270.4 12682.7 9926.3 10022.9 10075.0 10121.5 10930.9 11751.9 11926.5 12033.3 12101.6 12257.2 10932.1 11009.7 11099.8 11380.2 11585.9 9910.4 10005.2 10056.7 10220.7 10319.1 8388.8 8422.2 8802.6 8957.8 9081.1 16:10:44 INFO - PROCESS | 3422 | -------- Summary: end -------- 16:10:44 INFO - PROCESS | 3422 | 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:44 INFO - PROCESS | 3422 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:45 INFO - TEST-INFO | 3422: exit 0 16:10:45 INFO - TEST-OK | dromaeo_css | took 1119345ms 16:10:45 INFO - TEST-START | kraken 16:10:45 INFO - operating with platform_type : linux_ 16:10:45 INFO - Initialising browser for kraken test... 16:10:45 INFO - TEST-INFO | started process 3601 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3UM0of/profile http://localhost:47494/getInfo.html) 16:10:49 INFO - PROCESS | 3601 | __metrics Screen width/height:1600/1200 16:10:49 INFO - PROCESS | 3601 | colorDepth:24 16:10:49 INFO - PROCESS | 3601 | Browser inner width/height: 1024/697 16:10:49 INFO - PROCESS | 3601 | __metrics 16:10:50 INFO - PROCESS | 3601 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:10:51 INFO - PROCESS | 3601 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 16:10:51 INFO - TEST-INFO | 3601: exit 0 16:10:51 INFO - Browser initialized. 16:10:51 INFO - Running cycle 1/1 for kraken test... 16:10:51 INFO - TEST-INFO | started process 3684 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3UM0of/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:03 INFO - PROCESS | 3684 | RSS: Main: 150679552 16:11:03 INFO - PROCESS | 3684 | 16:11:06 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47494/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:06 INFO - PROCESS | 3684 | RSS: Main: 192860160 16:11:06 INFO - PROCESS | 3684 | 16:11:09 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47494/tests/kraken/driver.html?testName=audio-dft) 16:11:10 INFO - PROCESS | 3684 | RSS: Main: 182837248 16:11:10 INFO - PROCESS | 3684 | 16:11:13 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47494/tests/kraken/driver.html?testName=audio-fft) 16:11:13 INFO - PROCESS | 3684 | RSS: Main: 169304064 16:11:13 INFO - PROCESS | 3684 | 16:11:15 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47494/tests/kraken/driver.html?testName=audio-oscillator) 16:11:16 INFO - PROCESS | 3684 | RSS: Main: 171065344 16:11:16 INFO - PROCESS | 3684 | 16:11:18 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47494/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:18 INFO - PROCESS | 3684 | RSS: Main: 172740608 16:11:18 INFO - PROCESS | 3684 | 16:11:21 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47494/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:22 INFO - PROCESS | 3684 | RSS: Main: 173289472 16:11:22 INFO - PROCESS | 3684 | 16:11:25 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47494/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:26 INFO - PROCESS | 3684 | RSS: Main: 173453312 16:11:26 INFO - PROCESS | 3684 | 16:11:29 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47494/tests/kraken/driver.html?testName=json-parse-financial) 16:11:29 INFO - PROCESS | 3684 | RSS: Main: 172908544 16:11:29 INFO - PROCESS | 3684 | 16:11:31 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47494/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:31 INFO - PROCESS | 3684 | RSS: Main: 182771712 16:11:31 INFO - PROCESS | 3684 | 16:11:33 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:34 INFO - PROCESS | 3684 | RSS: Main: 182046720 16:11:34 INFO - PROCESS | 3684 | 16:11:38 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:39 INFO - PROCESS | 3684 | RSS: Main: 184451072 16:11:39 INFO - PROCESS | 3684 | 16:11:41 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:42 INFO - PROCESS | 3684 | RSS: Main: 198893568 16:11:42 INFO - PROCESS | 3684 | 16:11:45 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:11:45 INFO - PROCESS | 3684 | RSS: Main: 197419008 16:11:45 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | Cycle 1(1): loaded http://localhost:47494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47494/tests/kraken/driver.html?testName=ai-astar) 16:11:47 INFO - PROCESS | 3684 | RSS: Main: 257634304 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | __start_tp_report 16:11:47 INFO - PROCESS | 3684 | _x_x_mozilla_page_load 16:11:47 INFO - PROCESS | 3684 | _x_x_mozilla_page_load_details 16:11:47 INFO - PROCESS | 3684 | |i|pagename|runs| 16:11:47 INFO - PROCESS | 3684 | |0;ai-astar;113;115;108;113;114;114;100;111;110;111 16:11:47 INFO - PROCESS | 3684 | |1;audio-beat-detection;152;127;151;169;127;154;148;137;177;130 16:11:47 INFO - PROCESS | 3684 | |2;audio-dft;161;161;159;161;160;160;161;162;160;161 16:11:47 INFO - PROCESS | 3684 | |3;audio-fft;73;72;93;93;73;74;73;105;94;74 16:11:47 INFO - PROCESS | 3684 | |4;audio-oscillator;96;95;135;94;96;96;146;95;95;138 16:11:47 INFO - PROCESS | 3684 | |5;imaging-gaussian-blur;118;115;115;115;115;117;114;115;114;115 16:11:47 INFO - PROCESS | 3684 | |6;imaging-darkroom;145;145;145;145;146;145;145;145;145;146 16:11:47 INFO - PROCESS | 3684 | |7;imaging-desaturate;96;96;96;95;96;96;95;96;97;95 16:11:47 INFO - PROCESS | 3684 | |8;json-parse-financial;59;58;58;57;59;59;58;66;58;56 16:11:47 INFO - PROCESS | 3684 | |9;json-stringify-tinderbox;68;68;68;70;68;67;68;68;68;68 16:11:47 INFO - PROCESS | 3684 | |10;stanford-crypto-aes;82;82;83;82;86;83;84;81;82;79 16:11:47 INFO - PROCESS | 3684 | |11;stanford-crypto-ccm;121;117;114;122;133;116;143;116;115;117 16:11:47 INFO - PROCESS | 3684 | |12;stanford-crypto-pbkdf2;154;141;142;141;150;138;152;149;151;149 16:11:47 INFO - PROCESS | 3684 | |13;stanford-crypto-sha256-iterative;68;71;71;68;70;69;68;70;68;77 16:11:47 INFO - PROCESS | 3684 | __end_tp_report 16:11:47 INFO - PROCESS | 3684 | __start_cc_report 16:11:47 INFO - PROCESS | 3684 | _x_x_mozilla_cycle_collect,1055 16:11:47 INFO - PROCESS | 3684 | __end_cc_report 16:11:47 INFO - PROCESS | 3684 | __startTimestamp1456618307643__endTimestamp 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | ------- Summary: start ------- 16:11:47 INFO - PROCESS | 3684 | Number of tests: 14 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#0] ai-astar Cycles:10 Average:110.90 Median:113.00 stddev:4.38 (3.9%) stddev-sans-first:4.58 16:11:47 INFO - PROCESS | 3684 | Values: 113.0 115.0 108.0 113.0 114.0 114.0 100.0 111.0 110.0 111.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:151.50 stddev:17.20 (11.4%) stddev-sans-first:18.16 16:11:47 INFO - PROCESS | 3684 | Values: 152.0 127.0 151.0 169.0 127.0 154.0 148.0 137.0 177.0 130.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 16:11:47 INFO - PROCESS | 3684 | Values: 161.0 161.0 159.0 161.0 160.0 160.0 161.0 162.0 160.0 161.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#3] audio-fft Cycles:10 Average:82.40 Median:74.00 stddev:12.40 (16.8%) stddev-sans-first:12.68 16:11:47 INFO - PROCESS | 3684 | Values: 73.0 72.0 93.0 93.0 73.0 74.0 73.0 105.0 94.0 74.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#4] audio-oscillator Cycles:10 Average:108.60 Median:95.00 stddev:21.61 (22.8%) stddev-sans-first:22.44 16:11:47 INFO - PROCESS | 3684 | Values: 96.0 95.0 135.0 94.0 96.0 96.0 146.0 95.0 95.0 138.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:115.00 stddev:1.25 (1.1%) stddev-sans-first:0.87 16:11:47 INFO - PROCESS | 3684 | Values: 118.0 115.0 115.0 115.0 115.0 117.0 114.0 115.0 114.0 115.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:11:47 INFO - PROCESS | 3684 | Values: 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:11:47 INFO - PROCESS | 3684 | Values: 96.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 97.0 95.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.50 stddev:2.70 (4.6%) stddev-sans-first:2.86 16:11:47 INFO - PROCESS | 3684 | Values: 59.0 58.0 58.0 57.0 59.0 59.0 58.0 66.0 58.0 56.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 16:11:47 INFO - PROCESS | 3684 | Values: 68.0 68.0 68.0 70.0 68.0 67.0 68.0 68.0 68.0 68.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:1.84 (2.2%) stddev-sans-first:1.94 16:11:47 INFO - PROCESS | 3684 | Values: 82.0 82.0 83.0 82.0 86.0 83.0 84.0 81.0 82.0 79.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#11] stanford-crypto-ccm Cycles:10 Average:121.40 Median:119.00 stddev:9.40 (7.9%) stddev-sans-first:9.96 16:11:47 INFO - PROCESS | 3684 | Values: 121.0 117.0 114.0 122.0 133.0 116.0 143.0 116.0 115.0 117.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.70 Median:149.50 stddev:5.62 (3.8%) stddev-sans-first:5.30 16:11:47 INFO - PROCESS | 3684 | Values: 154.0 141.0 142.0 141.0 150.0 138.0 152.0 149.0 151.0 149.0 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:2.75 (3.9%) stddev-sans-first:2.82 16:11:47 INFO - PROCESS | 3684 | Values: 68.0 71.0 71.0 68.0 70.0 69.0 68.0 70.0 68.0 77.0 16:11:47 INFO - PROCESS | 3684 | -------- Summary: end -------- 16:11:47 INFO - PROCESS | 3684 | 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:47 INFO - PROCESS | 3684 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 16:11:48 INFO - TEST-INFO | 3684: exit 0 16:11:48 INFO - TEST-OK | kraken | took 63441ms 16:11:48 INFO - SUITE-END | took 1182s 16:11:48 INFO - Completed test suite (00:19:42) 16:11:48 INFO - Working with test: dromaeo_css 16:11:48 INFO - Generating results file: dromaeo_css 16:11:48 INFO - Working with test: kraken 16:11:48 INFO - Generating results file: kraken 16:11:48 INFO - javascript benchmark 16:11:48 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:11:48 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 16:11:48 INFO - process_Request line: dromaeo_css 6726.91 graph.html#tests=[[72,63,35]] 16:11:48 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:11:48 INFO - process_Request line: kraken 1513.40 graph.html#tests=[[232,63,35]] 16:11:48 INFO - RETURN: dromaeo_css: 6726.91 16:11:48 INFO - RETURN: kraken: 1513.40 16:11:48 INFO - javascript benchmark 16:11:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3061.5101583746577, 3069.570260163577, 3137.686231376861, 3156.7316778073864, 3164.367126574685, 4892.3884514435695, 4932.210846264594, 4934.037862616791, 4951.975240123803, 4966.478519755423, 4978.3279257300255, 4994.400671919375, 5008.7996480140755, 5055.924161137576, 5107.872303192422, 4545.795439205243, 4581.427321584802, 4650.162970665279, 4668.159731248374, 4691.0617876424685, 4596.21864283071, 4612.377329060584, 4678.321643361711, 4687.648426368019, 4697.342372067913, 4494.235979883411, 4581.885452863672, 4649.511801260886, 4663.417072865892, 4699.389079419653, 4578.427696537933, 4582.633389328879, 4585.403897493338, 4599.402077729907, 4642.117997580449, 4482.372467854503, 4577.038821847416, 4577.06170235101, 4587.770611469413, 4631.143238500888, 4844.709317440965, 4935.925961110586, 4947.158982972904, 4951.603871690256, 4957.677750946213, 5318.0, 5321.467853214686, 5328.3605967284175, 5329.840104796862, 5336.946630533684, 5247.921281180786, 5358.866028349284, 5368.570514358881, 5417.539509141728, 5438.782448702047, 5082.949170508285, 5104.795808167632, 5109.974450127763, 5148.768305426265, 5156.793728250866, 5483.643563168461, 5499.917501237484, 5502.752376143083, 5506.779728810883, 5510.201020851954, 5224.947750522446, 5261.394939581997, 5268.868278292996, 5281.313429254249, 5285.9735701322015, 5394.08300588893, 5397.568194544427, 5412.025835349675, 5429.918551221735, 5467.507924286833, 5359.5712343012465, 5368.087425137736, 5379.623426360117, 5398.514133727984, 5450.809221677222, 5280.419153893069, 5302.09864323066, 5360.973195133999, 5392.460753924576, 5394.757235924393, 4126.216018956388, 4140.192662430857, 4140.668746500333, 4172.102997855475, 4176.916461670749, 3746.8126593670754, 3759.9248015039548, 3762.9059273517832, 3770.905727356838, 3797.582265950743, 4920.015996800583, 4960.528749768766, 5057.570106540985, 5073.239014147907, 5150.665206761549, 5342.332208473941, 5400.91898621521, 5402.432744561765, 5403.459654034565, 5430.266913966643, 5105.106606343875, 5110.054639891632, 5110.514501122388, 5113.8721531961255, 5156.561692256125, 4481.462224533077, 4577.0, 4625.583697467244, 4630.814767409333, 4646.303054541778, 5607.355154157242, 5629.915551266734, 5634.0, 5636.605437619327, 5646.153077038477, 5326.707031113326, 5365.356157261153, 5367.060764366299, 5370.275012873369, 5415.702136382379, 5475.780968761205, 5558.694271815089, 5622.2128901954575, 5654.6889921054735, 5687.658740475585, 5322.148456247137, 5328.147496400557, 5328.440513746001, 5330.8933821324135, 5331.0, 5270.49930256625, 5370.087085195604, 5370.516653501204, 5376.569874410116, 5427.050266203478, 5228.660137091077, 5234.633575649668, 5259.47405259471, 5301.840944771663, 5302.522772950453, 5234.555062819703, 5242.5281724644965, 5249.921251181159, 5250.763715632882, 5281.102212623787, 5063.544281014726, 5138.691678499302, 5179.300794392709, 5180.352455943007, 5183.663061900965, 5264.9736751315995, 5282.762275697526, 5285.497877701612, 5339.652922560022, 5345.919811202757, 5144.0, 5178.145605974972, 5183.585313175013, 5185.585153187812, 5188.455212202664], "unit": "score", "name": "dojo.html", "value": 4976.491351903435}, {"lowerIsBetter": false, "replicates": [481.2900971067676, 510.056395668014, 524.8163142899986, 537.3873783886368, 554.0691638048079, 28526.71473285269, 29021.41957160858, 36085.27829443413, 36584.0, 36586.4512032319, 40244.597554024454, 40536.594634053654, 40558.79720601393, 40589.0, 40786.38820417696, 8410.327173826094, 8603.870941935877, 8617.48295102296, 8621.13788621136, 8623.784405389884, 7585.1656317805, 10244.692659220198, 10497.842532361983, 10500.2649814513, 10512.894871051307, 10115.443650599214, 10215.131713804336, 10222.948885255564, 10225.130863876579, 10232.795344093114, 10024.097831195191, 10032.347897386646, 10099.29304948654, 10108.140808031327, 10116.747081323027, 10032.398056116657, 10172.643957461454, 10191.063697897376, 10193.541290641864, 10491.16070714341, 39250.41124383137, 39816.20367592661, 39816.800915995234, 39839.8008009961, 39877.0, 3075.9538606920914, 3118.5945827042337, 3238.8218647974354, 3267.313864088544, 3638.909027274339, 28833.0, 29421.0, 29666.55500167499, 29861.402771944657, 30450.84774576135, 3895.104126050996, 3942.763434193929, 3954.307996100671, 4030.0, 4082.795860206978, 274887.6255618729, 276365.0, 277162.0, 277874.0, 278398.0, 60066.69966650226, 60203.09695354573, 60479.395206047375, 60526.69736651376, 60529.69735151296, 63352.683236584446, 64998.350016500175, 66133.66933165303, 66150.33849661538, 66154.66922665355, 15314.310856011283, 15509.457168999032, 15530.145841978574, 15565.766513502307, 15573.377064917277, 10977.176711746652, 11403.942980285046, 11419.086473082134, 11510.769784604261, 11762.176647634755, 19392.981880871746, 19530.609387812165, 19538.609227815363, 19743.50641233952, 19760.20959161646, 7598.430117741192, 7805.33654639362, 7855.5286682799215, 7879.960600196962, 7960.840783184304, 817.8691409374471, 823.7364043506141, 843.4306842881031, 881.7927786970057, 886.3795343259744, 24335.391615209406, 24987.875060624945, 24992.500149996897, 25224.621630675556, 25295.36761580975, 898.5327629632554, 899.2805755395657, 932.342698397628, 938.5213541093955, 967.9032096790264, 12822.166559173624, 12909.155390386626, 13206.537771177962, 13217.206967581977, 13220.471181152838, 302243.0, 305928.0, 307215.0, 307478.0, 308367.0, 50457.0, 51716.48283517192, 52180.0, 52203.73898130485, 52253.216201757, 1175.4122938530734, 1244.9688757781128, 1246.2584762066738, 1338.625184948216, 1350.7298540291783, 17688.91155544214, 24362.26913192607, 24450.51098978046, 24494.755052449247, 24866.274238571474, 49347.75326123346, 49718.751406242736, 49991.0, 50006.49993500018, 50013.24980125374, 272033.0, 272906.0, 275091.0, 275749.0, 276423.0, 27322.0, 28496.715032849406, 29837.55243671303, 30336.089917302517, 31611.20971975719, 40311.596884031955, 40586.391204131374, 40778.0, 41180.38229426621, 41373.37939930843, 31523.0, 33640.663593364734, 33642.831785840914, 34009.829950850086, 34177.65822341844, 33578.160545986546, 33886.66113338934, 33925.49111763375, 34264.657353427145, 34505.654943450245, 27427.31431714223, 27576.0, 27686.0, 28565.28586785347, 28565.857170714015, 28405.289867752646, 31121.68878311188, 31191.220219493785, 31298.53052204264, 31342.68657313398, 21619.891900540395, 22266.88866555657, 22356.552868942206, 22516.774832252122, 22587.661185082565], "unit": "score", "name": "ext.html", "value": 17885.34564604042}, {"lowerIsBetter": false, "replicates": [330.9222332751804, 337.26476281705874, 337.4684871327659, 338.8018009464463, 340.8006316304961, 16203.270852811622, 16441.342346306148, 17026.489205323844, 17428.82571174288, 17813.82186178138, 15209.619759506018, 15469.0, 16706.0, 17333.133343332847, 17348.39280625175, 5879.52963762899, 5964.2842858857, 6014.037753959367, 6037.909431358533, 6061.302950160722, 5888.352281249059, 6051.27384713835, 6077.969610151965, 6095.420935011167, 6097.786577469789, 6061.212042434478, 6065.969670151665, 6086.056661217496, 6393.648349340784, 6666.166729158856, 6610.40506354428, 6624.072629831828, 6636.369544893228, 6658.234303055138, 6661.566998145129, 6278.560500764948, 6349.174607301067, 6388.265349484822, 6392.968035159794, 6462.289148193695, 21279.893600532054, 22114.22600208985, 22154.778452215432, 22845.771542284532, 22951.08195672171, 2251.6059689554263, 2355.9293221203393, 2480.5907025340975, 2989.1331513860955, 3104.4256812489534, 13859.653508662263, 13885.86114138866, 14224.0, 14461.78307325391, 14862.851371486255, 1976.160131943918, 2068.4208421641965, 2122.490602255463, 2196.1544805250096, 2265.0826415301663, 68430.3156968429, 74537.0, 75627.0, 76385.61807190928, 76719.61640191819, 14924.179170145746, 15067.47263845782, 15682.607934801721, 15947.44183953556, 15976.76034859478, 24244.272671819872, 24521.87739061293, 24623.87688061584, 24667.383315417257, 24687.753122468905, 9036.32227582934, 9182.586783594754, 9239.353245272901, 9243.352965292364, 9390.201832844285, 7862.135165131831, 7875.842483150305, 7904.2095790421645, 7993.600319984093, 8021.839563208703, 7877.56673382958, 7878.803029924297, 7960.56216908064, 7987.520748755093, 7997.920020799834, 2889.1765846734, 2995.7453616442413, 3007.7593792496987, 3034.347615262729, 3065.4788685923445, 731.9707211711471, 765.4412279036335, 780.4614815777096, 793.6388943030908, 828.0725587342215, 14538.636534086732, 15195.240237988057, 15278.256827077437, 15658.217089145497, 15950.840491595167, 792.8652129138061, 798.5089170160426, 816.5713000674574, 825.2325337436242, 828.5070383122032, 8786.86819697705, 8881.42270752412, 8934.86597701035, 8937.329700272505, 9092.318076144316, 404766.0, 407093.0, 408166.0, 447587.0, 449678.0, 60590.697046514484, 73782.0, 74427.25572744312, 74884.62557687177, 76310.6184469074, 1001.7545701303227, 1002.3033991375924, 1018.9490525473697, 1046.1473898772629, 1048.7220886465093, 11585.246958947642, 13411.865881341062, 13991.58025259244, 14038.78941815894, 14160.645983850487, 33012.50481242831, 33485.16287092842, 33714.494282586274, 33881.32237355288, 34195.48706769351, 43294.35058474063, 44317.33523997078, 44612.776936115115, 44675.53244675636, 44684.55315446934, 7317.451191160791, 7416.592087435243, 7430.665620047002, 7574.5076570022775, 7753.108392534705, 11050.944745276543, 12083.818742719042, 12335.75328493443, 12401.62795116148, 12903.096783225084, 9976.650817221362, 10174.491275436347, 10338.431386273527, 10516.68449946503, 10587.153027757608, 10420.895791041992, 10502.789944201226, 10844.45777711127, 10883.836742448711, 10999.835002475129, 7562.7521458960855, 10114.645987390406, 10419.791604168026, 10520.579176832842, 10540.156787456832, 7403.925960740324, 7461.664225109775, 7470.775876723707, 7572.242775722604, 7627.275408836152, 9430.858537121812, 10246.77977614511, 10304.0, 10467.738306542398, 10477.842832357368], "unit": "score", "name": "jquery.html", "value": 9093.501964089442}, {"lowerIsBetter": false, "replicates": [1298.396245745728, 1439.4817865568398, 1448.3989144505028, 1572.9606759831004, 1708.666809972056, 6875.44996400288, 6901.654917254136, 6945.479089068327, 6957.269486703899, 7011.649417529122, 6937.0, 7087.929120708792, 7094.326039026284, 7102.147742270922, 7104.0, 1886.500077479469, 2097.276439628326, 2119.4277545062823, 2140.7538133114736, 2143.2070134050423, 2112.493001679601, 2114.4925217947734, 2120.54408302215, 2122.0981083039724, 2122.4269447249235, 2070.213318938801, 2115.132795553823, 2130.2437634639714, 2130.28635407138, 2131.296672098204, 2087.4155236533643, 2094.9057292421953, 2105.1789801977243, 2107.515271487566, 2124.649432843579, 2107.947301317464, 2113.0596884386455, 2118.1633254864396, 2120.3532922458644, 2130.0095455613214, 7240.312170343862, 7269.236730143364, 7284.9271507284775, 7292.963535182344, 7299.963500182518, 4281.293586558214, 4380.759058251795, 4396.692231543793, 4398.3842262083335, 4401.625861801751, 2903.404802015582, 2917.343597690524, 2944.926376840575, 2950.350922796981, 3066.4173806976605, 866.7876370289256, 871.8605023196258, 892.9998401789971, 952.8856537215579, 1774.8935063896208, 9312.674056408061, 9345.0, 9361.251099911991, 9382.0, 9396.201322887562, 3622.166901612644, 3747.2880152770886, 3779.263043706451, 3794.2221844521805, 3807.390817469247, 7616.7334143304715, 7657.349125324298, 7666.080070391588, 7682.769516914502, 7683.346915512132, 3932.528096628423, 4301.41930839339, 4302.548232435612, 4304.440422745086, 4346.130773845243, 3654.3604869147794, 3685.9078523037138, 3689.8524059037873, 3878.4182372644327, 3983.5817239190046, 3102.0848849589424, 3103.937921241608, 3163.6994485524297, 3167.17653410115, 3183.793053451518, 951.1962391778935, 959.3572306554626, 962.9422234665942, 963.0754475703263, 966.7051549277397, 1280.5454063807401, 1286.298967062953, 1311.9803202951964, 1326.7346530693899, 1327.0909427042313, 6763.12079429671, 6795.796126116125, 6918.861622767517, 6920.169579650475, 6921.8961715574305, 440.594652919315, 440.72674941535814, 442.6812694859696, 444.8198479615761, 458.2210242587614, 5269.815556455505, 5356.223347614652, 5368.194770784336, 5401.621886467988, 5403.270558474555, 8161.347092232607, 8247.639139541909, 8309.626066827008, 8520.659173633108, 8805.735827925173, 8350.24847763692, 8471.49171049739, 8509.106543812934, 8551.871721924175, 8568.742937711879, 6582.341765823379, 6641.535092543476, 6790.94740315252, 6800.897986530206, 6880.449564034965, 6737.461003119836, 6759.391654751096, 6767.289434609295, 6803.863922721419, 6807.251202367835, 7624.885626715492, 7749.535027898344, 7848.803779905548, 7919.445638805229, 7921.247481489249, 7748.535087894745, 7968.322692571196, 7979.441439099207, 8018.599070046358, 8100.392470564849, 5632.577556683347, 6206.565540412128, 6416.197975253093, 6554.868902622016, 6562.146920900253, 6862.451003919575, 6919.308069193242, 6925.0, 6930.653467326512, 6942.861142777015, 6123.908141377794, 6166.969165154145, 6273.592216505913, 6274.121622972696, 6424.839379015375, 6118.479929206067, 6151.477124444473, 6180.505559555315, 6194.938050619436, 6311.179546659088, 6377.649229292433, 6414.454771344302, 6426.389492998158, 6431.0, 6431.260405053479, 5988.700564971647, 6057.0, 6058.545609079249, 6059.697015149137, 6061.72722227492, 5831.446012628793, 5886.705664716833, 5895.263092113486, 5896.086106653475, 5898.616589921641], "unit": "score", "name": "mootools.html", "value": 4038.219037975385}, {"lowerIsBetter": false, "replicates": [295.53748383779384, 298.2484139967281, 298.92078599171225, 304.03923601419507, 305.39684123855403, 5787.50806181474, 5803.216565763621, 5815.4475324844125, 5844.883102337951, 5936.109583562467, 5762.884742305151, 5763.9135412968735, 5792.942070579293, 5807.361190269067, 5809.825705228839, 4234.491860976672, 4258.744475331475, 4350.934735978963, 4361.149575832714, 4430.911381772355, 4331.8700438986725, 4343.78281085946, 4354.978225108885, 4372.453443319585, 4376.97811510942, 4284.907348626111, 4298.247806633843, 4304.332828411601, 4305.052888364555, 4310.56894310568, 4190.811413486385, 4251.447311849471, 4356.150550642626, 4357.520672725997, 4358.0, 3749.981250093732, 3762.6425489578446, 3763.8682646107254, 3769.886903392875, 3772.2078363543687, 5570.888582228333, 5573.526250268732, 5575.330960284751, 5580.162975553659, 5581.358143813472, 5048.1670524363435, 5051.535054834094, 5054.393472783253, 5054.620903432257, 5059.696418214919, 6137.41694539018, 6138.0, 6139.846503837395, 6139.969300153471, 6141.692915354214, 3980.263651224504, 4106.979465102685, 4163.417121602978, 4167.1457351242925, 4174.227767862955, 6390.744370225186, 6407.615543067431, 6427.582207156566, 6432.58188217769, 6437.356264373572, 4225.450691410097, 4227.365895115758, 4228.19664263795, 4228.260054490452, 4233.809478573472, 4275.5296917339065, 4279.7218180818145, 4279.97860010698, 4282.700210985264, 4283.87148385549, 5027.597792176617, 5030.522100400456, 5032.119379108642, 5033.245013248042, 5036.294918711384, 6179.258488981351, 6196.0, 6197.380261973857, 6199.535034872312, 6202.100695399142, 1447.3631602095045, 1482.3403585404392, 1482.6071091160852, 1482.7998220240345, 1483.970320593582, 929.6049179098991, 939.9342046056711, 962.3600305796567, 994.5872462927948, 998.2962011781676, 5885.205497257901, 5912.881742365129, 5920.407959204132, 5921.052631578927, 5933.376995415506, 6292.748290068437, 6297.748090076438, 6299.275583307978, 6299.653519056405, 6300.684965751694, 5899.852503687356, 5906.468417842415, 5907.881842363128, 5910.704464776744, 5911.024680927685, 3347.447671134284, 3389.508521264403, 3391.7286617070577, 3401.2347221875375, 3410.471376936579, 6582.440492558187, 6629.701663425158, 6652.767153149616, 6803.455723542104, 6807.421369183675, 6554.508411869129, 6574.112494813235, 6581.14445122131, 6591.34086591337, 6596.967015164893, 6765.932340676531, 6770.119884414995, 6770.289119642367, 6772.762953296611, 6778.93221067783, 6411.262704788823, 6481.5138864584405, 6489.286178520453, 6489.610623362613, 6491.35086491366, 6200.968995154995, 6353.110564520878, 6361.395667411589, 6371.55399122057, 6381.298057213796, 6741.359570840762, 6761.91809310508, 6771.0, 6787.66061696923, 6809.182898052265, 4156.521999970042, 4193.958060419357, 4202.642775364128, 4203.957960420479, 4206.0, 6405.167328247298, 6410.070539771614, 6414.326495718069, 6474.1583594134345, 6480.740770369132, 6183.690815459119, 6186.81439556814, 6203.875922481616, 6204.472619827366, 6229.657368844576, 6069.484093852072, 6238.189035425366, 6244.594101383395, 6246.531510136667, 6246.875062498633, 6108.6945652716295, 6125.724342404514, 6128.417800308964, 6130.141780150693, 6136.048912418582, 6193.0, 6264.404881536247, 6265.749370025148, 6269.122322874892, 6278.968605156945, 5857.677827719346, 5930.851728706817, 6074.483668888017, 6105.45050945417, 6108.511319094373], "unit": "score", "name": "prototype.html", "value": 4600.628042525717}, {"lowerIsBetter": false, "replicates": [650.8047585724283, 689.8516818883938, 704.9577025378475, 719.5358993449225, 725.3326939215921, 13439.0, 13482.5281115161, 13503.662408439794, 13549.119307245035, 13550.322483875816, 10824.567017319318, 11765.117616178799, 11803.70490737732, 11805.173637845353, 11818.0, 796.2475460689636, 807.5881300536739, 809.639710328901, 813.5078277642001, 814.0556953933439, 803.5941849366062, 807.5437377881478, 810.4245985350408, 811.4765975945542, 814.7800093974623, 694.6908625661582, 841.2344766262702, 845.5096044294326, 846.7798372423183, 848.9363297752662, 684.216572025031, 686.4988558352405, 832.5587438657521, 838.5597561280316, 839.9076101628816, 618.6906546726636, 619.3032838057186, 619.5074915442234, 619.9442050215503, 619.9597026193328, 13415.0, 13444.193348399029, 13473.663158421117, 13487.86512134876, 13566.525171618947, 12822.551210707581, 13059.54291599799, 13191.340432978408, 13256.93371533136, 13347.065705400626, 10117.291789574732, 10365.74085647865, 10437.79124417515, 10763.784724305548, 10910.018098371105, 3682.116292089906, 3782.583915769263, 3790.2040571480024, 3842.884713458601, 3863.420486926955, 50687.49312506865, 51443.228351574384, 51493.0, 51621.0, 51627.483725163016, 10528.789424211473, 10620.893791062144, 10640.148788096934, 11012.50443730034, 11210.0, 19348.032598370024, 19352.32266870653, 19372.225110995685, 19391.321303754583, 19431.125599348063, 7461.515001524884, 7545.622718864144, 7609.086909570887, 7621.580813055224, 7626.694932202649, 3972.56301806801, 4244.639205667491, 4262.445882035316, 4284.485861696554, 4292.935605965913, 3081.4915538935825, 3098.550710147016, 3107.0678796361003, 3127.921801954924, 3141.167590588496, 542.6093212886715, 549.9862503437446, 561.3348182403864, 564.1565859040678, 569.2258528401371, 570.9571782116358, 576.4350936082578, 582.2954225387329, 588.3733823477983, 588.8145234251197, 7766.92233077662, 7904.881426778603, 8038.959805200937, 8152.883960994128, 8208.09710931797, 430.9159713855768, 465.42519987815024, 555.5777609017096, 659.5086660437909, 673.6867356679168, 3828.2343531293855, 3835.6931445484292, 3918.8628398005935, 4002.3396139636634, 4031.23406552754, 52337.21494177591, 56885.43114568802, 57088.42911570831, 57302.14046789301, 57326.0, 47235.29147062797, 47956.0, 48167.51832481701, 48483.0, 48890.755546222754, 42921.785391072845, 42995.57004429916, 43140.13719725588, 44009.11981760347, 44299.11401771947, 8083.232092951161, 8143.144969778088, 8152.592370381576, 8163.959180204061, 8499.957500212708, 33121.668783311856, 33137.171570710925, 33394.4990825133, 33585.32829343351, 34559.82720086384, 38424.2315153701, 41816.37275440932, 42252.78873605612, 43069.138617228105, 43614.0, 10531.736706582396, 10649.201309901944, 11064.170187235828, 11224.831627525431, 11633.360165190657, 11991.400429978641, 12022.0, 12124.151309408257, 12270.4478298475, 12682.682932926382, 9926.255530835071, 10022.949885250528, 10075.0, 10121.544530496292, 10930.945345273223, 11751.941240294085, 11926.522939082339, 12033.278003319829, 12101.636950891487, 12257.203281786655, 10932.111587044543, 11009.724756881142, 11099.778004439704, 11380.203385762918, 11585.942070289595, 9910.405375677483, 10005.249606279413, 10056.69829905104, 10220.693379198636, 10319.122874555747, 8388.775246757908, 8422.199891010345, 8802.559872006503, 8957.820843583222, 9081.091890810865], "unit": "score", "name": "yui.html", "value": 6161.56162022809}], "name": "dromaeo_css", "value": 6726.910530427117}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 115.0, 108.0, 113.0, 114.0, 114.0, 100.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.9}, {"lowerIsBetter": true, "replicates": [152.0, 127.0, 151.0, 169.0, 127.0, 154.0, 148.0, 137.0, 177.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 147.2}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 159.0, 161.0, 160.0, 160.0, 161.0, 162.0, 160.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 93.0, 93.0, 73.0, 74.0, 73.0, 105.0, 94.0, 74.0], "unit": "score", "name": "audio-fft", "value": 82.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 135.0, 94.0, 96.0, 96.0, 146.0, 95.0, 95.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 108.6}, {"lowerIsBetter": true, "replicates": [118.0, 115.0, 115.0, 115.0, 115.0, 117.0, 114.0, 115.0, 114.0, 115.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 115.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 97.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [59.0, 58.0, 58.0, 57.0, 59.0, 59.0, 58.0, 66.0, 58.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 58.8}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 70.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 83.0, 82.0, 86.0, 83.0, 84.0, 81.0, 82.0, 79.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.4}, {"lowerIsBetter": true, "replicates": [121.0, 117.0, 114.0, 122.0, 133.0, 116.0, 143.0, 116.0, 115.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.4}, {"lowerIsBetter": true, "replicates": [154.0, 141.0, 142.0, 141.0, 150.0, 138.0, 152.0, 149.0, 151.0, 149.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.7}, {"lowerIsBetter": true, "replicates": [68.0, 71.0, 71.0, 68.0, 70.0, 69.0, 68.0, 70.0, 68.0, 77.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.0}], "name": "kraken", "value": 1513.4}]} 16:11:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1513.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6726.91"}}} 16:11:48 INFO - Return code: 0 16:11:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:11:48 INFO - # TBPL SUCCESS # 16:11:48 INFO - Running post-action listener: _resource_record_post_action 16:11:48 INFO - Running post-run listener: _resource_record_post_run 16:11:50 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 10432512; Write bytes: 422858752; Read time: 3016; Write time: 765168 16:11:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1187840; Read time: 0; Write time: 176 16:11:50 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 8671232; Write bytes: 421670912; Read time: 2960; Write time: 764992 16:11:50 INFO - Running post-run listener: _upload_blobber_files 16:11:50 INFO - Blob upload gear active. 16:11:50 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 16:11:50 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 16:11:50 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', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 16:11:50 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 Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 16:11:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:11:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:11:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:51 INFO - (blobuploader) - INFO - Done attempting. 16:11:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 16:11:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:11:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:51 INFO - (blobuploader) - INFO - Done attempting. 16:11:51 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:51 INFO - Return code: 0 16:11:51 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 16:11:51 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2119730ed58d1042feb2436c072a50bf8390ea071b9ac27996aa1f17e20625a0cac4babac0251c5ee1d02edd7c0b02a2d7380ffee509525b53c6b946e57d6fa5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a7130a3ff739502f76400547c255029148d433cb21243f7f6770ae122288feb994ddfbb3729c2b9aac4f7132a80c81151822ad9e71dab04dd356ec3cb774852"} 16:11:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 16:11:51 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 16:11:51 INFO - Contents: 16:11:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2119730ed58d1042feb2436c072a50bf8390ea071b9ac27996aa1f17e20625a0cac4babac0251c5ee1d02edd7c0b02a2d7380ffee509525b53c6b946e57d6fa5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a7130a3ff739502f76400547c255029148d433cb21243f7f6770ae122288feb994ddfbb3729c2b9aac4f7132a80c81151822ad9e71dab04dd356ec3cb774852"} 16:11:51 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:51 INFO - Copying logs to upload dir... 16:11:51 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 16:11:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.630663 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-02-27 16:11:51.943883) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 16:11:51.947296) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2119730ed58d1042feb2436c072a50bf8390ea071b9ac27996aa1f17e20625a0cac4babac0251c5ee1d02edd7c0b02a2d7380ffee509525b53c6b946e57d6fa5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a7130a3ff739502f76400547c255029148d433cb21243f7f6770ae122288feb994ddfbb3729c2b9aac4f7132a80c81151822ad9e71dab04dd356ec3cb774852"} build_url:https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011349 build_url: 'https://queue.taskcluster.net/v1/task/ACOLx2znRQ6kvA5tc8jy6Q/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/Mozilla-Inbound/sha512/2119730ed58d1042feb2436c072a50bf8390ea071b9ac27996aa1f17e20625a0cac4babac0251c5ee1d02edd7c0b02a2d7380ffee509525b53c6b946e57d6fa5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a7130a3ff739502f76400547c255029148d433cb21243f7f6770ae122288feb994ddfbb3729c2b9aac4f7132a80c81151822ad9e71dab04dd356ec3cb774852"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 16:11:51.970786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 16:11:51.971083) ========= 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=8783db0840cc980e80bb0bd400000236-1456607514.531823-742963620 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 16:11:52.054541) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 16:11:52.054831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 16:11:52.055112) ========= ========= Total master_lag: 0.30 =========