builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1450374819.2 results: success (0) buildid: 20151217072859 builduid: d0168571701b424e94c8027f9dd4f50b revision: 0711218a018d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.197072) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.197537) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.197853) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006182 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.221451) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.221942) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.237389) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.237847) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004683 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.287051) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.287392) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.287766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.288085) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 09:53:40-- 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% 222M=0s 2015-12-17 09:53:40 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166619 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.467266) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.467595) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022351 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:39.517396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 09:53:39.517779) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 09:53:40,641 Setting DEBUG logging. 2015-12-17 09:53:40,641 attempt 1/10 2015-12-17 09:53:40,641 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0711218a018d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 09:53:42,119 unpacking tar archive at: mozilla-central-0711218a018d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.922944 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-17 09:53:41.453731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:41.454071) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:41.461204) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:41.461505) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:53:41.461928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-17 09:53:41.462238) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:53:42 INFO - MultiFileLogger online at 20151217 09:53:42 in /builds/slave/test-pgo 09:53:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:53:42 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:53:42 INFO - {'append_to_log': False, 09:53:42 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:53:42 INFO - 'blob_upload_branch': 'Firefox', 09:53:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:53:42 INFO - 'branch': 'Firefox', 09:53:42 INFO - 'buildbot_json_path': 'buildprops.json', 09:53:42 INFO - 'config_files': ('talos/linux_config.py',), 09:53:42 INFO - 'default_actions': ('clobber', 09:53:42 INFO - 'read-buildbot-config', 09:53:42 INFO - 'download-and-extract', 09:53:42 INFO - 'populate-webroot', 09:53:42 INFO - 'create-virtualenv', 09:53:42 INFO - 'install', 09:53:42 INFO - 'run-tests'), 09:53:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:53:42 INFO - 'download_minidump_stackwalk': True, 09:53:42 INFO - 'download_symbols': 'ondemand', 09:53:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:53:42 INFO - 'tooltool.py': '/tools/tooltool.py', 09:53:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:53:42 INFO - '/tools/misc-python/virtualenv.py')}, 09:53:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:53:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:53:42 INFO - 'installer_path': 'installer.exe', 09:53:42 INFO - 'log_level': 'info', 09:53:42 INFO - 'log_name': 'talos', 09:53:42 INFO - 'log_to_console': True, 09:53:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:53:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:53:42 INFO - 'opt_config_files': (), 09:53:42 INFO - 'pip_index': False, 09:53:42 INFO - 'sps_profile': False, 09:53:42 INFO - 'sps_profile_interval': 0, 09:53:42 INFO - 'suite': 'dromaeojs', 09:53:42 INFO - 'system_bits': '64', 09:53:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:53:42 INFO - 'title': 'talos-linux64-ix-008', 09:53:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:53:42 INFO - 'use_talos_json': True, 09:53:42 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:53:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:53:42 INFO - 'work_dir': 'build'} 09:53:42 INFO - ##### 09:53:42 INFO - ##### Running clobber step. 09:53:42 INFO - ##### 09:53:42 INFO - Running pre-action listener: _resource_record_pre_action 09:53:42 INFO - Running main action method: clobber 09:53:42 INFO - rmtree: /builds/slave/test-pgo/build 09:53:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:53:43 INFO - Running post-action listener: _resource_record_post_action 09:53:43 INFO - ##### 09:53:43 INFO - ##### Running read-buildbot-config step. 09:53:43 INFO - ##### 09:53:43 INFO - Running pre-action listener: _resource_record_pre_action 09:53:43 INFO - Running main action method: read_buildbot_config 09:53:43 INFO - Using buildbot properties: 09:53:43 INFO - { 09:53:43 INFO - "project": "", 09:53:43 INFO - "product": "firefox", 09:53:43 INFO - "script_repo_revision": "production", 09:53:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:53:43 INFO - "repository": "", 09:53:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:53:43 INFO - "buildid": "20151217072859", 09:53:43 INFO - "pgo_build": "True", 09:53:43 INFO - "basedir": "/builds/slave/test-pgo", 09:53:43 INFO - "buildnumber": 605, 09:53:43 INFO - "slavename": "talos-linux64-ix-008", 09:53:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:53:43 INFO - "platform": "ubuntu64_hw", 09:53:43 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:53:43 INFO - "branch": "mozilla-central", 09:53:43 INFO - "repo_path": "mozilla-central", 09:53:43 INFO - "revision": "0711218a018d", 09:53:43 INFO - "stage_platform": "linux64-pgo", 09:53:43 INFO - "builduid": "d0168571701b424e94c8027f9dd4f50b", 09:53:43 INFO - "slavebuilddir": "test" 09:53:43 INFO - } 09:53:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 09:53:43 INFO - Running post-action listener: _resource_record_post_action 09:53:43 INFO - ##### 09:53:43 INFO - ##### Running download-and-extract step. 09:53:43 INFO - ##### 09:53:43 INFO - Running pre-action listener: _resource_record_pre_action 09:53:43 INFO - Running main action method: download_and_extract 09:53:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:53:43 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')]} 09:53:43 INFO - https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:53:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/test_packages.json 09:53:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/test_packages.json 09:53:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:53:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:53:44 INFO - Downloaded 1302 bytes. 09:53:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:53:44 INFO - Using the following test package requirements: 09:53:44 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:53:44 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:53:44 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'jsshell-linux-x86_64.zip'], 09:53:44 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:53:44 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:53:44 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:53:44 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:53:44 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:53:44 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:53:44 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:53:44 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:53:44 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:53: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')]} 09:53:44 INFO - https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:53:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:53:45 INFO - Downloaded 23120049 bytes. 09:53:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:53:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:53:46 INFO - Return code: 0 09:53:46 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:46 INFO - https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:53:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:53:47 INFO - Downloaded 23120049 bytes. 09:53:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:53:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:53:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:53:48 INFO - Return code: 0 09:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:48 INFO - https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:53:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:53:49 INFO - Downloaded 11132039 bytes. 09:53:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:53:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:53:49 INFO - Return code: 0 09:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:49 INFO - https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:53:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:53:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:53:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:53:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:53:51 INFO - Downloaded 54638638 bytes. 09:53:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:53:51 INFO - mkdir: /builds/slave/test-pgo/properties 09:53:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:53:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:53:51 INFO - Contents: 09:53:51 INFO - build_url:https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:53:51 INFO - Running post-action listener: _resource_record_post_action 09:53:51 INFO - Running post-action listener: set_extra_try_arguments 09:53:51 INFO - ##### 09:53:51 INFO - ##### Running populate-webroot step. 09:53:51 INFO - ##### 09:53:51 INFO - Running pre-action listener: _resource_record_pre_action 09:53:51 INFO - Running main action method: populate_webroot 09:53:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:53:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:53:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:53:51 INFO - '--tppagecycles', 09:53:51 INFO - '7'], 09:53:51 INFO - 'tests': ['tsvgm']}}, 09:53:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:53:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:53:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:53:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:53:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'tests': ['damp', 'tps']}, 09:53:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['damp', 'tps']}, 09:53:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:53:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:53:51 INFO - 'other': {'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'other_l64': {'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'other_nol64': {'tests': ['a11yr', 09:53:51 INFO - 'ts_paint', 09:53:51 INFO - 'tpaint', 09:53:51 INFO - 'sessionrestore', 09:53:51 INFO - 'sessionrestore_no_auto_restore']}, 09:53:51 INFO - 'svgr': {'tests': ['tsvgx', 09:53:51 INFO - 'tsvgr_opacity', 09:53:51 INFO - 'tart', 09:53:51 INFO - 'tscrollx', 09:53:51 INFO - 'cart']}, 09:53:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['tsvgx', 09:53:51 INFO - 'tsvgr_opacity', 09:53:51 INFO - 'tart', 09:53:51 INFO - 'tscrollx', 09:53:51 INFO - 'cart']}, 09:53:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'tests': ['tp5o']}, 09:53:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'talos_options': ['--e10s'], 09:53:51 INFO - 'tests': ['tp5o']}, 09:53:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'talos_options': ['--xperf_path', 09:53:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:51 INFO - 'tests': ['tp5n']}, 09:53:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:51 INFO - 'talos_options': ['--e10s', 09:53:51 INFO - '--xperf_path', 09:53:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:51 INFO - 'tests': ['tp5n']}}, 09:53:51 INFO - 'talos.zip': {'path': '', 09:53:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:53:51 INFO - Running post-action listener: _resource_record_post_action 09:53:51 INFO - ##### 09:53:51 INFO - ##### Running create-virtualenv step. 09:53:51 INFO - ##### 09:53:51 INFO - Running pre-action listener: _resource_record_pre_action 09:53:51 INFO - Running main action method: create_virtualenv 09:53:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:53:51 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 09:53:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:53:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:53:51 INFO - Using real prefix '/usr' 09:53:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:53:52 INFO - Installing distribute.............................................................................................................................................................................................done. 09:53:54 INFO - Installing pip.................done. 09:53:54 INFO - Return code: 0 09:53:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:53:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:53:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:53:54 INFO - Using env: {'DISPLAY': ':0', 09:53:54 INFO - 'HOME': '/home/cltbld', 09:53:54 INFO - 'LANG': 'en_US.UTF-8', 09:53:54 INFO - 'LANGUAGE': 'en_US:en', 09:53:54 INFO - 'LOGNAME': 'cltbld', 09:53:54 INFO - 'MAIL': '/var/mail/cltbld', 09:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:54 INFO - 'MOZ_NO_REMOTE': '1', 09:53:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:54 INFO - 'NO_EM_RESTART': '1', 09:53:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:53:54 INFO - 'PWD': '/builds/slave/test-pgo', 09:53:54 INFO - 'SHELL': '/bin/bash', 09:53:54 INFO - 'SHLVL': '1', 09:53:54 INFO - 'TERM': 'linux', 09:53:54 INFO - 'TMOUT': '86400', 09:53:54 INFO - 'USER': 'cltbld', 09:53:54 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:54 INFO - '_': '/tools/buildbot/bin/python'} 09:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:54 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 09:53:54 INFO - Cleaning up... 09:53:54 INFO - Return code: 0 09:53:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:53:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:53:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:53:54 INFO - Using env: {'DISPLAY': ':0', 09:53:54 INFO - 'HOME': '/home/cltbld', 09:53:54 INFO - 'LANG': 'en_US.UTF-8', 09:53:54 INFO - 'LANGUAGE': 'en_US:en', 09:53:54 INFO - 'LOGNAME': 'cltbld', 09:53:54 INFO - 'MAIL': '/var/mail/cltbld', 09:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:54 INFO - 'MOZ_NO_REMOTE': '1', 09:53:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:54 INFO - 'NO_EM_RESTART': '1', 09:53:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:53:54 INFO - 'PWD': '/builds/slave/test-pgo', 09:53:54 INFO - 'SHELL': '/bin/bash', 09:53:54 INFO - 'SHLVL': '1', 09:53:54 INFO - 'TERM': 'linux', 09:53:54 INFO - 'TMOUT': '86400', 09:53:54 INFO - 'USER': 'cltbld', 09:53:54 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:54 INFO - '_': '/tools/buildbot/bin/python'} 09:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:54 INFO - Downloading/unpacking psutil>=0.7.1 09:53:54 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 09:53:54 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 09:53:54 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 09:53:54 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 09:53:54 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 09:53:54 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 09:53:56 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 09:53:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:53:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:53:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:56 INFO - Installing collected packages: psutil 09:53:56 INFO - Running setup.py install for psutil 09:53:56 INFO - building 'psutil._psutil_linux' extension 09:53:56 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 09:53:56 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 09:53:56 INFO - building 'psutil._psutil_posix' extension 09:53:56 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 09:53:56 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 09:53:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:57 INFO - Successfully installed psutil 09:53:57 INFO - Cleaning up... 09:53:57 INFO - Return code: 0 09:53:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:53:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:53:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:53:57 INFO - Using env: {'DISPLAY': ':0', 09:53:57 INFO - 'HOME': '/home/cltbld', 09:53:57 INFO - 'LANG': 'en_US.UTF-8', 09:53:57 INFO - 'LANGUAGE': 'en_US:en', 09:53:57 INFO - 'LOGNAME': 'cltbld', 09:53:57 INFO - 'MAIL': '/var/mail/cltbld', 09:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:57 INFO - 'MOZ_NO_REMOTE': '1', 09:53:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:57 INFO - 'NO_EM_RESTART': '1', 09:53:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:53:57 INFO - 'PWD': '/builds/slave/test-pgo', 09:53:57 INFO - 'SHELL': '/bin/bash', 09:53:57 INFO - 'SHLVL': '1', 09:53:57 INFO - 'TERM': 'linux', 09:53:57 INFO - 'TMOUT': '86400', 09:53:57 INFO - 'USER': 'cltbld', 09:53:57 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:57 INFO - '_': '/tools/buildbot/bin/python'} 09:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:53:57 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 09:53:57 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 09:53:57 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 09:53:57 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 09:53:57 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 09:53:57 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 09:53:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:53:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:53:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:53:59 INFO - Installing collected packages: mozsystemmonitor 09:53:59 INFO - Running setup.py install for mozsystemmonitor 09:53:59 INFO - Successfully installed mozsystemmonitor 09:53:59 INFO - Cleaning up... 09:53:59 INFO - Return code: 0 09:53:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:53:59 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')]} 09:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:59 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')]} 09:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:53:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:53:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:53:59 INFO - Using env: {'DISPLAY': ':0', 09:53:59 INFO - 'HOME': '/home/cltbld', 09:53:59 INFO - 'LANG': 'en_US.UTF-8', 09:53:59 INFO - 'LANGUAGE': 'en_US:en', 09:53:59 INFO - 'LOGNAME': 'cltbld', 09:53:59 INFO - 'MAIL': '/var/mail/cltbld', 09:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:59 INFO - 'MOZ_NO_REMOTE': '1', 09:53:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:59 INFO - 'NO_EM_RESTART': '1', 09:53:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:53:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:53:59 INFO - 'SHELL': '/bin/bash', 09:53:59 INFO - 'SHLVL': '1', 09:53:59 INFO - 'TERM': 'linux', 09:53:59 INFO - 'TMOUT': '86400', 09:53:59 INFO - 'USER': 'cltbld', 09:53:59 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:59 INFO - '_': '/tools/buildbot/bin/python'} 09:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:54:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:54:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:54:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:54:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:54:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:01 INFO - Downloading docopt-0.6.1.tar.gz 09:54:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:54:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:54:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:54:01 INFO - Running setup.py install for blobuploader 09:54:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:54:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:54:01 INFO - Running setup.py install for requests 09:54:02 INFO - Running setup.py install for docopt 09:54:02 INFO - Successfully installed blobuploader requests docopt 09:54:02 INFO - Cleaning up... 09:54:02 INFO - Return code: 0 09:54:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:54:02 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')]} 09:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:02 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')]} 09:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:54:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:54:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:02 INFO - Using env: {'DISPLAY': ':0', 09:54:02 INFO - 'HOME': '/home/cltbld', 09:54:02 INFO - 'LANG': 'en_US.UTF-8', 09:54:02 INFO - 'LANGUAGE': 'en_US:en', 09:54:02 INFO - 'LOGNAME': 'cltbld', 09:54:02 INFO - 'MAIL': '/var/mail/cltbld', 09:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:02 INFO - 'MOZ_NO_REMOTE': '1', 09:54:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:02 INFO - 'NO_EM_RESTART': '1', 09:54:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:02 INFO - 'SHELL': '/bin/bash', 09:54:02 INFO - 'SHLVL': '1', 09:54:02 INFO - 'TERM': 'linux', 09:54:02 INFO - 'TMOUT': '86400', 09:54:02 INFO - 'USER': 'cltbld', 09:54:02 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:02 INFO - '_': '/tools/buildbot/bin/python'} 09:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:54:02 INFO - Running setup.py (path:/tmp/pip-YZFX_Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:54:02 INFO - Running setup.py (path:/tmp/pip-b39Rdu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:54:02 INFO - Running setup.py (path:/tmp/pip-Jk9Syf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:54:02 INFO - Running setup.py (path:/tmp/pip-0Dn7kW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:54:02 INFO - Running setup.py (path:/tmp/pip-wjdje4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:54:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:54:02 INFO - Running setup.py (path:/tmp/pip-UvCJCN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:54:03 INFO - Running setup.py (path:/tmp/pip-cK7xdJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:54:03 INFO - Running setup.py (path:/tmp/pip-FORczF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:54:03 INFO - Running setup.py (path:/tmp/pip-5nj1bN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:54:03 INFO - Running setup.py (path:/tmp/pip-CWk8Uw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:54:03 INFO - Running setup.py (path:/tmp/pip-v2OXYO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:54:03 INFO - Running setup.py (path:/tmp/pip-0K7RWL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:54:03 INFO - Running setup.py (path:/tmp/pip-B52dHS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:54:03 INFO - Running setup.py (path:/tmp/pip-8fnOgY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:54:03 INFO - Running setup.py (path:/tmp/pip-sGd_Aw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:54:03 INFO - Running setup.py (path:/tmp/pip-ngYtUX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:54:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:54:03 INFO - Running setup.py (path:/tmp/pip-RnJKdW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:54:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:54:03 INFO - Running setup.py install for manifestparser 09:54:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:54:03 INFO - Running setup.py install for mozcrash 09:54:04 INFO - Running setup.py install for mozdebug 09:54:04 INFO - Running setup.py install for mozdevice 09:54:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for mozfile 09:54:04 INFO - Running setup.py install for mozhttpd 09:54:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for mozinfo 09:54:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for mozInstall 09:54:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for mozleak 09:54:04 INFO - Running setup.py install for mozlog 09:54:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for moznetwork 09:54:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:54:04 INFO - Running setup.py install for mozprocess 09:54:05 INFO - Running setup.py install for mozprofile 09:54:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:54:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:54:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:54:05 INFO - Running setup.py install for mozrunner 09:54:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:54:05 INFO - Running setup.py install for mozscreenshot 09:54:05 INFO - Running setup.py install for moztest 09:54:05 INFO - Running setup.py install for mozversion 09:54:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:54:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:54:05 INFO - Cleaning up... 09:54:05 INFO - Return code: 0 09:54:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:54:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:54:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:05 INFO - Using env: {'DISPLAY': ':0', 09:54:05 INFO - 'HOME': '/home/cltbld', 09:54:05 INFO - 'LANG': 'en_US.UTF-8', 09:54:05 INFO - 'LANGUAGE': 'en_US:en', 09:54:05 INFO - 'LOGNAME': 'cltbld', 09:54:05 INFO - 'MAIL': '/var/mail/cltbld', 09:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:05 INFO - 'MOZ_NO_REMOTE': '1', 09:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:05 INFO - 'NO_EM_RESTART': '1', 09:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:05 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:05 INFO - 'SHELL': '/bin/bash', 09:54:05 INFO - 'SHLVL': '1', 09:54:05 INFO - 'TERM': 'linux', 09:54:05 INFO - 'TMOUT': '86400', 09:54:05 INFO - 'USER': 'cltbld', 09:54:05 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:05 INFO - '_': '/tools/buildbot/bin/python'} 09:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:54:05 INFO - Running setup.py (path:/tmp/pip-3KDaJu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:54:05 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)) 09:54:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:54:05 INFO - Running setup.py (path:/tmp/pip-WXAoTo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:54:05 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)) 09:54:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:54:05 INFO - Running setup.py (path:/tmp/pip-kLDmEM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:54:06 INFO - Running setup.py (path:/tmp/pip-ImYQ3W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:54:06 INFO - Running setup.py (path:/tmp/pip-yVJ655-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:54:06 INFO - Running setup.py (path:/tmp/pip-V18BdV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:54:06 INFO - Running setup.py (path:/tmp/pip-nd0tBa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:54:06 INFO - Running setup.py (path:/tmp/pip-BHKp_K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:54:06 INFO - Running setup.py (path:/tmp/pip-HU8jDu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:54:06 INFO - Running setup.py (path:/tmp/pip-0kUxtD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:54:06 INFO - Running setup.py (path:/tmp/pip-8Ni3kW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:54:06 INFO - Running setup.py (path:/tmp/pip-eKPvIY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:54:06 INFO - Running setup.py (path:/tmp/pip-sksdbV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:54:06 INFO - Running setup.py (path:/tmp/pip-a7bWgN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:54:06 INFO - Running setup.py (path:/tmp/pip-SHzo3i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:54:06 INFO - Running setup.py (path:/tmp/pip-dfDZtD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:54:06 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)) 09:54:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:54:06 INFO - Running setup.py (path:/tmp/pip-Y8a272-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:54:07 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)) 09:54:07 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)) 09:54:07 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)) 09:54:07 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)) 09:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:54:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:54:07 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 09:54:07 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 09:54:07 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 09:54:07 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 09:54:07 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 09:54:07 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 09:54:08 INFO - Downloading blessings-1.5.1.tar.gz 09:54:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 09:54:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:54:08 INFO - Installing collected packages: blessings 09:54:08 INFO - Running setup.py install for blessings 09:54:08 INFO - Successfully installed blessings 09:54:08 INFO - Cleaning up... 09:54:08 INFO - Return code: 0 09:54:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:54:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:54:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:54:09 INFO - Reading from file tmpfile_stdout 09:54:09 INFO - Current package versions: 09:54:09 INFO - argparse == 1.2.1 09:54:09 INFO - blessings == 1.5.1 09:54:09 INFO - blobuploader == 1.2.4 09:54:09 INFO - docopt == 0.6.1 09:54:09 INFO - manifestparser == 1.1 09:54:09 INFO - mozInstall == 1.12 09:54:09 INFO - mozcrash == 0.16 09:54:09 INFO - mozdebug == 0.1 09:54:09 INFO - mozdevice == 0.47 09:54:09 INFO - mozfile == 1.2 09:54:09 INFO - mozhttpd == 0.7 09:54:09 INFO - mozinfo == 0.9 09:54:09 INFO - mozleak == 0.1 09:54:09 INFO - mozlog == 3.1 09:54:09 INFO - moznetwork == 0.27 09:54:09 INFO - mozprocess == 0.22 09:54:09 INFO - mozprofile == 0.27 09:54:09 INFO - mozrunner == 6.11 09:54:09 INFO - mozscreenshot == 0.1 09:54:09 INFO - mozsystemmonitor == 0.0 09:54:09 INFO - moztest == 0.7 09:54:09 INFO - mozversion == 1.4 09:54:09 INFO - psutil == 3.1.1 09:54:09 INFO - requests == 1.2.3 09:54:09 INFO - wsgiref == 0.1.2 09:54:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:54:09 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')]} 09:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:09 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')]} 09:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:54:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:54:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:54:09 INFO - Using env: {'DISPLAY': ':0', 09:54:09 INFO - 'HOME': '/home/cltbld', 09:54:09 INFO - 'LANG': 'en_US.UTF-8', 09:54:09 INFO - 'LANGUAGE': 'en_US:en', 09:54:09 INFO - 'LOGNAME': 'cltbld', 09:54:09 INFO - 'MAIL': '/var/mail/cltbld', 09:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:09 INFO - 'MOZ_NO_REMOTE': '1', 09:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:09 INFO - 'NO_EM_RESTART': '1', 09:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:09 INFO - 'SHELL': '/bin/bash', 09:54:09 INFO - 'SHLVL': '1', 09:54:09 INFO - 'TERM': 'linux', 09:54:09 INFO - 'TMOUT': '86400', 09:54:09 INFO - 'USER': 'cltbld', 09:54:09 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:09 INFO - '_': '/tools/buildbot/bin/python'} 09:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:09 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 1)) 09:54:09 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 2)) 09:54:09 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 3)) 09:54:09 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 4)) 09:54:09 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 5)) 09:54:09 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 6)) 09:54:09 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 7)) 09:54:09 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 8)) 09:54:09 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:54:09 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 3)) 09:54:09 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.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:54:09 INFO - Cleaning up... 09:54:09 INFO - Return code: 0 09:54:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:54:09 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')]} 09:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:09 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')]} 09:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:54:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:54:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:54:09 INFO - Using env: {'DISPLAY': ':0', 09:54:09 INFO - 'HOME': '/home/cltbld', 09:54:09 INFO - 'LANG': 'en_US.UTF-8', 09:54:09 INFO - 'LANGUAGE': 'en_US:en', 09:54:09 INFO - 'LOGNAME': 'cltbld', 09:54:09 INFO - 'MAIL': '/var/mail/cltbld', 09:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:09 INFO - 'MOZ_NO_REMOTE': '1', 09:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:09 INFO - 'NO_EM_RESTART': '1', 09:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:09 INFO - 'SHELL': '/bin/bash', 09:54:09 INFO - 'SHLVL': '1', 09:54:09 INFO - 'TERM': 'linux', 09:54:09 INFO - 'TMOUT': '86400', 09:54:09 INFO - 'USER': 'cltbld', 09:54:09 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:09 INFO - '_': '/tools/buildbot/bin/python'} 09:54:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:09 INFO - Downloading/unpacking jsonschema 09:54:09 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 09:54:09 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 09:54:09 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 09:54:09 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 09:54:09 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 09:54:09 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 09:54:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:54:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 09:54:11 INFO - Downloading/unpacking functools32 (from jsonschema) 09:54:11 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 09:54:11 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 09:54:11 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 09:54:11 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 09:54:11 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 09:54:11 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 09:54:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:54:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 09:54:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:54:11 INFO - warning: no files found matching '*.txt' 09:54:11 INFO - no previously-included directories found matching 'build' 09:54:11 INFO - no previously-included directories found matching 'dist' 09:54:11 INFO - no previously-included directories found matching '.git*' 09:54:11 INFO - Installing collected packages: jsonschema, functools32 09:54:11 INFO - Running setup.py install for functools32 09:54:11 INFO - warning: no files found matching '*.txt' 09:54:11 INFO - no previously-included directories found matching 'build' 09:54:11 INFO - no previously-included directories found matching 'dist' 09:54:11 INFO - no previously-included directories found matching '.git*' 09:54:11 INFO - Successfully installed jsonschema functools32 09:54:11 INFO - Cleaning up... 09:54:11 INFO - Return code: 0 09:54:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:54:11 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')]} 09:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:11 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')]} 09:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5777c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe7f0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, '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': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', '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 09:54:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:54:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:54:11 INFO - Using env: {'DISPLAY': ':0', 09:54:11 INFO - 'HOME': '/home/cltbld', 09:54:11 INFO - 'LANG': 'en_US.UTF-8', 09:54:11 INFO - 'LANGUAGE': 'en_US:en', 09:54:11 INFO - 'LOGNAME': 'cltbld', 09:54:11 INFO - 'MAIL': '/var/mail/cltbld', 09:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:11 INFO - 'MOZ_NO_REMOTE': '1', 09:54:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:11 INFO - 'NO_EM_RESTART': '1', 09:54:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:11 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:11 INFO - 'SHELL': '/bin/bash', 09:54:11 INFO - 'SHLVL': '1', 09:54:11 INFO - 'TERM': 'linux', 09:54:11 INFO - 'TMOUT': '86400', 09:54:11 INFO - 'USER': 'cltbld', 09:54:11 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:11 INFO - '_': '/tools/buildbot/bin/python'} 09:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:12 INFO - Downloading/unpacking flake8 09:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:54:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 09:54:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:54:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 09:54:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:13 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 09:54:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 09:54:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:54:14 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 09:54:14 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 09:54:14 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 09:54:14 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 09:54:14 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 09:54:14 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 09:54:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 09:54:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:54:14 INFO - no previously-included directories found matching 'docs/_build' 09:54:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:54:14 INFO - Running setup.py install for pep8 09:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:54:14 INFO - no previously-included directories found matching 'docs/_build' 09:54:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:54:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:54:14 INFO - Cleaning up... 09:54:14 INFO - Return code: 0 09:54:14 INFO - Running post-action listener: _resource_record_post_action 09:54:14 INFO - Running post-action listener: _start_resource_monitoring 09:54:14 INFO - Starting resource monitoring. 09:54:14 INFO - ##### 09:54:14 INFO - ##### Running install step. 09:54:14 INFO - ##### 09:54:14 INFO - Running pre-action listener: _resource_record_pre_action 09:54:14 INFO - Running main action method: install 09:54:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:54:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:54:14 INFO - Reading from file tmpfile_stdout 09:54:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:54:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:54:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:54:14 INFO - Reading from file tmpfile_stdout 09:54:14 INFO - Output received: 09:54:14 INFO - Usage: mozinstall [options] installer 09:54:14 INFO - Options: 09:54:14 INFO - -h, --help show this help message and exit 09:54:14 INFO - -d DEST, --destination=DEST 09:54:14 INFO - Directory to install application into. [default: 09:54:14 INFO - "/builds/slave/test-pgo"] 09:54:14 INFO - --app=APP Application being installed. [default: firefox] 09:54:14 INFO - mkdir: /builds/slave/test-pgo/build/application 09:54:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:54:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:54:30 INFO - Reading from file tmpfile_stdout 09:54:30 INFO - Output received: 09:54:30 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:54:30 INFO - Running post-action listener: _resource_record_post_action 09:54:30 INFO - ##### 09:54:30 INFO - ##### Running run-tests step. 09:54:30 INFO - ##### 09:54:30 INFO - Running pre-action listener: _resource_record_pre_action 09:54:30 INFO - Running main action method: run_tests 09:54:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:54:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:54:30 INFO - Python 2.7.3 09:54:30 INFO - Return code: 0 09:54:30 INFO - grabbing minidump binary from tooltool 09:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:30 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 0xf0de30>, '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 0xf0ed30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf0f1c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:54:30 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 09:54:30 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 09:54:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:54:30 INFO - Return code: 0 09:54:30 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:54:30 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:54:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:54:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:54:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:54:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:54:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:54:31 INFO - Return code: 0 09:54:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:54:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 09:54:31 INFO - Using env: {'DISPLAY': ':0', 09:54:31 INFO - 'HOME': '/home/cltbld', 09:54:31 INFO - 'LANG': 'en_US.UTF-8', 09:54:31 INFO - 'LANGUAGE': 'en_US:en', 09:54:31 INFO - 'LOGNAME': 'cltbld', 09:54:31 INFO - 'MAIL': '/var/mail/cltbld', 09:54:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:54:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:31 INFO - 'MOZ_NO_REMOTE': '1', 09:54:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:54:31 INFO - 'NO_EM_RESTART': '1', 09:54:31 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:54:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:54:31 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:54:31 INFO - 'SHELL': '/bin/bash', 09:54:31 INFO - 'SHLVL': '1', 09:54:31 INFO - 'TERM': 'linux', 09:54:31 INFO - 'TMOUT': '86400', 09:54:31 INFO - 'USER': 'cltbld', 09:54:31 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702', 09:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:31 INFO - '_': '/tools/buildbot/bin/python'} 09:54:31 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 09:54:31 INFO - mozversion INFO | application_buildid: 20151217072859 09:54:31 INFO - mozversion INFO | application_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 09:54:31 INFO - mozversion INFO | application_display_name: Nightly 09:54:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:54:31 INFO - mozversion INFO | application_name: Firefox 09:54:31 INFO - mozversion INFO | application_remotingname: firefox 09:54:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:54:31 INFO - mozversion INFO | application_vendor: Mozilla 09:54:31 INFO - mozversion INFO | application_version: 46.0a1 09:54:31 INFO - mozversion INFO | platform_buildid: 20151217072859 09:54:31 INFO - mozversion INFO | platform_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 09:54:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:54:31 INFO - mozversion INFO | platform_version: 46.0a1 09:54:31 INFO - 2015-12-17 09:54:31,878 DEBUG : using testdate: 1450374871 09:54:31 INFO - 2015-12-17 09:54:31,878 DEBUG : actual date: 1450374871 09:54:31 INFO - 2015-12-17 09:54:31,887 INFO : starting webserver on 'localhost:48875' 09:54:31 INFO - 2015-12-17 09:54:31,888 INFO : Starting test suite talos-linux64-ix-008 09:54:31 INFO - 2015-12-17 09:54:31,888 INFO : Starting test dromaeo_css 09:54:31 INFO - 2015-12-17 09:54:31,888 DEBUG : operating with platform_type : linux_ 09:54:31 INFO - 2015-12-17 09:54:31,889 INFO : Initialising browser for dromaeo_css test... 09:54:31 INFO - 2015-12-17 09:54:31,905 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8Aois8/profile http://localhost:48875/getInfo.html 09:54:35 INFO - 2015-12-17 09:54:35,840 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:54:35 INFO - 2015-12-17 09:54:35,840 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:54:35 INFO - 2015-12-17 09:54:35,840 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:54:35 INFO - 2015-12-17 09:54:35,840 DEBUG : BROWSER_OUTPUT: __metrics 09:54:35 INFO - 2015-12-17 09:54:35,937 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 09:54:35 INFO - 2015-12-17 09:54:35,993 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:54:37 INFO - 2015-12-17 09:54:37,123 INFO : Browser initialized. 09:54:37 INFO - 2015-12-17 09:54:37,124 INFO : Running cycle 1/1 for dromaeo_css test... 09:54:37 INFO - 2015-12-17 09:54:37,124 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8Aois8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:54:48 INFO - 2015-12-17 09:54:48,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 150228992 09:54:48 INFO - 2015-12-17 09:54:48,909 DEBUG : BROWSER_OUTPUT: 09:55:40 INFO - 2015-12-17 09:55:40,563 DEBUG : BROWSER_OUTPUT: 1450374940561 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] 09:57:49 INFO - 2015-12-17 09:57:49,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48875/tests/dromaeo/cssquery-ext.html) 09:57:50 INFO - 2015-12-17 09:57:50,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 254414848 09:57:50 INFO - 2015-12-17 09:57:50,217 DEBUG : BROWSER_OUTPUT: 10:00:52 INFO - 2015-12-17 10:00:52,531 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-ext.html (next: http://localhost:48875/tests/dromaeo/cssquery-jquery.html) 10:00:53 INFO - 2015-12-17 10:00:53,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 237510656 10:00:53 INFO - 2015-12-17 10:00:53,100 DEBUG : BROWSER_OUTPUT: 10:03:55 INFO - 2015-12-17 10:03:55,063 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48875/tests/dromaeo/cssquery-mootools.html) 10:03:55 INFO - 2015-12-17 10:03:55,628 DEBUG : BROWSER_OUTPUT: RSS: Main: 234397696 10:03:55 INFO - 2015-12-17 10:03:55,629 DEBUG : BROWSER_OUTPUT: 10:06:57 INFO - 2015-12-17 10:06:57,458 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48875/tests/dromaeo/cssquery-prototype.html) 10:06:58 INFO - 2015-12-17 10:06:58,027 DEBUG : BROWSER_OUTPUT: RSS: Main: 211308544 10:06:58 INFO - 2015-12-17 10:06:58,027 DEBUG : BROWSER_OUTPUT: 10:10:00 INFO - 2015-12-17 10:10:00,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48875/tests/dromaeo/cssquery-yui.html) 10:10:01 INFO - 2015-12-17 10:10:01,010 DEBUG : BROWSER_OUTPUT: RSS: Main: 215076864 10:10:01 INFO - 2015-12-17 10:10:01,010 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/dromaeo/cssquery-yui.html (next: http://localhost:48875/tests/dromaeo/cssquery-dojo.html) 10:13:03 INFO - 2015-12-17 10:13:03,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 299089920 10:13:03 INFO - 2015-12-17 10:13:03,853 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,853 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:13:03 INFO - 2015-12-17 10:13:03,853 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:13:03 INFO - 2015-12-17 10:13:03,853 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:13:03 INFO - 2015-12-17 10:13:03,854 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:13:03 INFO - 2015-12-17 10:13:03,854 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3116.0807561769284;3167.6673949235314;3197.920051998701;3202.2954949911014;3230.951535726964;5174.922376164361;5241.2924255225535;5267.631265811395;5281.201331495643;5332.040232758102;5164.147915593922;5165.974170129153;5268.157094864822;5268.420473747885;5277.445868183833;4790.832320868771;4794.784234709437;4808.3508726322025;4857.052874689437;4926.876828079308;4915.410150781893;4938.430919044464;4959.231319145538;4985;4999.7500124993785;4710.246360582325;4723.244280915037;4831.758412079382;4832.057748738997;4958.801647934079;4819;4838.7338696372035;4842.031593681243;4846.6365022623095;4848.0788650156355;4800.231962885921;4804.567588917014;4807.879803004953;4835.298881662175;4842.12841688496;4999.825006124768;5097.770600322994;5112;5187.584993200535;5204.271402003723;5773;5775.3935836736955;5780;5782.161586569966;5797.043487824504;5615.382307946123;5791.594588378815;5796.362400135981;5800.129980502916;5811.4479124483105;5308.681479111227;5345.064613692588;5355.357357117132;5417.241586177938;5420.268263784417;5738.081906894877;5738.942610573924;5747.683877386784;5770.971145144247;5798.1302804579655;5569.610127291051;5571.4150014248735;5574.554035677136;5575.4424557543925;5575.916361254584;5529.253550770594;5550.056490396642;5601.915971260434;5656.745446454919;5658.688772117573;5689.203511508392;5705.2012718219485;5715.914261286084;5717.342505611825;5729.828105156852;5710.343310519261;5716.885662286731;5723.370429252779;5723.427657234243;5727.427257274239;4286.507051689034;4442.933355999663;4447.421835161408;4455.420795296655;4486.12520558488;3876.5929577393968;3888.922221555553;3914.373700207953;3942.42834788954;3959.3269144245546;5345.037893179265;5347.81282655113;5351.956368508104;5361.8123365682795;5380.973095134499;5503.752331145108;5618.775248990077;5662.17898404729;5663.235463212496;5667.631603945731;5209.348831396075;5255.474452554714;5284.841454756286;5321.36143662763;5366.060939335601;4992.350994370709;4996.150654388763;5005.27423523587;5016;5037.697738135724;5664.46187612168;5768.884622307614;5815.04051831443;5824.912626310609;5831.329397119385;5562.721863906708;5580.218769372373;5595.104783234663;5699.544036477155;5702.543796496353;5825.533957283492;5880.852978675567;5894.823155305347;5905.379935106924;5910.202122713378;5684.261046063985;5688.971555142198;5691.601587888808;5693.772249109989;5698.0028495012375;5509.228707980806;5625.746841392229;5632.9436705632415;5646.604737668488;5754.769809207585;5606.159076138449;5613.494785469327;5616.7191640416995;5621.8594535136945;5623.8594035149445;5609.21470994053;5610.326760788732;5611.158326251095;5614.157876318585;5619.438056194348;5369.597280203922;5408.053590621704;5411.729413529229;5460.890782184255;5517.531009864046;5589.944100558942;5600.327960644749;5605.635633683797;5620.9156862647915;5655.095184770417;5505.504504594445;5509.641873278224;5511.641743286674;5516.779328826962;5518.475744804237 10:13:03 INFO - 2015-12-17 10:13:03,854 DEBUG : BROWSER_OUTPUT: |1;ext.html;564.4177886170767;601.419630056995;604.356360476093;609.2141137932068;610.0970563565919;30776.230594235127;31487.68512314876;39059.60940390599;39537.60462395375;39588.20823583526;43631.78184109075;44151;44170.7791461043;44193.33709994353;44503;7421.0723659542555;8739.606717697703;8905.910940890606;8908.331875109361;8916.643334266653;7659.119201291867;7762.4178186635945;7762.8447431051345;10426.322289051226;10751.569937202503;10420.218483613722;10493.895061049407;10499.895001050007;10505.107065899409;10511.316764410327;10401;10494.580216791319;10494.580216791319;10495.055445009948;10500.422476763775;7741.922580774177;7973.1628179040945;7975.7607271781935;10482.109020733247;10792.406417647026;40122;40385.19229615421;40488.595114048774;40598.79700601478;40620.39069413961;3262.804231746079;3320.2197483591335;3402.931941361184;3465.9133521661906;3819.9618003819883;30046.54930176049;30396.848015760002;30549.69450305513;30647.540286895714;31301;3709;3755.1738617504107;3848.615138486157;3886.2033283177166;3948.5853985331414;266718;267696;268199;268369;268573;59890.70054649786;60070.39929600735;60077.498062547915;60330.69834650886;60593.394066058776;63962.36037639657;64385;64479.67760161263;64520;64546;13253.801192982113;13588.048836581343;13590.524331648543;13595.184288942695;13607.523736669367;10083.437067254587;10125;10125.645602404027;10130.898691012995;10145.137663298554;19632.803671963382;20325.695114573293;20401.285954991505;20438;20471.4882127948;7648.770536883902;7762.874383214402;7782.525048097366;7799.1031031430975;7802.141764405911;932.244881645869;989.7921436498272;1065.898739619735;1081.874850155829;1087.456271864068;28830.279243018675;28881;28911.855440722662;29333.706662933524;29404.11787646374;953.6757502449201;1016.3800081950004;1019.3628981886321;1030.0429184549266;1060.9734756631146;14038.929805351112;14392;15354.385824567116;15653.452129175424;15868;332431;333701;334195;334880;335616;38562;38790.41814372787;52161;53825;53974;1342.1879762743595;1352.1413902172192;1366.5285476510587;1393.0596847128147;1397.4410235905923;29444.852775735984;32206.516902246018;34241.486377703855;34248.315033699684;34261.65738342684;38239.426408603336;38853;43385.349219762364;44177.55822441863;44234.77882610695;285603;285897;289664;290038;290498;41253.1749365017;41617.37573936333;41979.7901010493;42081.15837683168;42177.78911105425;44789.32816007828;44846.551534484235;45205.773971131246;45451.31823022591;46209;37888.62111378851;38830;39150;42541;43093.35359969666;38842;38934;39535.32300717734;39757.403638946016;39897.202055958136;37348.6265137356;37657.43513847349;38307;38431.61568384281;38504.80747596244;33562;33616;35043.82478087593;35311.64688353083;35679.64320356763;26273.343166420993;26706.287299110703;26728.331791704586;26729.732702672725;26822.865885671228 10:13:03 INFO - 2015-12-17 10:13:03,854 DEBUG : BROWSER_OUTPUT: |2;jquery.html;359.7104331013535;362.82947014902993;363.83809204903827;364.87411842914196;367.54424513603124;13799.793003104962;14095.859041409583;16165.83834161658;16712.582185445353;17343.739843902335;14262.928685356559;14375.712485750279;16186.676266474664;16581.17094145291;16709.49871503854;4792.928106078403;5534.916976245359;5550.833474995757;5552.888942221153;5553.861153471175;4046.7774272415004;4149.585041495857;5396.892062158754;5541.889162216753;5550.4449555044575;5819.243498345209;5962.284525856881;5963.970180149093;5982.8803423931495;5989.371116032819;5931.377205393458;6079.358573185259;6117.755289788403;6166.07508873668;6207.068939659065;5334.359876814807;5339.172428273624;5339.225812257239;5341.813036543703;5354.705491197982;20790.79209207904;21070.262540811153;21274.787252127437;21294.36116916495;21445.892770536047;2233.910643574255;2240.148743477468;2297.0122847175708;2328.4062564046058;2329.813614910803;12865.80701289472;12890.291033993126;12906.54827081057;13275.2460227159;13429.66425839352;1681.9663606727797;1788.1238193285196;1807.430659342303;1819.7816262048507;1823.9908800455912;50856;54928;58191.418085819445;61528.99999999955;62534.68732656353;10118.089371956412;10191.694249172537;10242.795144097076;10301.9484902575;10304.381737095799;13343.399547020408;13380;13402.128861623964;13408.932955335162;13411.329433528284;7174.928250717426;7176.138863336433;7202.459815513857;7222.205557388683;7230.313120253568;6700.162479690039;6731.427828634523;6746.797596072126;6750.189977202767;6793.830154246183;4245.87262382129;4264.594863487963;4360.49854266757;4449.555044495524;4450.599446049871;1739.113052343314;1760.9119544023004;1762.4448298785842;1785.9553511162326;1791.9552011199823;458.36516424751295;981.3768257156756;994.2543092680489;1018.7809620931536;1030.5826140413144;10433.895661043292;10678.839817402744;11010.339379637247;11098.2786118902;11138.888611113947;440.7311539960621;449.79983907161653;453.5600467546475;456.57766565926386;457.41679358817106;5861.384554621789;6160.1375807387;6172.444479996732;6188.659623720649;6200.224971878515;549095;562359;563889;565886.1705691445;577803;83836;84889;85149.57425212834;85746;86325;470.9929351059737;471.4460508902026;472.3458010655199;472.81087564974285;476.33313361294466;9755.9024409755;9783.755406114904;9865.45739984279;9895.65365212214;9955.651552195639;32011.839940801077;32345.676543234265;32986;33399.49900751539;33556.664433355356;46682.29976550423;47730.761346193045;48270;49471.25793113178;50329.245061323374;9458.763530911783;9821.410715357102;9944.35361701487;9968.50157492137;9979;11983.700407489883;12009.819852702041;15799.605009874385;17573.12134393301;18122.637547248716;11836.112291577994;11837.289762614271;12577.251873451027;14030.649233769238;14560.49038283655;13753.449862005407;14029.579112626638;14248.501302454364;14263.786043209568;14338;14014.579562613138;14046.1572305662;14555.490557830424;14654.19401932904;14660.78008829847;9533.189678877374;9598.136167744937;9642.084002019797;9893.455859927773;9911.30620856562;14356.569302920929;14388.928055359656;14615.488457903923;15034.92482537624;15071.54785356441 10:13:03 INFO - 2015-12-17 10:13:03,858 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1151.9193656444052;1638.5985433568774;1681.9579510512235;1704.7016772064885;1705.880588358815;6095.299040610325;6425.453836423903;6742.359475849798;6754.257031726513;6765.695543700533;6696.765613203539;6826.658667066639;6826.692798824052;6844.178698556168;6847.863042739143;2060.4127823570325;2088.1647341063544;2093.727815383998;2096.088201632287;2097.528056187361;2177.0747432341273;2192.4738062864876;2197.9120835166573;2198.5822693688147;2203.8347123965686;2085.9582808343825;2105.210546045233;2116.4074059263353;2129.818965387944;2133.391983284764;2140.1011575138477;2163.8268938484885;2165.4586353411646;2166.7616562178227;2175.3365223606793;2091.184438069139;2094.5706130243266;2104.4633618427356;2111.9683204751786;2112.207922029239;6645.3354664533645;6710.966445167792;6711.463082953352;6722.327767223287;6725.2602213756445;4201.68487363449;4239.554846741109;4255.616994470482;4272.679549033804;4283.057727299989;2889.8410587417684;2913.6212292402065;2936.8091074080116;2938.2066841952765;2946.337074158297;965.3242730088896;1001.5342865567546;1015.3298822776932;1020.8009438159562;1021.1269364693154;8968.058353872815;8977.192052715287;8985.46087234768;8987.370884038186;9020.77448063797;3872.7095467840027;3885.3589157789215;3888.747231429948;3906.1797022625565;3910.511186101737;8012.439129260896;8089.555074470843;8104.5137291762685;8119.553424561587;8119.797005074803;4283.250431174532;4309.784510774512;4310.61204491591;4317.935230971538;4320;3558.1104723819044;3637.418013117888;3851.9229615407535;3945.447637330776;3958.722889397772;3235.530848027023;3288.4409650359016;3309;3427.920205135424;3436.5532480777338;1088.983665245006;1115.7935781880378;1123.5898896902736;1129.5707631100295;1136.1251836086167;1218.5004148299154;1228.7542491501556;1244.3404995352478;1287.459267107817;1289.56799472176;6461.321561236096;6549.574277671936;6568;6605.07528945948;6608.2730899601;459.7172738765702;465.63912967450494;467.42273292483753;467.94384673838675;472.90541891621814;5193.454687257859;5249.632525723163;5250.579953603703;5251.369835619674;5265.684058956398;7926.207379262028;8019.278264956182;8071.515709057476;8095.5142691438705;8244.505329680118;7929.32600728933;7992.440529162903;8085.676572937016;8098.797530061795;8102.878456823152;6521.869562608721;6536.57512261711;6681.899771503334;6695.933040669628;6696.129503164558;6345.7144428499905;6346.873062538816;6347.5556711029785;6356.936430635635;6366.554341196072;7242.456815738736;7312.561246325237;7549.2073332299315;7579.242075792376;7608.771736847903;7555.73554925575;7599.316061554487;7764.650590723318;7774;7776.41676874248;5433.755481003285;5590.413006634408;5938.109283607493;5991.850203744767;5995.850103747442;6222.875542489034;6443.581167224115;6484.221893372825;6484.578502397518;6485.027245913151;6202.906956395567;6217.658028808459;6286.591371560834;6342.651154186564;6433.774817881352;6076.817695469143;6135.84660383494;6193.163922870355;6257.2491301044165;6342.651154186564;6304.590201636879;6340.778072767431;6342.080398342122;6346.777862774781;6386.840328991812;5842;5895.675737834289;5897.262842144732;5912.260967379078;5914.497267732291;5705.087186050212;5759.2512973313205;5844.211031510692;5853.531717462678;5856.677882716492 10:13:03 INFO - 2015-12-17 10:13:03,859 DEBUG : BROWSER_OUTPUT: |4;prototype.html;330.7932542161149;335.51518056408486;341.5798567761653;348.4581475805122;353.7435359364461;5906.970465147669;5916.970415147929;5921.940780592193;5929.110633404992;5959.910601340978;5835.562332825045;5837.678927658977;5851.180834683148;5854.736536855841;5859.150423188635;4512.887177820564;4543.250363689987;4544.454665440152;4554.407926969489;4559.977200114003;4389.209942210401;4487.416635837337;4491.595756381925;4496.325551167319;4502.077074199798;4429.46846378435;4441.267190913495;4451.62161216297;4456.086502267044;4468.843590474334;4289.378040184156;4365.410669559632;4436.0906014267;4466.240739074366;4468.151051300259;3915.5888631693547;3939.960600393988;3945.230680017398;3947.131631041166;3956.8417263309434;5547.3343198816;5550.16747487876;5558;5569.582281328917;5572.944270557324;5002.674826136289;5077.822276220315;5127.692338459704;5152.587792976553;5244.842654720326;6174.9073763893575;6177.505799536027;6220.87558248837;6222.81331560054;6224;4338.370936214231;4346.195953748535;4346.826126954918;4350.456192975878;4355.782210889474;6246.344133865924;6323.8735225295695;6376.617402955838;6412.326705695881;6425.100485931926;4446.799894004777;4465.799039043251;4466.553344665573;4466.57567531084;4472;4529.365888775574;4529.660275479352;4530.343100250511;4530.705504142286;4536.4102666653125;5195.064888320063;5196.064708352457;5203.583713302926;5203.713795741195;5210.7915683373;6093.786717464867;6219.40915613016;6222.346653601307;6222.502199824094;6230.875382492415;1589.6582234819339;1609.147152009437;1610.6215039465717;1611.806583209999;1613.5482065021813;1068.8610480637624;1071.6570697376922;1078.0620859851979;1079.2013909706761;1100.8513850630063;6038.607490513103;6043.395660434009;6053.606515576474;6057.697115144225;6061.605995610271;6450.193725784277;6463.224413070461;6472.644004579794;6473.126127972663;6474.708638111297;5924.970375148183;6037.366076561985;6043.395660434009;6052.122442245938;6053.4551890330085;3483.042163405033;3491.94762078569;3502.4571191465366;3502.877399291013;3508.245727168671;6590.373914478117;6595.70319335631;6603.405693487513;6612.735490580323;6617.86764264712;6486.610803351814;6570.934290657128;6592.24189218246;6601.504887133484;6611.834704132435;6540;6600.933990660128;6665.933340666531;6678.666066696743;6700.095487109275;6488.643124628191;6491.772787952399;6494.415502604788;6496.935030649633;6498.577592456475;6267.4045965633195;6410.487161027013;6418.101465794699;6433.131527243762;6436.259830119409;6626.237982632059;6668.199816022108;6671.766488172891;6675.599464032173;6680.098186744727;4439.4228750262255;4439.445069366329;4441.378207050951;4441.800118994588;4455.75493347869;6344.365563443619;6490.415862572391;6497.415232628897;6504.056911747864;6504.4796416285635;6292.2134733158355;6306.621602703853;6317.494600431863;6328.683565821599;6333.050042493663;6313.715882785194;6317.526185536195;6319.24169099711;6324.968375158095;6325;6061.6666083365835;6208.130861679459;6210.689465526796;6213.130161777264;6222.751089956532;6317.652529110943;6329.651869147241;6329.841753956188;6336.746530138743;6344.27040890285;6218.098375735402;6219.564630475823;6220.688965551613;6225.159603453657;6226.844328891632 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: |5;yui.html;815.6737305077968;821.092692574705;833.3125171733321;840.1850205300856;841.6465084664441;12748.490060397584;12948.676283092927;12956.48174073036;12957.611271661865;12973.805392919103;14856.18290993995;15232.39070437184;15270.08379497231;15279.847201527982;15287.464938727147;689.0147089661807;816.8407160603684;817.4604760857835;818.7420962396841;820.2044017303207;694.9027136200936;812.9796755081111;822.0341099208437;830.1325115254543;831.8003679117029;853.5262929074361;872.7730789994583;873.4279047224078;873.9868901966476;874.191372979991;708.3730898155118;712.2521352579822;863.5164307987548;870.268974061788;873.3711727556149;624.3600309682566;626.6334194496179;629.625372903122;631.6652174347557;632.3044650884037;13834.169949803045;13999;14006.859931400759;14007.719845603031;14010.719785604231;12237;12332.691682708004;12470.875291247063;12595.181313214613;12731.681707957283;10015.59937602495;10261.230407719451;10646.307989980627;10660.360378377321;10804.297720648132;3694.704423646102;3737.7757334560015;3778.6977041836585;3861.575226725058;3862;44854.32718509225;45327;45329.54670453286;45337.77331113356;45513.317300240524;9732.513374331254;9987.500624968723;10149.34029288094;10149.898501015043;10591.470426478645;18272;18277.817221827878;18287.359942402218;19028.238870445304;19040.143193556047;7881.251281128284;8013.879791803128;8104.1085480597085;8141.674333026731;8172.877406838902;4301.935470967938;4360.106178233455;4398.252297109499;4426.186190977103;4445.422095127613;2967.0802051364144;2994.086803524946;2995.5955945947453;2998.625171853518;3003.3692924485667;534.7807398966405;535.9088954877681;536.7906516458585;542.5117394345042;557.6068871445622;590.2562770908686;669.0532895952173;690.2545251128869;693.1508901595505;713.8358177619177;8200.835983280302;8344.374171937128;8516.40385173032;8596.656133754705;8673.471929536056;577.7948828166025;666.4501786026548;678.4877417549718;705.2982282628817;712.7434123715458;3763.3790424580184;3871.5741268460447;3981.6416522513114;3993.5008123984503;4017.276890159741;52302.21546676803;57976;62611;62783.37216627866;62789.37210627835;47599.28601070987;48461.5153848464;49020.26469602959;49170.75414622975;49749.75125124351;43675.56324436778;43915.12169756587;43979.34030989538;43986.56013439825;45254.54745452503;8584.91415085841;8723;8738.563071846509;8867.645294188418;8876.556172191236;33579.832100840315;33855.322893541495;33902.491462628575;34178.82910585531;35985;42487.787561061996;43326.78336608297;44142.33786493269;44458.11083778371;44467.77766111149;10565.735856603646;10940.781184376427;11047.889521104686;11102.389368584481;11484.253523520945;11591.24656897299;12045.457954392248;12065.638030859089;12434.129610927148;12760.106792524433;10909.945450272699;11106.722331941766;11493.655190344303;11631.127665425296;11788.646340609796;11202.943985280348;11380.886191137983;11573.479193436147;11816.468258928197;11874.46564904564;11209.775804484028;11377.488013039267;11755.235909665844;11787.587434439753;11817.763644727229;9054.954725226595;9222.584983675884;9253.953730231306;9463.526823658653;9464.432134071978;8688.78278043054;8880.51157186361;9513.749445687366;10067.546960386653;10085.647002354883 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,819 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: __startTimestamp1450375983847__endTimestamp 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,861 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5283.53 Median:5509.64 stddev:577.62 (10.5%) stddev-sans-first:553.80 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: Values: 3116.1 3167.7 3197.9 3202.3 3231.0 5174.9 5241.3 5267.6 5281.2 5332.0 5164.1 5166.0 5268.2 5268.4 5277.4 4790.8 4794.8 4808.4 4857.1 4926.9 4915.4 4938.4 4959.2 4985.0 4999.8 4710.2 4723.2 4831.8 4832.1 4958.8 4819.0 4838.7 4842.0 4846.6 4848.1 4800.2 4804.6 4807.9 4835.3 4842.1 4999.8 5097.8 5112.0 5187.6 5204.3 5773.0 5775.4 5780.0 5782.2 5797.0 5615.4 5791.6 5796.4 5800.1 5811.4 5308.7 5345.1 5355.4 5417.2 5420.3 5738.1 5738.9 5747.7 5771.0 5798.1 5569.6 5571.4 5574.6 5575.4 5575.9 5529.3 5550.1 5601.9 5656.7 5658.7 5689.2 5705.2 5715.9 5717.3 5729.8 5710.3 5716.9 5723.4 5723.4 5727.4 4286.5 4442.9 4447.4 4455.4 4486.1 3876.6 3888.9 3914.4 3942.4 3959.3 5345.0 5347.8 5352.0 5361.8 5381.0 5503.8 5618.8 5662.2 5663.2 5667.6 5209.3 5255.5 5284.8 5321.4 5366.1 4992.4 4996.2 5005.3 5016.0 5037.7 5664.5 5768.9 5815.0 5824.9 5831.3 5562.7 5580.2 5595.1 5699.5 5702.5 5825.5 5880.9 5894.8 5905.4 5910.2 5684.3 5689.0 5691.6 5693.8 5698.0 5509.2 5625.7 5632.9 5646.6 5754.8 5606.2 5613.5 5616.7 5621.9 5623.9 5609.2 5610.3 5611.2 5614.2 5619.4 5369.6 5408.1 5411.7 5460.9 5517.5 5589.9 5600.3 5605.6 5620.9 5655.1 5505.5 5509.6 5511.6 5516.8 5518.5 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,862 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47472.83 Median:35495.65 stddev:77830.56 (219.3%) stddev-sans-first:77969.20 10:13:03 INFO - 2015-12-17 10:13:03,863 DEBUG : BROWSER_OUTPUT: Values: 564.4 601.4 604.4 609.2 610.1 30776.2 31487.7 39059.6 39537.6 39588.2 43631.8 44151.0 44170.8 44193.3 44503.0 7421.1 8739.6 8905.9 8908.3 8916.6 7659.1 7762.4 7762.8 10426.3 10751.6 10420.2 10493.9 10499.9 10505.1 10511.3 10401.0 10494.6 10494.6 10495.1 10500.4 7741.9 7973.2 7975.8 10482.1 10792.4 40122.0 40385.2 40488.6 40598.8 40620.4 3262.8 3320.2 3402.9 3465.9 3820.0 30046.5 30396.8 30549.7 30647.5 31301.0 3709.0 3755.2 3848.6 3886.2 3948.6 266718.0 267696.0 268199.0 268369.0 268573.0 59890.7 60070.4 60077.5 60330.7 60593.4 63962.4 64385.0 64479.7 64520.0 64546.0 13253.8 13588.0 13590.5 13595.2 13607.5 10083.4 10125.0 10125.6 10130.9 10145.1 19632.8 20325.7 20401.3 20438.0 20471.5 7648.8 7762.9 7782.5 7799.1 7802.1 932.2 989.8 1065.9 1081.9 1087.5 28830.3 28881.0 28911.9 29333.7 29404.1 953.7 1016.4 1019.4 1030.0 1061.0 14038.9 14392.0 15354.4 15653.5 15868.0 332431.0 333701.0 334195.0 334880.0 335616.0 38562.0 38790.4 52161.0 53825.0 53974.0 1342.2 1352.1 1366.5 1393.1 1397.4 29444.9 32206.5 34241.5 34248.3 34261.7 38239.4 38853.0 43385.3 44177.6 44234.8 285603.0 285897.0 289664.0 290038.0 290498.0 41253.2 41617.4 41979.8 42081.2 42177.8 44789.3 44846.6 45205.8 45451.3 46209.0 37888.6 38830.0 39150.0 42541.0 43093.4 38842.0 38934.0 39535.3 39757.4 39897.2 37348.6 37657.4 38307.0 38431.6 38504.8 33562.0 33616.0 35043.8 35311.6 35679.6 26273.3 26706.3 26728.3 26729.7 26822.9 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29132.83 Median:4312.55 stddev:92238.82 (2138.8%) stddev-sans-first:92472.27 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: Values: 359.7 362.8 363.8 364.9 367.5 13799.8 14095.9 16165.8 16712.6 17343.7 14262.9 14375.7 16186.7 16581.2 16709.5 4792.9 5534.9 5550.8 5552.9 5553.9 4046.8 4149.6 5396.9 5541.9 5550.4 5819.2 5962.3 5964.0 5982.9 5989.4 5931.4 6079.4 6117.8 6166.1 6207.1 5334.4 5339.2 5339.2 5341.8 5354.7 20790.8 21070.3 21274.8 21294.4 21445.9 2233.9 2240.1 2297.0 2328.4 2329.8 12865.8 12890.3 12906.5 13275.2 13429.7 1682.0 1788.1 1807.4 1819.8 1824.0 50856.0 54928.0 58191.4 61529.0 62534.7 10118.1 10191.7 10242.8 10301.9 10304.4 13343.4 13380.0 13402.1 13408.9 13411.3 7174.9 7176.1 7202.5 7222.2 7230.3 6700.2 6731.4 6746.8 6750.2 6793.8 4245.9 4264.6 4360.5 4449.6 4450.6 1739.1 1760.9 1762.4 1786.0 1792.0 458.4 981.4 994.3 1018.8 1030.6 10433.9 10678.8 11010.3 11098.3 11138.9 440.7 449.8 453.6 456.6 457.4 5861.4 6160.1 6172.4 6188.7 6200.2 549095.0 562359.0 563889.0 565886.2 577803.0 83836.0 84889.0 85149.6 85746.0 86325.0 471.0 471.4 472.3 472.8 476.3 9755.9 9783.8 9865.5 9895.7 9955.7 32011.8 32345.7 32986.0 33399.5 33556.7 46682.3 47730.8 48270.0 49471.3 50329.2 9458.8 9821.4 9944.4 9968.5 9979.0 11983.7 12009.8 15799.6 17573.1 18122.6 11836.1 11837.3 12577.3 14030.6 14560.5 13753.4 14029.6 14248.5 14263.8 14338.0 14014.6 14046.2 14555.5 14654.2 14660.8 9533.2 9598.1 9642.1 9893.5 9911.3 14356.6 14388.9 14615.5 15034.9 15071.5 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4848.06 Median:5855.10 stddev:2426.23 (41.4%) stddev-sans-first:2417.12 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: Values: 1151.9 1638.6 1682.0 1704.7 1705.9 6095.3 6425.5 6742.4 6754.3 6765.7 6696.8 6826.7 6826.7 6844.2 6847.9 2060.4 2088.2 2093.7 2096.1 2097.5 2177.1 2192.5 2197.9 2198.6 2203.8 2086.0 2105.2 2116.4 2129.8 2133.4 2140.1 2163.8 2165.5 2166.8 2175.3 2091.2 2094.6 2104.5 2112.0 2112.2 6645.3 6711.0 6711.5 6722.3 6725.3 4201.7 4239.6 4255.6 4272.7 4283.1 2889.8 2913.6 2936.8 2938.2 2946.3 965.3 1001.5 1015.3 1020.8 1021.1 8968.1 8977.2 8985.5 8987.4 9020.8 3872.7 3885.4 3888.7 3906.2 3910.5 8012.4 8089.6 8104.5 8119.6 8119.8 4283.3 4309.8 4310.6 4317.9 4320.0 3558.1 3637.4 3851.9 3945.4 3958.7 3235.5 3288.4 3309.0 3427.9 3436.6 1089.0 1115.8 1123.6 1129.6 1136.1 1218.5 1228.8 1244.3 1287.5 1289.6 6461.3 6549.6 6568.0 6605.1 6608.3 459.7 465.6 467.4 467.9 472.9 5193.5 5249.6 5250.6 5251.4 5265.7 7926.2 8019.3 8071.5 8095.5 8244.5 7929.3 7992.4 8085.7 8098.8 8102.9 6521.9 6536.6 6681.9 6695.9 6696.1 6345.7 6346.9 6347.6 6356.9 6366.6 7242.5 7312.6 7549.2 7579.2 7608.8 7555.7 7599.3 7764.7 7774.0 7776.4 5433.8 5590.4 5938.1 5991.9 5995.9 6222.9 6443.6 6484.2 6484.6 6485.0 6202.9 6217.7 6286.6 6342.7 6433.8 6076.8 6135.8 6193.2 6257.2 6342.7 6304.6 6340.8 6342.1 6346.8 6386.8 5842.0 5895.7 5897.3 5912.3 5914.5 5705.1 5759.3 5844.2 5853.5 5856.7 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,864 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5230.17 Median:5998.64 stddev:1571.53 (26.2%) stddev-sans-first:1532.31 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: Values: 330.8 335.5 341.6 348.5 353.7 5907.0 5917.0 5921.9 5929.1 5959.9 5835.6 5837.7 5851.2 5854.7 5859.2 4512.9 4543.3 4544.5 4554.4 4560.0 4389.2 4487.4 4491.6 4496.3 4502.1 4429.5 4441.3 4451.6 4456.1 4468.8 4289.4 4365.4 4436.1 4466.2 4468.2 3915.6 3940.0 3945.2 3947.1 3956.8 5547.3 5550.2 5558.0 5569.6 5572.9 5002.7 5077.8 5127.7 5152.6 5244.8 6174.9 6177.5 6220.9 6222.8 6224.0 4338.4 4346.2 4346.8 4350.5 4355.8 6246.3 6323.9 6376.6 6412.3 6425.1 4446.8 4465.8 4466.6 4466.6 4472.0 4529.4 4529.7 4530.3 4530.7 4536.4 5195.1 5196.1 5203.6 5203.7 5210.8 6093.8 6219.4 6222.3 6222.5 6230.9 1589.7 1609.1 1610.6 1611.8 1613.5 1068.9 1071.7 1078.1 1079.2 1100.9 6038.6 6043.4 6053.6 6057.7 6061.6 6450.2 6463.2 6472.6 6473.1 6474.7 5925.0 6037.4 6043.4 6052.1 6053.5 3483.0 3491.9 3502.5 3502.9 3508.2 6590.4 6595.7 6603.4 6612.7 6617.9 6486.6 6570.9 6592.2 6601.5 6611.8 6540.0 6600.9 6665.9 6678.7 6700.1 6488.6 6491.8 6494.4 6496.9 6498.6 6267.4 6410.5 6418.1 6433.1 6436.3 6626.2 6668.2 6671.8 6675.6 6680.1 4439.4 4439.4 4441.4 4441.8 4455.8 6344.4 6490.4 6497.4 6504.1 6504.5 6292.2 6306.6 6317.5 6328.7 6333.1 6313.7 6317.5 6319.2 6325.0 6325.0 6061.7 6208.1 6210.7 6213.1 6222.8 6317.7 6329.7 6329.8 6336.7 6344.3 6218.1 6219.6 6220.7 6225.2 6226.8 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13700.23 Median:4435.80 stddev:15612.55 (352.0%) stddev-sans-first:15626.36 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: Values: 815.7 821.1 833.3 840.2 841.6 12748.5 12948.7 12956.5 12957.6 12973.8 14856.2 15232.4 15270.1 15279.8 15287.5 689.0 816.8 817.5 818.7 820.2 694.9 813.0 822.0 830.1 831.8 853.5 872.8 873.4 874.0 874.2 708.4 712.3 863.5 870.3 873.4 624.4 626.6 629.6 631.7 632.3 13834.2 13999.0 14006.9 14007.7 14010.7 12237.0 12332.7 12470.9 12595.2 12731.7 10015.6 10261.2 10646.3 10660.4 10804.3 3694.7 3737.8 3778.7 3861.6 3862.0 44854.3 45327.0 45329.5 45337.8 45513.3 9732.5 9987.5 10149.3 10149.9 10591.5 18272.0 18277.8 18287.4 19028.2 19040.1 7881.3 8013.9 8104.1 8141.7 8172.9 4301.9 4360.1 4398.3 4426.2 4445.4 2967.1 2994.1 2995.6 2998.6 3003.4 534.8 535.9 536.8 542.5 557.6 590.3 669.1 690.3 693.2 713.8 8200.8 8344.4 8516.4 8596.7 8673.5 577.8 666.5 678.5 705.3 712.7 3763.4 3871.6 3981.6 3993.5 4017.3 52302.2 57976.0 62611.0 62783.4 62789.4 47599.3 48461.5 49020.3 49170.8 49749.8 43675.6 43915.1 43979.3 43986.6 45254.5 8584.9 8723.0 8738.6 8867.6 8876.6 33579.8 33855.3 33902.5 34178.8 35985.0 42487.8 43326.8 44142.3 44458.1 44467.8 10565.7 10940.8 11047.9 11102.4 11484.3 11591.2 12045.5 12065.6 12434.1 12760.1 10909.9 11106.7 11493.7 11631.1 11788.6 11202.9 11380.9 11573.5 11816.5 11874.5 11209.8 11377.5 11755.2 11787.6 11817.8 9055.0 9222.6 9254.0 9463.5 9464.4 8688.8 8880.5 9513.7 10067.5 10085.6 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:13:03 INFO - 2015-12-17 10:13:03,866 DEBUG : BROWSER_OUTPUT: 10:13:04 INFO - 2015-12-17 10:13:04,532 INFO : Browser exited with error code: 0 10:13:04 INFO - 2015-12-17 10:13:04,550 INFO : Completed test dromaeo_css (00:18:32) 10:13:04 INFO - 2015-12-17 10:13:04,550 INFO : Starting test kraken 10:13:04 INFO - 2015-12-17 10:13:04,551 DEBUG : operating with platform_type : linux_ 10:13:04 INFO - 2015-12-17 10:13:04,551 INFO : Initialising browser for kraken test... 10:13:04 INFO - 2015-12-17 10:13:04,557 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoOrHoH/profile http://localhost:48875/getInfo.html 10:13:08 INFO - 2015-12-17 10:13:08,151 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:13:08 INFO - 2015-12-17 10:13:08,152 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:13:08 INFO - 2015-12-17 10:13:08,152 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:13:08 INFO - 2015-12-17 10:13:08,152 DEBUG : BROWSER_OUTPUT: __metrics 10:13:08 INFO - 2015-12-17 10:13:08,233 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:13:08 INFO - 2015-12-17 10:13:08,289 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:13:09 INFO - 2015-12-17 10:13:09,378 INFO : Browser initialized. 10:13:09 INFO - 2015-12-17 10:13:09,378 INFO : Running cycle 1/1 for kraken test... 10:13:09 INFO - 2015-12-17 10:13:09,378 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoOrHoH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:13:21 INFO - 2015-12-17 10:13:21,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 149868544 10:13:21 INFO - 2015-12-17 10:13:21,049 DEBUG : BROWSER_OUTPUT: 10:13:23 INFO - 2015-12-17 10:13:23,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48875/tests/kraken/driver.html?testName=audio-beat-detection) 10:13:24 INFO - 2015-12-17 10:13:24,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 185757696 10:13:24 INFO - 2015-12-17 10:13:24,113 DEBUG : BROWSER_OUTPUT: 10:13:26 INFO - 2015-12-17 10:13:26,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48875/tests/kraken/driver.html?testName=audio-dft) 10:13:27 INFO - 2015-12-17 10:13:27,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 176005120 10:13:27 INFO - 2015-12-17 10:13:27,320 DEBUG : BROWSER_OUTPUT: 10:13:30 INFO - 2015-12-17 10:13:30,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48875/tests/kraken/driver.html?testName=audio-fft) 10:13:30 INFO - 2015-12-17 10:13:30,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 160501760 10:13:30 INFO - 2015-12-17 10:13:30,793 DEBUG : BROWSER_OUTPUT: 10:13:32 INFO - 2015-12-17 10:13:32,771 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48875/tests/kraken/driver.html?testName=audio-oscillator) 10:13:33 INFO - 2015-12-17 10:13:33,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 163147776 10:13:33 INFO - 2015-12-17 10:13:33,341 DEBUG : BROWSER_OUTPUT: 10:13:35 INFO - 2015-12-17 10:13:35,554 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48875/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:13:36 INFO - 2015-12-17 10:13:36,120 DEBUG : BROWSER_OUTPUT: RSS: Main: 163594240 10:13:36 INFO - 2015-12-17 10:13:36,120 DEBUG : BROWSER_OUTPUT: 10:13:38 INFO - 2015-12-17 10:13:38,978 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48875/tests/kraken/driver.html?testName=imaging-darkroom) 10:13:39 INFO - 2015-12-17 10:13:39,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 163815424 10:13:39 INFO - 2015-12-17 10:13:39,545 DEBUG : BROWSER_OUTPUT: 10:13:42 INFO - 2015-12-17 10:13:42,885 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48875/tests/kraken/driver.html?testName=imaging-desaturate) 10:13:43 INFO - 2015-12-17 10:13:43,458 DEBUG : BROWSER_OUTPUT: RSS: Main: 164794368 10:13:43 INFO - 2015-12-17 10:13:43,459 DEBUG : BROWSER_OUTPUT: 10:13:46 INFO - 2015-12-17 10:13:46,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48875/tests/kraken/driver.html?testName=json-parse-financial) 10:13:46 INFO - 2015-12-17 10:13:46,740 DEBUG : BROWSER_OUTPUT: RSS: Main: 165711872 10:13:46 INFO - 2015-12-17 10:13:46,740 DEBUG : BROWSER_OUTPUT: 10:13:48 INFO - 2015-12-17 10:13:48,313 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48875/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:13:48 INFO - 2015-12-17 10:13:48,874 DEBUG : BROWSER_OUTPUT: RSS: Main: 176549888 10:13:48 INFO - 2015-12-17 10:13:48,874 DEBUG : BROWSER_OUTPUT: 10:13:50 INFO - 2015-12-17 10:13:50,676 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:13:51 INFO - 2015-12-17 10:13:51,278 DEBUG : BROWSER_OUTPUT: RSS: Main: 175104000 10:13:51 INFO - 2015-12-17 10:13:51,278 DEBUG : BROWSER_OUTPUT: 10:13:53 INFO - 2015-12-17 10:13:53,329 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:13:53 INFO - 2015-12-17 10:13:53,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 188375040 10:13:53 INFO - 2015-12-17 10:13:53,899 DEBUG : BROWSER_OUTPUT: 10:13:56 INFO - 2015-12-17 10:13:56,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:56 INFO - 2015-12-17 10:13:56,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 196386816 10:13:56 INFO - 2015-12-17 10:13:56,923 DEBUG : BROWSER_OUTPUT: 10:13:59 INFO - 2015-12-17 10:13:59,471 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:14:00 INFO - 2015-12-17 10:14:00,044 DEBUG : BROWSER_OUTPUT: RSS: Main: 192241664 10:14:00 INFO - 2015-12-17 10:14:00,045 DEBUG : BROWSER_OUTPUT: 10:14:01 INFO - 2015-12-17 10:14:01,739 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48875/tests/kraken/driver.html?testName=ai-astar) 10:14:01 INFO - 2015-12-17 10:14:01,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 250986496 10:14:01 INFO - 2015-12-17 10:14:01,997 DEBUG : BROWSER_OUTPUT: 10:14:01 INFO - 2015-12-17 10:14:01,997 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:14:01 INFO - 2015-12-17 10:14:01,997 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:14:01 INFO - 2015-12-17 10:14:01,997 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:14:01 INFO - 2015-12-17 10:14:01,998 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:14:01 INFO - 2015-12-17 10:14:01,998 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;114;107;111;113;112;101;110;113;117 10:14:01 INFO - 2015-12-17 10:14:01,998 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;153;138;156;170;124;160;145;124;182;131 10:14:02 INFO - 2015-12-17 10:14:01,998 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;162;160;159;161;161;164;160;161 10:14:02 INFO - 2015-12-17 10:14:01,999 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;82;92;71;77;71;123;93;73 10:14:02 INFO - 2015-12-17 10:14:01,999 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;147;93;94;93;139;95;93;146 10:14:02 INFO - 2015-12-17 10:14:01,999 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:14:02 INFO - 2015-12-17 10:14:01,999 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;144;145;145;145;145;144;145;144 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;96;95;95;94 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;58;58;59;58;59;67;56;57 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;68;67;67;68;68;67;68;68;68 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;85;85;84;84;85;83;84;92;85 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;118;121;121;130;123;130;118;130;115 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;139;150;149;150;139;148;148;148;141 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;68;65;68;68;66;67;67;65 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:14:02 INFO - 2015-12-17 10:14:02,000 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,947 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376041991__endTimestamp 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,001 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.00 Median:112.50 stddev:4.37 (3.9%) stddev-sans-first:4.62 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: Values: 112.0 114.0 107.0 111.0 113.0 112.0 101.0 110.0 113.0 117.0 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.30 Median:154.50 stddev:19.50 (12.6%) stddev-sans-first:20.61 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: Values: 153.0 138.0 156.0 170.0 124.0 160.0 145.0 124.0 182.0 131.0 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.37 (0.9%) stddev-sans-first:1.45 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 162.0 160.0 159.0 161.0 161.0 164.0 160.0 161.0 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:16.63 (22.2%) stddev-sans-first:17.12 10:14:02 INFO - 2015-12-17 10:14:02,003 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 82.0 92.0 71.0 77.0 71.0 123.0 93.0 73.0 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.80 Median:93.50 stddev:24.38 (26.1%) stddev-sans-first:25.27 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 147.0 93.0 94.0 93.0 139.0 95.0 93.0 146.0 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.70 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 144.0 145.0 145.0 145.0 145.0 144.0 145.0 144.0 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,004 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.00 stddev:3.01 (5.2%) stddev-sans-first:3.18 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 58.0 58.0 59.0 58.0 59.0 67.0 56.0 57.0 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 67.0 67.0 68.0 68.0 67.0 68.0 68.0 68.0 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:2.69 (3.2%) stddev-sans-first:2.64 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: Values: 82.0 85.0 85.0 84.0 84.0 85.0 83.0 84.0 92.0 85.0 10:14:02 INFO - 2015-12-17 10:14:02,005 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:122.00 stddev:6.01 (4.9%) stddev-sans-first:5.80 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: Values: 115.0 118.0 121.0 121.0 130.0 123.0 130.0 118.0 130.0 115.0 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.20 Median:148.00 stddev:4.78 (3.2%) stddev-sans-first:4.68 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: Values: 140.0 139.0 150.0 149.0 150.0 139.0 148.0 148.0 148.0 141.0 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 68.0 65.0 68.0 68.0 66.0 67.0 67.0 65.0 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:14:02 INFO - 2015-12-17 10:14:02,006 DEBUG : BROWSER_OUTPUT: 10:14:02 INFO - 2015-12-17 10:14:02,836 INFO : Browser exited with error code: 0 10:14:02 INFO - 2015-12-17 10:14:02,889 INFO : Completed test kraken (00:00:58) 10:14:02 INFO - 2015-12-17 10:14:02,889 INFO : Starting test v8_7 10:14:02 INFO - 2015-12-17 10:14:02,889 DEBUG : operating with platform_type : linux_ 10:14:02 INFO - 2015-12-17 10:14:02,890 INFO : Initialising browser for v8_7 test... 10:14:02 INFO - 2015-12-17 10:14:02,896 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnkMN3Y/profile http://localhost:48875/getInfo.html 10:14:06 INFO - 2015-12-17 10:14:06,776 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:14:06 INFO - 2015-12-17 10:14:06,776 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:14:06 INFO - 2015-12-17 10:14:06,776 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:14:06 INFO - 2015-12-17 10:14:06,776 DEBUG : BROWSER_OUTPUT: __metrics 10:14:06 INFO - 2015-12-17 10:14:06,893 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:14:06 INFO - 2015-12-17 10:14:06,948 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:14:07 INFO - 2015-12-17 10:14:07,716 INFO : Browser initialized. 10:14:07 INFO - 2015-12-17 10:14:07,716 INFO : Running cycle 1/1 for v8_7 test... 10:14:07 INFO - 2015-12-17 10:14:07,716 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpnkMN3Y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:14:19 INFO - 2015-12-17 10:14:19,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 150056960 10:14:19 INFO - 2015-12-17 10:14:19,528 DEBUG : BROWSER_OUTPUT: 10:14:40 INFO - 2015-12-17 10:14:40,895 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:48875/tests/v8_7/run.html (next: http://localhost:48875/tests/v8_7/run.html) 10:14:41 INFO - 2015-12-17 10:14:41,145 DEBUG : BROWSER_OUTPUT: RSS: Main: 575483904 10:14:41 INFO - 2015-12-17 10:14:41,145 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,145 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:14:41 INFO - 2015-12-17 10:14:41,145 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |0;Richards;165.56291390728478 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.96977968414896 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.68794326241135 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5112.244897959184 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2002 10:14:41 INFO - 2015-12-17 10:14:41,146 DEBUG : BROWSER_OUTPUT: |5;Earley;769.2307692307693 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: |6;Boyer;12268.292682926829 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: |7;RegExp;25300 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: |8;Splay;621.5040397762585 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:14:41 INFO - 2015-12-17 10:14:41,147 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376081144__endTimestamp 10:14:41 INFO - 2015-12-17 10:14:41,148 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,148 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:14:41 INFO - 2015-12-17 10:14:41,148 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:14:41 INFO - 2015-12-17 10:14:41,148 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,148 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.56 Median:165.56 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,149 DEBUG : BROWSER_OUTPUT: Values: 165.6 10:14:41 INFO - 2015-12-17 10:14:41,149 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,149 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.97 Median:194.97 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,149 DEBUG : BROWSER_OUTPUT: Values: 195.0 10:14:41 INFO - 2015-12-17 10:14:41,149 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,150 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.69 Median:283.69 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,150 DEBUG : BROWSER_OUTPUT: Values: 283.7 10:14:41 INFO - 2015-12-17 10:14:41,150 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,150 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5112.24 Median:5112.24 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,150 DEBUG : BROWSER_OUTPUT: Values: 5112.2 10:14:41 INFO - 2015-12-17 10:14:41,151 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,151 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2002.00 Median:2002.00 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,151 DEBUG : BROWSER_OUTPUT: Values: 2002.0 10:14:41 INFO - 2015-12-17 10:14:41,151 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,151 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:769.23 Median:769.23 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,152 DEBUG : BROWSER_OUTPUT: Values: 769.2 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12268.29 Median:12268.29 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: Values: 12268.3 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25300.00 Median:25300.00 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: Values: 25300.0 10:14:41 INFO - 2015-12-17 10:14:41,153 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:621.50 Median:621.50 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: Values: 621.5 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: Values: 4835.7 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:14:41 INFO - 2015-12-17 10:14:41,154 DEBUG : BROWSER_OUTPUT: 10:14:41 INFO - 2015-12-17 10:14:41,944 INFO : Browser exited with error code: 0 10:14:41 INFO - 2015-12-17 10:14:41,961 INFO : Completed test v8_7 (00:00:39) 10:14:42 INFO - 2015-12-17 10:14:42,173 INFO : Completed test suite (00:20:10) 10:14:42 INFO - 2015-12-17 10:14:42,174 DEBUG : Working with test: dromaeo_css 10:14:42 INFO - 2015-12-17 10:14:42,174 DEBUG : Generating results file: dromaeo_css 10:14:42 INFO - 2015-12-17 10:14:42,175 DEBUG : Working with test: kraken 10:14:42 INFO - 2015-12-17 10:14:42,175 DEBUG : Generating results file: kraken 10:14:42 INFO - 2015-12-17 10:14:42,175 INFO : javascript benchmark 10:14:42 INFO - 2015-12-17 10:14:42,175 DEBUG : Working with test: v8_7 10:14:42 INFO - 2015-12-17 10:14:42,175 DEBUG : Generating results file: v8_7 10:14:42 INFO - 2015-12-17 10:14:42,176 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:14:42 INFO - 2015-12-17 10:14:42,227 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 10:14:42 INFO - 2015-12-17 10:14:42,227 DEBUG : process_Request line: dromaeo_css 6787.36 graph.html#tests=[[72,1,35]] 10:14:42 INFO - 2015-12-17 10:14:42,227 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:14:42 INFO - 2015-12-17 10:14:42,258 DEBUG : process_Request line: kraken 1505.70 graph.html#tests=[[232,1,35]] 10:14:42 INFO - 2015-12-17 10:14:42,258 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:14:42 INFO - 2015-12-17 10:14:42,301 DEBUG : process_Request line: v8_7 19796.79 graph.html#tests=[[230,1,35]] 10:14:42 INFO - 2015-12-17 10:14:42,303 INFO : javascript benchmark 10:14:42 INFO - 2015-12-17 10:14:42,306 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3116.0807561769284, 3167.6673949235314, 3197.920051998701, 3202.2954949911014, 3230.951535726964, 5174.922376164361, 5241.2924255225535, 5267.631265811395, 5281.201331495643, 5332.040232758102, 5164.147915593922, 5165.974170129153, 5268.157094864822, 5268.420473747885, 5277.445868183833, 4790.832320868771, 4794.784234709437, 4808.3508726322025, 4857.052874689437, 4926.876828079308, 4915.410150781893, 4938.430919044464, 4959.231319145538, 4985.0, 4999.7500124993785, 4710.246360582325, 4723.244280915037, 4831.758412079382, 4832.057748738997, 4958.801647934079, 4819.0, 4838.7338696372035, 4842.031593681243, 4846.6365022623095, 4848.0788650156355, 4800.231962885921, 4804.567588917014, 4807.879803004953, 4835.298881662175, 4842.12841688496, 4999.825006124768, 5097.770600322994, 5112.0, 5187.584993200535, 5204.271402003723, 5773.0, 5775.3935836736955, 5780.0, 5782.161586569966, 5797.043487824504, 5615.382307946123, 5791.594588378815, 5796.362400135981, 5800.129980502916, 5811.4479124483105, 5308.681479111227, 5345.064613692588, 5355.357357117132, 5417.241586177938, 5420.268263784417, 5738.081906894877, 5738.942610573924, 5747.683877386784, 5770.971145144247, 5798.1302804579655, 5569.610127291051, 5571.4150014248735, 5574.554035677136, 5575.4424557543925, 5575.916361254584, 5529.253550770594, 5550.056490396642, 5601.915971260434, 5656.745446454919, 5658.688772117573, 5689.203511508392, 5705.2012718219485, 5715.914261286084, 5717.342505611825, 5729.828105156852, 5710.343310519261, 5716.885662286731, 5723.370429252779, 5723.427657234243, 5727.427257274239, 4286.507051689034, 4442.933355999663, 4447.421835161408, 4455.420795296655, 4486.12520558488, 3876.5929577393968, 3888.922221555553, 3914.373700207953, 3942.42834788954, 3959.3269144245546, 5345.037893179265, 5347.81282655113, 5351.956368508104, 5361.8123365682795, 5380.973095134499, 5503.752331145108, 5618.775248990077, 5662.17898404729, 5663.235463212496, 5667.631603945731, 5209.348831396075, 5255.474452554714, 5284.841454756286, 5321.36143662763, 5366.060939335601, 4992.350994370709, 4996.150654388763, 5005.27423523587, 5016.0, 5037.697738135724, 5664.46187612168, 5768.884622307614, 5815.04051831443, 5824.912626310609, 5831.329397119385, 5562.721863906708, 5580.218769372373, 5595.104783234663, 5699.544036477155, 5702.543796496353, 5825.533957283492, 5880.852978675567, 5894.823155305347, 5905.379935106924, 5910.202122713378, 5684.261046063985, 5688.971555142198, 5691.601587888808, 5693.772249109989, 5698.0028495012375, 5509.228707980806, 5625.746841392229, 5632.9436705632415, 5646.604737668488, 5754.769809207585, 5606.159076138449, 5613.494785469327, 5616.7191640416995, 5621.8594535136945, 5623.8594035149445, 5609.21470994053, 5610.326760788732, 5611.158326251095, 5614.157876318585, 5619.438056194348, 5369.597280203922, 5408.053590621704, 5411.729413529229, 5460.890782184255, 5517.531009864046, 5589.944100558942, 5600.327960644749, 5605.635633683797, 5620.9156862647915, 5655.095184770417, 5505.504504594445, 5509.641873278224, 5511.641743286674, 5516.779328826962, 5518.475744804237], "unit": "score", "name": "dojo.html", "value": 5246.474325018895}, {"lowerIsBetter": false, "replicates": [564.4177886170767, 601.419630056995, 604.356360476093, 609.2141137932068, 610.0970563565919, 30776.230594235127, 31487.68512314876, 39059.60940390599, 39537.60462395375, 39588.20823583526, 43631.78184109075, 44151.0, 44170.7791461043, 44193.33709994353, 44503.0, 7421.0723659542555, 8739.606717697703, 8905.910940890606, 8908.331875109361, 8916.643334266653, 7659.119201291867, 7762.4178186635945, 7762.8447431051345, 10426.322289051226, 10751.569937202503, 10420.218483613722, 10493.895061049407, 10499.895001050007, 10505.107065899409, 10511.316764410327, 10401.0, 10494.580216791319, 10494.580216791319, 10495.055445009948, 10500.422476763775, 7741.922580774177, 7973.1628179040945, 7975.7607271781935, 10482.109020733247, 10792.406417647026, 40122.0, 40385.19229615421, 40488.595114048774, 40598.79700601478, 40620.39069413961, 3262.804231746079, 3320.2197483591335, 3402.931941361184, 3465.9133521661906, 3819.9618003819883, 30046.54930176049, 30396.848015760002, 30549.69450305513, 30647.540286895714, 31301.0, 3709.0, 3755.1738617504107, 3848.615138486157, 3886.2033283177166, 3948.5853985331414, 266718.0, 267696.0, 268199.0, 268369.0, 268573.0, 59890.70054649786, 60070.39929600735, 60077.498062547915, 60330.69834650886, 60593.394066058776, 63962.36037639657, 64385.0, 64479.67760161263, 64520.0, 64546.0, 13253.801192982113, 13588.048836581343, 13590.524331648543, 13595.184288942695, 13607.523736669367, 10083.437067254587, 10125.0, 10125.645602404027, 10130.898691012995, 10145.137663298554, 19632.803671963382, 20325.695114573293, 20401.285954991505, 20438.0, 20471.4882127948, 7648.770536883902, 7762.874383214402, 7782.525048097366, 7799.1031031430975, 7802.141764405911, 932.244881645869, 989.7921436498272, 1065.898739619735, 1081.874850155829, 1087.456271864068, 28830.279243018675, 28881.0, 28911.855440722662, 29333.706662933524, 29404.11787646374, 953.6757502449201, 1016.3800081950004, 1019.3628981886321, 1030.0429184549266, 1060.9734756631146, 14038.929805351112, 14392.0, 15354.385824567116, 15653.452129175424, 15868.0, 332431.0, 333701.0, 334195.0, 334880.0, 335616.0, 38562.0, 38790.41814372787, 52161.0, 53825.0, 53974.0, 1342.1879762743595, 1352.1413902172192, 1366.5285476510587, 1393.0596847128147, 1397.4410235905923, 29444.852775735984, 32206.516902246018, 34241.486377703855, 34248.315033699684, 34261.65738342684, 38239.426408603336, 38853.0, 43385.349219762364, 44177.55822441863, 44234.77882610695, 285603.0, 285897.0, 289664.0, 290038.0, 290498.0, 41253.1749365017, 41617.37573936333, 41979.7901010493, 42081.15837683168, 42177.78911105425, 44789.32816007828, 44846.551534484235, 45205.773971131246, 45451.31823022591, 46209.0, 37888.62111378851, 38830.0, 39150.0, 42541.0, 43093.35359969666, 38842.0, 38934.0, 39535.32300717734, 39757.403638946016, 39897.202055958136, 37348.6265137356, 37657.43513847349, 38307.0, 38431.61568384281, 38504.80747596244, 33562.0, 33616.0, 35043.82478087593, 35311.64688353083, 35679.64320356763, 26273.343166420993, 26706.287299110703, 26728.331791704586, 26729.732702672725, 26822.865885671228], "unit": "score", "name": "ext.html", "value": 19006.41920200799}, {"lowerIsBetter": false, "replicates": [359.7104331013535, 362.82947014902993, 363.83809204903827, 364.87411842914196, 367.54424513603124, 13799.793003104962, 14095.859041409583, 16165.83834161658, 16712.582185445353, 17343.739843902335, 14262.928685356559, 14375.712485750279, 16186.676266474664, 16581.17094145291, 16709.49871503854, 4792.928106078403, 5534.916976245359, 5550.833474995757, 5552.888942221153, 5553.861153471175, 4046.7774272415004, 4149.585041495857, 5396.892062158754, 5541.889162216753, 5550.4449555044575, 5819.243498345209, 5962.284525856881, 5963.970180149093, 5982.8803423931495, 5989.371116032819, 5931.377205393458, 6079.358573185259, 6117.755289788403, 6166.07508873668, 6207.068939659065, 5334.359876814807, 5339.172428273624, 5339.225812257239, 5341.813036543703, 5354.705491197982, 20790.79209207904, 21070.262540811153, 21274.787252127437, 21294.36116916495, 21445.892770536047, 2233.910643574255, 2240.148743477468, 2297.0122847175708, 2328.4062564046058, 2329.813614910803, 12865.80701289472, 12890.291033993126, 12906.54827081057, 13275.2460227159, 13429.66425839352, 1681.9663606727797, 1788.1238193285196, 1807.430659342303, 1819.7816262048507, 1823.9908800455912, 50856.0, 54928.0, 58191.418085819445, 61528.99999999955, 62534.68732656353, 10118.089371956412, 10191.694249172537, 10242.795144097076, 10301.9484902575, 10304.381737095799, 13343.399547020408, 13380.0, 13402.128861623964, 13408.932955335162, 13411.329433528284, 7174.928250717426, 7176.138863336433, 7202.459815513857, 7222.205557388683, 7230.313120253568, 6700.162479690039, 6731.427828634523, 6746.797596072126, 6750.189977202767, 6793.830154246183, 4245.87262382129, 4264.594863487963, 4360.49854266757, 4449.555044495524, 4450.599446049871, 1739.113052343314, 1760.9119544023004, 1762.4448298785842, 1785.9553511162326, 1791.9552011199823, 458.36516424751295, 981.3768257156756, 994.2543092680489, 1018.7809620931536, 1030.5826140413144, 10433.895661043292, 10678.839817402744, 11010.339379637247, 11098.2786118902, 11138.888611113947, 440.7311539960621, 449.79983907161653, 453.5600467546475, 456.57766565926386, 457.41679358817106, 5861.384554621789, 6160.1375807387, 6172.444479996732, 6188.659623720649, 6200.224971878515, 549095.0, 562359.0, 563889.0, 565886.1705691445, 577803.0, 83836.0, 84889.0, 85149.57425212834, 85746.0, 86325.0, 470.9929351059737, 471.4460508902026, 472.3458010655199, 472.81087564974285, 476.33313361294466, 9755.9024409755, 9783.755406114904, 9865.45739984279, 9895.65365212214, 9955.651552195639, 32011.839940801077, 32345.676543234265, 32986.0, 33399.49900751539, 33556.664433355356, 46682.29976550423, 47730.761346193045, 48270.0, 49471.25793113178, 50329.245061323374, 9458.763530911783, 9821.410715357102, 9944.35361701487, 9968.50157492137, 9979.0, 11983.700407489883, 12009.819852702041, 15799.605009874385, 17573.12134393301, 18122.637547248716, 11836.112291577994, 11837.289762614271, 12577.251873451027, 14030.649233769238, 14560.49038283655, 13753.449862005407, 14029.579112626638, 14248.501302454364, 14263.786043209568, 14338.0, 14014.579562613138, 14046.1572305662, 14555.490557830424, 14654.19401932904, 14660.78008829847, 9533.189678877374, 9598.136167744937, 9642.084002019797, 9893.455859927773, 9911.30620856562, 14356.569302920929, 14388.928055359656, 14615.488457903923, 15034.92482537624, 15071.54785356441], "unit": "score", "name": "jquery.html", "value": 8210.199685206882}, {"lowerIsBetter": false, "replicates": [1151.9193656444052, 1638.5985433568774, 1681.9579510512235, 1704.7016772064885, 1705.880588358815, 6095.299040610325, 6425.453836423903, 6742.359475849798, 6754.257031726513, 6765.695543700533, 6696.765613203539, 6826.658667066639, 6826.692798824052, 6844.178698556168, 6847.863042739143, 2060.4127823570325, 2088.1647341063544, 2093.727815383998, 2096.088201632287, 2097.528056187361, 2177.0747432341273, 2192.4738062864876, 2197.9120835166573, 2198.5822693688147, 2203.8347123965686, 2085.9582808343825, 2105.210546045233, 2116.4074059263353, 2129.818965387944, 2133.391983284764, 2140.1011575138477, 2163.8268938484885, 2165.4586353411646, 2166.7616562178227, 2175.3365223606793, 2091.184438069139, 2094.5706130243266, 2104.4633618427356, 2111.9683204751786, 2112.207922029239, 6645.3354664533645, 6710.966445167792, 6711.463082953352, 6722.327767223287, 6725.2602213756445, 4201.68487363449, 4239.554846741109, 4255.616994470482, 4272.679549033804, 4283.057727299989, 2889.8410587417684, 2913.6212292402065, 2936.8091074080116, 2938.2066841952765, 2946.337074158297, 965.3242730088896, 1001.5342865567546, 1015.3298822776932, 1020.8009438159562, 1021.1269364693154, 8968.058353872815, 8977.192052715287, 8985.46087234768, 8987.370884038186, 9020.77448063797, 3872.7095467840027, 3885.3589157789215, 3888.747231429948, 3906.1797022625565, 3910.511186101737, 8012.439129260896, 8089.555074470843, 8104.5137291762685, 8119.553424561587, 8119.797005074803, 4283.250431174532, 4309.784510774512, 4310.61204491591, 4317.935230971538, 4320.0, 3558.1104723819044, 3637.418013117888, 3851.9229615407535, 3945.447637330776, 3958.722889397772, 3235.530848027023, 3288.4409650359016, 3309.0, 3427.920205135424, 3436.5532480777338, 1088.983665245006, 1115.7935781880378, 1123.5898896902736, 1129.5707631100295, 1136.1251836086167, 1218.5004148299154, 1228.7542491501556, 1244.3404995352478, 1287.459267107817, 1289.56799472176, 6461.321561236096, 6549.574277671936, 6568.0, 6605.07528945948, 6608.2730899601, 459.7172738765702, 465.63912967450494, 467.42273292483753, 467.94384673838675, 472.90541891621814, 5193.454687257859, 5249.632525723163, 5250.579953603703, 5251.369835619674, 5265.684058956398, 7926.207379262028, 8019.278264956182, 8071.515709057476, 8095.5142691438705, 8244.505329680118, 7929.32600728933, 7992.440529162903, 8085.676572937016, 8098.797530061795, 8102.878456823152, 6521.869562608721, 6536.57512261711, 6681.899771503334, 6695.933040669628, 6696.129503164558, 6345.7144428499905, 6346.873062538816, 6347.5556711029785, 6356.936430635635, 6366.554341196072, 7242.456815738736, 7312.561246325237, 7549.2073332299315, 7579.242075792376, 7608.771736847903, 7555.73554925575, 7599.316061554487, 7764.650590723318, 7774.0, 7776.41676874248, 5433.755481003285, 5590.413006634408, 5938.109283607493, 5991.850203744767, 5995.850103747442, 6222.875542489034, 6443.581167224115, 6484.221893372825, 6484.578502397518, 6485.027245913151, 6202.906956395567, 6217.658028808459, 6286.591371560834, 6342.651154186564, 6433.774817881352, 6076.817695469143, 6135.84660383494, 6193.163922870355, 6257.2491301044165, 6342.651154186564, 6304.590201636879, 6340.778072767431, 6342.080398342122, 6346.777862774781, 6386.840328991812, 5842.0, 5895.675737834289, 5897.262842144732, 5912.260967379078, 5914.497267732291, 5705.087186050212, 5759.2512973313205, 5844.211031510692, 5853.531717462678, 5856.677882716492], "unit": "score", "name": "mootools.html", "value": 3993.8543015683354}, {"lowerIsBetter": false, "replicates": [330.7932542161149, 335.51518056408486, 341.5798567761653, 348.4581475805122, 353.7435359364461, 5906.970465147669, 5916.970415147929, 5921.940780592193, 5929.110633404992, 5959.910601340978, 5835.562332825045, 5837.678927658977, 5851.180834683148, 5854.736536855841, 5859.150423188635, 4512.887177820564, 4543.250363689987, 4544.454665440152, 4554.407926969489, 4559.977200114003, 4389.209942210401, 4487.416635837337, 4491.595756381925, 4496.325551167319, 4502.077074199798, 4429.46846378435, 4441.267190913495, 4451.62161216297, 4456.086502267044, 4468.843590474334, 4289.378040184156, 4365.410669559632, 4436.0906014267, 4466.240739074366, 4468.151051300259, 3915.5888631693547, 3939.960600393988, 3945.230680017398, 3947.131631041166, 3956.8417263309434, 5547.3343198816, 5550.16747487876, 5558.0, 5569.582281328917, 5572.944270557324, 5002.674826136289, 5077.822276220315, 5127.692338459704, 5152.587792976553, 5244.842654720326, 6174.9073763893575, 6177.505799536027, 6220.87558248837, 6222.81331560054, 6224.0, 4338.370936214231, 4346.195953748535, 4346.826126954918, 4350.456192975878, 4355.782210889474, 6246.344133865924, 6323.8735225295695, 6376.617402955838, 6412.326705695881, 6425.100485931926, 4446.799894004777, 4465.799039043251, 4466.553344665573, 4466.57567531084, 4472.0, 4529.365888775574, 4529.660275479352, 4530.343100250511, 4530.705504142286, 4536.4102666653125, 5195.064888320063, 5196.064708352457, 5203.583713302926, 5203.713795741195, 5210.7915683373, 6093.786717464867, 6219.40915613016, 6222.346653601307, 6222.502199824094, 6230.875382492415, 1589.6582234819339, 1609.147152009437, 1610.6215039465717, 1611.806583209999, 1613.5482065021813, 1068.8610480637624, 1071.6570697376922, 1078.0620859851979, 1079.2013909706761, 1100.8513850630063, 6038.607490513103, 6043.395660434009, 6053.606515576474, 6057.697115144225, 6061.605995610271, 6450.193725784277, 6463.224413070461, 6472.644004579794, 6473.126127972663, 6474.708638111297, 5924.970375148183, 6037.366076561985, 6043.395660434009, 6052.122442245938, 6053.4551890330085, 3483.042163405033, 3491.94762078569, 3502.4571191465366, 3502.877399291013, 3508.245727168671, 6590.373914478117, 6595.70319335631, 6603.405693487513, 6612.735490580323, 6617.86764264712, 6486.610803351814, 6570.934290657128, 6592.24189218246, 6601.504887133484, 6611.834704132435, 6540.0, 6600.933990660128, 6665.933340666531, 6678.666066696743, 6700.095487109275, 6488.643124628191, 6491.772787952399, 6494.415502604788, 6496.935030649633, 6498.577592456475, 6267.4045965633195, 6410.487161027013, 6418.101465794699, 6433.131527243762, 6436.259830119409, 6626.237982632059, 6668.199816022108, 6671.766488172891, 6675.599464032173, 6680.098186744727, 4439.4228750262255, 4439.445069366329, 4441.378207050951, 4441.800118994588, 4455.75493347869, 6344.365563443619, 6490.415862572391, 6497.415232628897, 6504.056911747864, 6504.4796416285635, 6292.2134733158355, 6306.621602703853, 6317.494600431863, 6328.683565821599, 6333.050042493663, 6313.715882785194, 6317.526185536195, 6319.24169099711, 6324.968375158095, 6325.0, 6061.6666083365835, 6208.130861679459, 6210.689465526796, 6213.130161777264, 6222.751089956532, 6317.652529110943, 6329.651869147241, 6329.841753956188, 6336.746530138743, 6344.27040890285, 6218.098375735402, 6219.564630475823, 6220.688965551613, 6225.159603453657, 6226.844328891632], "unit": "score", "name": "prototype.html", "value": 4724.568424688381}, {"lowerIsBetter": false, "replicates": [815.6737305077968, 821.092692574705, 833.3125171733321, 840.1850205300856, 841.6465084664441, 12748.490060397584, 12948.676283092927, 12956.48174073036, 12957.611271661865, 12973.805392919103, 14856.18290993995, 15232.39070437184, 15270.08379497231, 15279.847201527982, 15287.464938727147, 689.0147089661807, 816.8407160603684, 817.4604760857835, 818.7420962396841, 820.2044017303207, 694.9027136200936, 812.9796755081111, 822.0341099208437, 830.1325115254543, 831.8003679117029, 853.5262929074361, 872.7730789994583, 873.4279047224078, 873.9868901966476, 874.191372979991, 708.3730898155118, 712.2521352579822, 863.5164307987548, 870.268974061788, 873.3711727556149, 624.3600309682566, 626.6334194496179, 629.625372903122, 631.6652174347557, 632.3044650884037, 13834.169949803045, 13999.0, 14006.859931400759, 14007.719845603031, 14010.719785604231, 12237.0, 12332.691682708004, 12470.875291247063, 12595.181313214613, 12731.681707957283, 10015.59937602495, 10261.230407719451, 10646.307989980627, 10660.360378377321, 10804.297720648132, 3694.704423646102, 3737.7757334560015, 3778.6977041836585, 3861.575226725058, 3862.0, 44854.32718509225, 45327.0, 45329.54670453286, 45337.77331113356, 45513.317300240524, 9732.513374331254, 9987.500624968723, 10149.34029288094, 10149.898501015043, 10591.470426478645, 18272.0, 18277.817221827878, 18287.359942402218, 19028.238870445304, 19040.143193556047, 7881.251281128284, 8013.879791803128, 8104.1085480597085, 8141.674333026731, 8172.877406838902, 4301.935470967938, 4360.106178233455, 4398.252297109499, 4426.186190977103, 4445.422095127613, 2967.0802051364144, 2994.086803524946, 2995.5955945947453, 2998.625171853518, 3003.3692924485667, 534.7807398966405, 535.9088954877681, 536.7906516458585, 542.5117394345042, 557.6068871445622, 590.2562770908686, 669.0532895952173, 690.2545251128869, 693.1508901595505, 713.8358177619177, 8200.835983280302, 8344.374171937128, 8516.40385173032, 8596.656133754705, 8673.471929536056, 577.7948828166025, 666.4501786026548, 678.4877417549718, 705.2982282628817, 712.7434123715458, 3763.3790424580184, 3871.5741268460447, 3981.6416522513114, 3993.5008123984503, 4017.276890159741, 52302.21546676803, 57976.0, 62611.0, 62783.37216627866, 62789.37210627835, 47599.28601070987, 48461.5153848464, 49020.26469602959, 49170.75414622975, 49749.75125124351, 43675.56324436778, 43915.12169756587, 43979.34030989538, 43986.56013439825, 45254.54745452503, 8584.91415085841, 8723.0, 8738.563071846509, 8867.645294188418, 8876.556172191236, 33579.832100840315, 33855.322893541495, 33902.491462628575, 34178.82910585531, 35985.0, 42487.787561061996, 43326.78336608297, 44142.33786493269, 44458.11083778371, 44467.77766111149, 10565.735856603646, 10940.781184376427, 11047.889521104686, 11102.389368584481, 11484.253523520945, 11591.24656897299, 12045.457954392248, 12065.638030859089, 12434.129610927148, 12760.106792524433, 10909.945450272699, 11106.722331941766, 11493.655190344303, 11631.127665425296, 11788.646340609796, 11202.943985280348, 11380.886191137983, 11573.479193436147, 11816.468258928197, 11874.46564904564, 11209.775804484028, 11377.488013039267, 11755.235909665844, 11787.587434439753, 11817.763644727229, 9054.954725226595, 9222.584983675884, 9253.953730231306, 9463.526823658653, 9464.432134071978, 8688.78278043054, 8880.51157186361, 9513.749445687366, 10067.546960386653, 10085.647002354883], "unit": "score", "name": "yui.html", "value": 6328.297800112999}], "name": "dromaeo_css", "value": 6787.363713195647}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 114.0, 107.0, 111.0, 113.0, 112.0, 101.0, 110.0, 113.0, 117.0], "unit": "score", "name": "ai-astar", "value": 111.0}, {"lowerIsBetter": true, "replicates": [153.0, 138.0, 156.0, 170.0, 124.0, 160.0, 145.0, 124.0, 182.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 148.3}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 160.0, 159.0, 161.0, 161.0, 164.0, 160.0, 161.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 82.0, 92.0, 71.0, 77.0, 71.0, 123.0, 93.0, 73.0], "unit": "score", "name": "audio-fft", "value": 82.4}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 147.0, 93.0, 94.0, 93.0, 139.0, 95.0, 93.0, 146.0], "unit": "score", "name": "audio-oscillator", "value": 108.8}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 144.0, 145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 144.0], "unit": "score", "name": "imaging-darkroom", "value": 144.7}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "unit": "score", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0, 67.0, 56.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.8}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.6}, {"lowerIsBetter": true, "replicates": [82.0, 85.0, 85.0, 84.0, 84.0, 85.0, 83.0, 84.0, 92.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.9}, {"lowerIsBetter": true, "replicates": [115.0, 118.0, 121.0, 121.0, 130.0, 123.0, 130.0, 118.0, 130.0, 115.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.1}, {"lowerIsBetter": true, "replicates": [140.0, 139.0, 150.0, 149.0, 150.0, 139.0, 148.0, 148.0, 148.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.2}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 65.0, 68.0, 68.0, 66.0, 67.0, 67.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1505.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.56291390728478], "unit": "score", "name": "Richards", "value": 213.2240799999999}, {"lowerIsBetter": false, "replicates": [194.96977968414896], "unit": "score", "name": "DeltaBlue", "value": 339.11922200000015}, {"lowerIsBetter": false, "replicates": [283.68794326241135], "unit": "score", "name": "Encrypt", "value": 938.2880249999996}, {"lowerIsBetter": false, "replicates": [5112.244897959184], "unit": "score", "name": "Decrypt", "value": 52.067341317365305}, {"lowerIsBetter": false, "replicates": [2002.0], "unit": "score", "name": "RayTrace", "value": 369.62487512487513}, {"lowerIsBetter": false, "replicates": [769.2307692307693], "unit": "score", "name": "Earley", "value": 866.4018999999997}, {"lowerIsBetter": false, "replicates": [12268.292682926829], "unit": "score", "name": "Boyer", "value": 54.32402186878729}, {"lowerIsBetter": false, "replicates": [25300.0], "unit": "score", "name": "RegExp", "value": 36.007312252964404}, {"lowerIsBetter": false, "replicates": [621.5040397762585], "unit": "score", "name": "Splay", "value": 131.11901899999998}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 19796.79016275865}]} 10:14:42 INFO - RETURN: dromaeo_css: 6787.36 10:14:42 INFO - RETURN: kraken: 1505.70 10:14:42 INFO - RETURN: v8_7: 19796.79 10:14:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19796.79"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1505.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6787.36"}}} 10:14:42 INFO - Return code: 0 10:14:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:14:42 INFO - # TBPL SUCCESS # 10:14:42 INFO - Running post-action listener: _resource_record_post_action 10:14:42 INFO - Running post-run listener: _resource_record_post_run 10:14:43 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 0; Write bytes: 556318720; Read time: 0; Write time: 653408 10:14:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 241577984; Read time: 0; Write time: 383268 10:14:43 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 310730752; Read time: 0; Write time: 269340 10:14:43 INFO - Running post-run listener: _upload_blobber_files 10:14:43 INFO - Blob upload gear active. 10:14:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:14:43 INFO - Copying logs to upload dir... 10:14:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.995539 ========= master_lag: 0.90 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-17 10:14:43.359924) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:14:43.367439) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009534 build_url: 'https://queue.taskcluster.net/v1/task/dtHN6l_CRbuSGADO_iRLcA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:14:43.393385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:14:43.393846) ========= 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=7d909545e45784438f4e8e36000001b3-1450374449.6796-419958702 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004626 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:14:43.444222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:14:43.444584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:14:43.444898) ========= ========= Total master_lag: 1.08 =========