builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-054 starttime: 1450618353.43 results: success (0) buildid: 20151220030223 builduid: 69962deddfc8411ab7342ec9ab7380f5 revision: 388bdc46ba51ee31da8b8abe977e0ca38d117434 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.429839) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.430299) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.430637) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006442 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.457792) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.458095) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.532475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.532755) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.594608) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.594933) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.595351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:33.595657) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-20 05:32:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|: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-20 05:32:34 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.393673 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.010404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.010811) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018808 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.061614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.062049) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 388bdc46ba51ee31da8b8abe977e0ca38d117434 --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 388bdc46ba51ee31da8b8abe977e0ca38d117434 --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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-20 05:32:34,590 truncating revision to first 12 chars 2015-12-20 05:32:34,590 Setting DEBUG logging. 2015-12-20 05:32:34,590 attempt 1/10 2015-12-20 05:32:34,590 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/388bdc46ba51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-20 05:32:34,848 unpacking tar archive at: mozilla-central-388bdc46ba51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.567232 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.642295) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.642751) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.650814) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.651119) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-20 05:32:34.651565) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-12-20 05:32:34.651905) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:32:35 INFO - MultiFileLogger online at 20151220 05:32:35 in /builds/slave/test-pgo 05:32:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 05:32:35 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:32:35 INFO - {'append_to_log': False, 05:32:35 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:32:35 INFO - 'blob_upload_branch': 'Firefox', 05:32:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:32:35 INFO - 'branch': 'Firefox', 05:32:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:35 INFO - 'config_files': ('talos/linux_config.py',), 05:32:35 INFO - 'default_actions': ('clobber', 05:32:35 INFO - 'read-buildbot-config', 05:32:35 INFO - 'download-and-extract', 05:32:35 INFO - 'populate-webroot', 05:32:35 INFO - 'create-virtualenv', 05:32:35 INFO - 'install', 05:32:35 INFO - 'run-tests'), 05:32:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:35 INFO - 'download_minidump_stackwalk': True, 05:32:35 INFO - 'download_symbols': 'ondemand', 05:32:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:32:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:32:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:32:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:32:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:35 INFO - 'installer_path': 'installer.exe', 05:32:35 INFO - 'log_level': 'info', 05:32:35 INFO - 'log_name': 'talos', 05:32:35 INFO - 'log_to_console': True, 05:32:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:32:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:32:35 INFO - 'opt_config_files': (), 05:32:35 INFO - 'pip_index': False, 05:32:35 INFO - 'sps_profile': False, 05:32:35 INFO - 'sps_profile_interval': 0, 05:32:35 INFO - 'suite': 'dromaeojs-e10s', 05:32:35 INFO - 'system_bits': '64', 05:32:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:35 INFO - 'title': 'talos-linux64-ix-054', 05:32:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:35 INFO - 'use_talos_json': True, 05:32:35 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:32:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:35 INFO - 'work_dir': 'build'} 05:32:35 INFO - ##### 05:32:35 INFO - ##### Running clobber step. 05:32:35 INFO - ##### 05:32:35 INFO - Running pre-action listener: _resource_record_pre_action 05:32:35 INFO - Running main action method: clobber 05:32:35 INFO - rmtree: /builds/slave/test-pgo/build 05:32:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:32:36 INFO - Running post-action listener: _resource_record_post_action 05:32:36 INFO - ##### 05:32:36 INFO - ##### Running read-buildbot-config step. 05:32:36 INFO - ##### 05:32:36 INFO - Running pre-action listener: _resource_record_pre_action 05:32:36 INFO - Running main action method: read_buildbot_config 05:32:36 INFO - Using buildbot properties: 05:32:36 INFO - { 05:32:36 INFO - "project": "", 05:32:36 INFO - "product": "firefox", 05:32:36 INFO - "script_repo_revision": "production", 05:32:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:32:36 INFO - "repository": "", 05:32:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:32:36 INFO - "buildid": "20151220030223", 05:32:36 INFO - "stage_platform": "linux64-pgo", 05:32:36 INFO - "basedir": "/builds/slave/test-pgo", 05:32:36 INFO - "nightly_build": "True", 05:32:36 INFO - "buildnumber": 272, 05:32:36 INFO - "slavename": "talos-linux64-ix-054", 05:32:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:32:36 INFO - "platform": "ubuntu64_hw", 05:32:36 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:32:36 INFO - "branch": "mozilla-central", 05:32:36 INFO - "repo_path": "mozilla-central", 05:32:36 INFO - "revision": "388bdc46ba51ee31da8b8abe977e0ca38d117434", 05:32:36 INFO - "pgo_build": "True", 05:32:36 INFO - "builduid": "69962deddfc8411ab7342ec9ab7380f5", 05:32:36 INFO - "slavebuilddir": "test" 05:32:36 INFO - } 05:32:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:32:36 INFO - Running post-action listener: _resource_record_post_action 05:32:36 INFO - ##### 05:32:36 INFO - ##### Running download-and-extract step. 05:32:36 INFO - ##### 05:32:36 INFO - Running pre-action listener: _resource_record_pre_action 05:32:36 INFO - Running main action method: download_and_extract 05:32:36 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:32:36 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')]} 05:32:36 INFO - https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:32:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/test_packages.json 05:32:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/test_packages.json 05:32:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:32:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:32:41 INFO - Downloaded 1302 bytes. 05:32:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:32:41 INFO - Using the following test package requirements: 05:32:41 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:41 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:32:41 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'jsshell-linux-x86_64.zip'], 05:32:41 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:32:41 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:41 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:32:41 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:32:41 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:32:41 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:32:41 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:32:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:32:41 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:41 INFO - https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:32:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:45 INFO - Downloaded 23120960 bytes. 05:32: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 05:32:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32: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 05:32:47 INFO - Return code: 0 05:32:47 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 05:32:47 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')]} 05:32:47 INFO - https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:32:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:52 INFO - Downloaded 23120960 bytes. 05:32:52 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 05:32:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:32:52 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 05:32:53 INFO - Return code: 0 05:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:53 INFO - https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:32:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:32:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:32:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:54 INFO - Downloaded 11132039 bytes. 05:32:54 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 05:32:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:32:54 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 05:32:54 INFO - Return code: 0 05:32: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')]} 05:32:54 INFO - https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:32:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:32:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:32:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/9F5c3q79SpuVjSjdwSVq7A/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 05:32:56 INFO - Downloaded 54644693 bytes. 05:32:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:32:56 INFO - mkdir: /builds/slave/test-pgo/properties 05:32:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:32:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:32:56 INFO - Contents: 05:32:56 INFO - build_url:https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:32:56 INFO - Running post-action listener: _resource_record_post_action 05:32:56 INFO - Running post-action listener: set_extra_try_arguments 05:32:56 INFO - ##### 05:32:56 INFO - ##### Running populate-webroot step. 05:32:56 INFO - ##### 05:32:56 INFO - Running pre-action listener: _resource_record_pre_action 05:32:56 INFO - Running main action method: populate_webroot 05:32:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:56 INFO - '--tppagecycles', 05:32:56 INFO - '7'], 05:32:56 INFO - 'tests': ['tsvgm']}}, 05:32:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:32:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:32:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:32:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'tests': ['damp', 'tps']}, 05:32:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['damp', 'tps']}, 05:32:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:32:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:56 INFO - 'other': {'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'other_l64': {'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'other_nol64': {'tests': ['a11yr', 05:32:56 INFO - 'ts_paint', 05:32:56 INFO - 'tpaint', 05:32:56 INFO - 'sessionrestore', 05:32:56 INFO - 'sessionrestore_no_auto_restore']}, 05:32:56 INFO - 'svgr': {'tests': ['tsvgx', 05:32:56 INFO - 'tsvgr_opacity', 05:32:56 INFO - 'tart', 05:32:56 INFO - 'tscrollx', 05:32:56 INFO - 'cart']}, 05:32:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['tsvgx', 05:32:56 INFO - 'tsvgr_opacity', 05:32:56 INFO - 'tart', 05:32:56 INFO - 'tscrollx', 05:32:56 INFO - 'cart']}, 05:32:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'tests': ['tp5o']}, 05:32:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'talos_options': ['--e10s'], 05:32:56 INFO - 'tests': ['tp5o']}, 05:32:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'talos_options': ['--xperf_path', 05:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:56 INFO - 'tests': ['tp5n']}, 05:32:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:56 INFO - 'talos_options': ['--e10s', 05:32:56 INFO - '--xperf_path', 05:32:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:56 INFO - 'tests': ['tp5n']}}, 05:32:56 INFO - 'talos.zip': {'path': '', 05:32:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:56 INFO - Running post-action listener: _resource_record_post_action 05:32:56 INFO - ##### 05:32:56 INFO - ##### Running create-virtualenv step. 05:32:56 INFO - ##### 05:32:56 INFO - Running pre-action listener: _resource_record_pre_action 05:32:56 INFO - Running main action method: create_virtualenv 05:32:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:32:56 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 05:32:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:32:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:56 INFO - Using real prefix '/usr' 05:32:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:32:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:59 INFO - Installing pip.................done. 05:32:59 INFO - Return code: 0 05:32:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:32: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')]} 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:32: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:32: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 pip>=1.5 05:32:59 INFO - Using env: {'DISPLAY': ':0', 05:32:59 INFO - 'HOME': '/home/cltbld', 05:32:59 INFO - 'LANG': 'en_US.UTF-8', 05:32:59 INFO - 'LANGUAGE': 'en_US:en', 05:32:59 INFO - 'LOGNAME': 'cltbld', 05:32:59 INFO - 'MAIL': '/var/mail/cltbld', 05:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:59 INFO - 'MOZ_NO_REMOTE': '1', 05:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:59 INFO - 'NO_EM_RESTART': '1', 05:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:59 INFO - 'SHELL': '/bin/bash', 05:32:59 INFO - 'SHLVL': '1', 05:32:59 INFO - 'TERM': 'linux', 05:32:59 INFO - 'TMOUT': '86400', 05:32:59 INFO - 'USER': 'cltbld', 05:32:59 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:59 INFO - '_': '/tools/buildbot/bin/python'} 05:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:59 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 05:32:59 INFO - Cleaning up... 05:32:59 INFO - Return code: 0 05:32:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:32: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')]} 05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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', '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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:32: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:32: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 psutil>=0.7.1 05:32:59 INFO - Using env: {'DISPLAY': ':0', 05:32:59 INFO - 'HOME': '/home/cltbld', 05:32:59 INFO - 'LANG': 'en_US.UTF-8', 05:32:59 INFO - 'LANGUAGE': 'en_US:en', 05:32:59 INFO - 'LOGNAME': 'cltbld', 05:32:59 INFO - 'MAIL': '/var/mail/cltbld', 05:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:59 INFO - 'MOZ_NO_REMOTE': '1', 05:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:59 INFO - 'NO_EM_RESTART': '1', 05:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:59 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:59 INFO - 'SHELL': '/bin/bash', 05:32:59 INFO - 'SHLVL': '1', 05:32:59 INFO - 'TERM': 'linux', 05:32:59 INFO - 'TMOUT': '86400', 05:32:59 INFO - 'USER': 'cltbld', 05:32:59 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:59 INFO - '_': '/tools/buildbot/bin/python'} 05:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:59 INFO - Downloading/unpacking psutil>=0.7.1 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:33:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:33:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:33:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:33:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:01 INFO - Installing collected packages: psutil 05:33:01 INFO - Running setup.py install for psutil 05:33:01 INFO - building 'psutil._psutil_linux' extension 05:33:01 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 05:33:01 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 05:33:01 INFO - building 'psutil._psutil_posix' extension 05:33:01 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 05:33:01 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 05:33:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:33:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:33:01 INFO - Successfully installed psutil 05:33:01 INFO - Cleaning up... 05:33:01 INFO - Return code: 0 05:33:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:33:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:33:01 INFO - Using env: {'DISPLAY': ':0', 05:33:01 INFO - 'HOME': '/home/cltbld', 05:33:01 INFO - 'LANG': 'en_US.UTF-8', 05:33:01 INFO - 'LANGUAGE': 'en_US:en', 05:33:01 INFO - 'LOGNAME': 'cltbld', 05:33:01 INFO - 'MAIL': '/var/mail/cltbld', 05:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:01 INFO - 'MOZ_NO_REMOTE': '1', 05:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:01 INFO - 'NO_EM_RESTART': '1', 05:33:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:01 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:01 INFO - 'SHELL': '/bin/bash', 05:33:01 INFO - 'SHLVL': '1', 05:33:01 INFO - 'TERM': 'linux', 05:33:01 INFO - 'TMOUT': '86400', 05:33:01 INFO - 'USER': 'cltbld', 05:33:01 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:01 INFO - '_': '/tools/buildbot/bin/python'} 05:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:33:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:33:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:33:04 INFO - Installing collected packages: mozsystemmonitor 05:33:04 INFO - Running setup.py install for mozsystemmonitor 05:33:04 INFO - Successfully installed mozsystemmonitor 05:33:04 INFO - Cleaning up... 05:33:04 INFO - Return code: 0 05:33:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:33:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:33:04 INFO - Using env: {'DISPLAY': ':0', 05:33:04 INFO - 'HOME': '/home/cltbld', 05:33:04 INFO - 'LANG': 'en_US.UTF-8', 05:33:04 INFO - 'LANGUAGE': 'en_US:en', 05:33:04 INFO - 'LOGNAME': 'cltbld', 05:33:04 INFO - 'MAIL': '/var/mail/cltbld', 05:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:04 INFO - 'MOZ_NO_REMOTE': '1', 05:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:04 INFO - 'NO_EM_RESTART': '1', 05:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:04 INFO - 'SHELL': '/bin/bash', 05:33:04 INFO - 'SHLVL': '1', 05:33:04 INFO - 'TERM': 'linux', 05:33:04 INFO - 'TMOUT': '86400', 05:33:04 INFO - 'USER': 'cltbld', 05:33:04 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:04 INFO - '_': '/tools/buildbot/bin/python'} 05:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:33:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:33:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:33:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:33:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:33:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:33:06 INFO - Downloading docopt-0.6.1.tar.gz 05:33:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:33:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:33:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:33:06 INFO - Running setup.py install for blobuploader 05:33:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:33:06 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:33:06 INFO - Running setup.py install for requests 05:33:07 INFO - Running setup.py install for docopt 05:33:07 INFO - Successfully installed blobuploader requests docopt 05:33:07 INFO - Cleaning up... 05:33:07 INFO - Return code: 0 05:33:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:07 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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:07 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 05:33:07 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 05:33:07 INFO - Using env: {'DISPLAY': ':0', 05:33:07 INFO - 'HOME': '/home/cltbld', 05:33:07 INFO - 'LANG': 'en_US.UTF-8', 05:33:07 INFO - 'LANGUAGE': 'en_US:en', 05:33:07 INFO - 'LOGNAME': 'cltbld', 05:33:07 INFO - 'MAIL': '/var/mail/cltbld', 05:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:07 INFO - 'MOZ_NO_REMOTE': '1', 05:33:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:07 INFO - 'NO_EM_RESTART': '1', 05:33:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:07 INFO - 'SHELL': '/bin/bash', 05:33:07 INFO - 'SHLVL': '1', 05:33:07 INFO - 'TERM': 'linux', 05:33:07 INFO - 'TMOUT': '86400', 05:33:07 INFO - 'USER': 'cltbld', 05:33:07 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:07 INFO - '_': '/tools/buildbot/bin/python'} 05:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:07 INFO - Running setup.py (path:/tmp/pip-Edzlsl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:07 INFO - Running setup.py (path:/tmp/pip-PauXec-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:07 INFO - Running setup.py (path:/tmp/pip-zsVHLN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:07 INFO - Running setup.py (path:/tmp/pip-rFhDFb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:07 INFO - Running setup.py (path:/tmp/pip-3iefFq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:08 INFO - Running setup.py (path:/tmp/pip-8Mzg1i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:08 INFO - Running setup.py (path:/tmp/pip-9k08J9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:08 INFO - Running setup.py (path:/tmp/pip-69qHqe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:08 INFO - Running setup.py (path:/tmp/pip-AOJMGy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:08 INFO - Running setup.py (path:/tmp/pip-3xdWTw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:08 INFO - Running setup.py (path:/tmp/pip-rjZM8l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:08 INFO - Running setup.py (path:/tmp/pip-JCxQXz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:08 INFO - Running setup.py (path:/tmp/pip-SfaKEy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:08 INFO - Running setup.py (path:/tmp/pip-s9xXRs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:08 INFO - Running setup.py (path:/tmp/pip-Z_0sCg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:08 INFO - Running setup.py (path:/tmp/pip-JeP1Pi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:08 INFO - Running setup.py (path:/tmp/pip-Q09zmA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:33:08 INFO - Running setup.py install for manifestparser 05:33:08 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:33:08 INFO - Running setup.py install for mozcrash 05:33:09 INFO - Running setup.py install for mozdebug 05:33:09 INFO - Running setup.py install for mozdevice 05:33:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Running setup.py install for mozfile 05:33:09 INFO - Running setup.py install for mozhttpd 05:33:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Running setup.py install for mozinfo 05:33:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Running setup.py install for mozInstall 05:33:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Running setup.py install for mozleak 05:33:09 INFO - Running setup.py install for mozlog 05:33:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:33:09 INFO - Running setup.py install for moznetwork 05:33:10 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Running setup.py install for mozprocess 05:33:10 INFO - Running setup.py install for mozprofile 05:33:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Running setup.py install for mozrunner 05:33:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Running setup.py install for mozscreenshot 05:33:10 INFO - Running setup.py install for moztest 05:33:10 INFO - Running setup.py install for mozversion 05:33:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:33:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:33:10 INFO - Cleaning up... 05:33:10 INFO - Return code: 0 05:33:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:10 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')]} 05:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:10 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')]} 05:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:10 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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:10 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 05:33:10 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 05:33:10 INFO - Using env: {'DISPLAY': ':0', 05:33:10 INFO - 'HOME': '/home/cltbld', 05:33:10 INFO - 'LANG': 'en_US.UTF-8', 05:33:10 INFO - 'LANGUAGE': 'en_US:en', 05:33:10 INFO - 'LOGNAME': 'cltbld', 05:33:10 INFO - 'MAIL': '/var/mail/cltbld', 05:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:10 INFO - 'MOZ_NO_REMOTE': '1', 05:33:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:10 INFO - 'NO_EM_RESTART': '1', 05:33:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:10 INFO - 'SHELL': '/bin/bash', 05:33:10 INFO - 'SHLVL': '1', 05:33:10 INFO - 'TERM': 'linux', 05:33:10 INFO - 'TMOUT': '86400', 05:33:10 INFO - 'USER': 'cltbld', 05:33:10 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:10 INFO - '_': '/tools/buildbot/bin/python'} 05:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:10 INFO - Running setup.py (path:/tmp/pip-28T6Zu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:33:10 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)) 05:33:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:10 INFO - Running setup.py (path:/tmp/pip-JKZBpJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:11 INFO - Running setup.py (path:/tmp/pip-aHGPiv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:11 INFO - Running setup.py (path:/tmp/pip-wFLnbS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:11 INFO - Running setup.py (path:/tmp/pip-V54kbB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:11 INFO - Running setup.py (path:/tmp/pip-SorwdO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:11 INFO - Running setup.py (path:/tmp/pip-MR86K8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:11 INFO - Running setup.py (path:/tmp/pip-0T8gmv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:11 INFO - Running setup.py (path:/tmp/pip-4vPLz5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:11 INFO - Running setup.py (path:/tmp/pip-_Z2HBJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:11 INFO - Running setup.py (path:/tmp/pip-DsOSmt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:11 INFO - Running setup.py (path:/tmp/pip-m9k6Ye-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:11 INFO - Running setup.py (path:/tmp/pip-3cm1ze-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:11 INFO - Running setup.py (path:/tmp/pip-RiGfST-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:33:11 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)) 05:33:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:11 INFO - Running setup.py (path:/tmp/pip-H7dG1V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:12 INFO - Running setup.py (path:/tmp/pip-JOsH46-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:33:12 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)) 05:33:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:12 INFO - Running setup.py (path:/tmp/pip-A8YZvm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:33:12 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)) 05:33:12 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)) 05:33:12 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)) 05:33:12 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)) 05:33:12 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)) 05:33:12 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)) 05:33:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:33: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 05:33: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 05:33: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 05:33: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 05:33: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 05:33: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 05:33:13 INFO - Downloading blessings-1.5.1.tar.gz 05:33:13 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 05:33:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:33:13 INFO - Installing collected packages: blessings 05:33:13 INFO - Running setup.py install for blessings 05:33:14 INFO - Successfully installed blessings 05:33:14 INFO - Cleaning up... 05:33:14 INFO - Return code: 0 05:33:14 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:33:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:33:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:33:14 INFO - Reading from file tmpfile_stdout 05:33:14 INFO - Current package versions: 05:33:14 INFO - argparse == 1.2.1 05:33:14 INFO - blessings == 1.5.1 05:33:14 INFO - blobuploader == 1.2.4 05:33:14 INFO - docopt == 0.6.1 05:33:14 INFO - manifestparser == 1.1 05:33:14 INFO - mozInstall == 1.12 05:33:14 INFO - mozcrash == 0.16 05:33:14 INFO - mozdebug == 0.1 05:33:14 INFO - mozdevice == 0.47 05:33:14 INFO - mozfile == 1.2 05:33:14 INFO - mozhttpd == 0.7 05:33:14 INFO - mozinfo == 0.9 05:33:14 INFO - mozleak == 0.1 05:33:14 INFO - mozlog == 3.1 05:33:14 INFO - moznetwork == 0.27 05:33:14 INFO - mozprocess == 0.22 05:33:14 INFO - mozprofile == 0.27 05:33:14 INFO - mozrunner == 6.11 05:33:14 INFO - mozscreenshot == 0.1 05:33:14 INFO - mozsystemmonitor == 0.0 05:33:14 INFO - moztest == 0.7 05:33:14 INFO - mozversion == 1.4 05:33:14 INFO - psutil == 3.1.1 05:33:14 INFO - requests == 1.2.3 05:33:14 INFO - wsgiref == 0.1.2 05:33:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:33:14 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')]} 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:14 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')]} 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:14 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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:14 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 05:33:14 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 05:33:14 INFO - Using env: {'DISPLAY': ':0', 05:33:14 INFO - 'HOME': '/home/cltbld', 05:33:14 INFO - 'LANG': 'en_US.UTF-8', 05:33:14 INFO - 'LANGUAGE': 'en_US:en', 05:33:14 INFO - 'LOGNAME': 'cltbld', 05:33:14 INFO - 'MAIL': '/var/mail/cltbld', 05:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:14 INFO - 'MOZ_NO_REMOTE': '1', 05:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:14 INFO - 'NO_EM_RESTART': '1', 05:33:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:14 INFO - 'SHELL': '/bin/bash', 05:33:14 INFO - 'SHLVL': '1', 05:33:14 INFO - 'TERM': 'linux', 05:33:14 INFO - 'TMOUT': '86400', 05:33:14 INFO - 'USER': 'cltbld', 05:33:14 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:14 INFO - '_': '/tools/buildbot/bin/python'} 05:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 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)) 05:33:14 INFO - Cleaning up... 05:33:14 INFO - Return code: 0 05:33:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:33:14 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')]} 05:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:14 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')]} 05:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:14 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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:14 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 05:33:14 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 05:33:14 INFO - Using env: {'DISPLAY': ':0', 05:33:14 INFO - 'HOME': '/home/cltbld', 05:33:14 INFO - 'LANG': 'en_US.UTF-8', 05:33:14 INFO - 'LANGUAGE': 'en_US:en', 05:33:14 INFO - 'LOGNAME': 'cltbld', 05:33:14 INFO - 'MAIL': '/var/mail/cltbld', 05:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:14 INFO - 'MOZ_NO_REMOTE': '1', 05:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:14 INFO - 'NO_EM_RESTART': '1', 05:33:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:14 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:14 INFO - 'SHELL': '/bin/bash', 05:33:14 INFO - 'SHLVL': '1', 05:33:14 INFO - 'TERM': 'linux', 05:33:14 INFO - 'TMOUT': '86400', 05:33:14 INFO - 'USER': 'cltbld', 05:33:14 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:14 INFO - '_': '/tools/buildbot/bin/python'} 05:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:15 INFO - Downloading/unpacking jsonschema 05:33:15 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 05:33:15 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 05:33:15 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 05:33:15 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 05:33:15 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 05:33:15 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 05:33:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:33:16 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 05:33:16 INFO - Downloading/unpacking functools32 (from jsonschema) 05:33:16 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 05:33:16 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 05:33:16 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 05:33:16 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 05:33:16 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 05:33:16 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 05:33:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:33:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:33:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:33:16 INFO - warning: no files found matching '*.txt' 05:33:16 INFO - no previously-included directories found matching 'build' 05:33:16 INFO - no previously-included directories found matching 'dist' 05:33:16 INFO - no previously-included directories found matching '.git*' 05:33:16 INFO - Installing collected packages: jsonschema, functools32 05:33:17 INFO - Running setup.py install for functools32 05:33:17 INFO - warning: no files found matching '*.txt' 05:33:17 INFO - no previously-included directories found matching 'build' 05:33:17 INFO - no previously-included directories found matching 'dist' 05:33:17 INFO - no previously-included directories found matching '.git*' 05:33:17 INFO - Successfully installed jsonschema functools32 05:33:17 INFO - Cleaning up... 05:33:17 INFO - Return code: 0 05:33:17 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:33:17 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 0x7fe23a2fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, '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': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', '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 05:33:17 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 05:33:17 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 05:33:17 INFO - Using env: {'DISPLAY': ':0', 05:33:17 INFO - 'HOME': '/home/cltbld', 05:33:17 INFO - 'LANG': 'en_US.UTF-8', 05:33:17 INFO - 'LANGUAGE': 'en_US:en', 05:33:17 INFO - 'LOGNAME': 'cltbld', 05:33:17 INFO - 'MAIL': '/var/mail/cltbld', 05:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:17 INFO - 'MOZ_NO_REMOTE': '1', 05:33:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:17 INFO - 'NO_EM_RESTART': '1', 05:33:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:17 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:17 INFO - 'SHELL': '/bin/bash', 05:33:17 INFO - 'SHLVL': '1', 05:33:17 INFO - 'TERM': 'linux', 05:33:17 INFO - 'TMOUT': '86400', 05:33:17 INFO - 'USER': 'cltbld', 05:33:17 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:17 INFO - '_': '/tools/buildbot/bin/python'} 05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:33:17 INFO - Downloading/unpacking flake8 05:33:17 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 05:33:17 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 05:33:17 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 05:33:17 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 05:33:17 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 05:33:17 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 05:33:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:33:19 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 05:33:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:33:19 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 05:33:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 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 05:33:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:33:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:33:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:33:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:33:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:33:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:33:19 INFO - no previously-included directories found matching 'docs/_build' 05:33:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:33:19 INFO - Running setup.py install for pep8 05:33:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:33:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:33:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:33:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:33:19 INFO - no previously-included directories found matching 'docs/_build' 05:33:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:33:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:33:19 INFO - Cleaning up... 05:33:19 INFO - Return code: 0 05:33:19 INFO - Running post-action listener: _resource_record_post_action 05:33:19 INFO - Running post-action listener: _start_resource_monitoring 05:33:19 INFO - Starting resource monitoring. 05:33:19 INFO - ##### 05:33:19 INFO - ##### Running install step. 05:33:19 INFO - ##### 05:33:19 INFO - Running pre-action listener: _resource_record_pre_action 05:33:19 INFO - Running main action method: install 05:33:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:33:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:33:20 INFO - Reading from file tmpfile_stdout 05:33:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:33:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:33:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:33:20 INFO - Reading from file tmpfile_stdout 05:33:20 INFO - Output received: 05:33:20 INFO - Usage: mozinstall [options] installer 05:33:20 INFO - Options: 05:33:20 INFO - -h, --help show this help message and exit 05:33:20 INFO - -d DEST, --destination=DEST 05:33:20 INFO - Directory to install application into. [default: 05:33:20 INFO - "/builds/slave/test-pgo"] 05:33:20 INFO - --app=APP Application being installed. [default: firefox] 05:33:20 INFO - mkdir: /builds/slave/test-pgo/build/application 05:33:20 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'] 05:33:20 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 05:33:36 INFO - Reading from file tmpfile_stdout 05:33:36 INFO - Output received: 05:33:36 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:33:36 INFO - Running post-action listener: _resource_record_post_action 05:33:36 INFO - ##### 05:33:36 INFO - ##### Running run-tests step. 05:33:36 INFO - ##### 05:33:36 INFO - Running pre-action listener: _resource_record_pre_action 05:33:36 INFO - Running main action method: run_tests 05:33:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:33:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:33:36 INFO - Python 2.7.3 05:33:36 INFO - Return code: 0 05:33:36 INFO - grabbing minidump binary from tooltool 05:33:36 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')]} 05:33:36 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 0x1cf2350>, '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 0x1cf3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf36e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:33:36 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 05:33:36 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 05:33:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:36 INFO - Return code: 0 05:33:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:33:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:33:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:33:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:33:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:33:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:33:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:33:37 INFO - Return code: 0 05:33:37 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:33:37 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost 05:33:37 INFO - Using env: {'DISPLAY': ':0', 05:33:37 INFO - 'HOME': '/home/cltbld', 05:33:37 INFO - 'LANG': 'en_US.UTF-8', 05:33:37 INFO - 'LANGUAGE': 'en_US:en', 05:33:37 INFO - 'LOGNAME': 'cltbld', 05:33:37 INFO - 'MAIL': '/var/mail/cltbld', 05:33:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:33:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:37 INFO - 'MOZ_NO_REMOTE': '1', 05:33:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:37 INFO - 'NO_EM_RESTART': '1', 05:33:37 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:33:37 INFO - 'PWD': '/builds/slave/test-pgo', 05:33:37 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:33:37 INFO - 'SHELL': '/bin/bash', 05:33:37 INFO - 'SHLVL': '1', 05:33:37 INFO - 'TERM': 'linux', 05:33:37 INFO - 'TMOUT': '86400', 05:33:37 INFO - 'USER': 'cltbld', 05:33:37 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995', 05:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:37 INFO - '_': '/tools/buildbot/bin/python'} 05:33:37 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] with output_timeout 3600 05:33:37 INFO - mozversion INFO | application_buildid: 20151220030223 05:33:37 INFO - mozversion INFO | application_changeset: 388bdc46ba51ee31da8b8abe977e0ca38d117434 05:33:37 INFO - mozversion INFO | application_display_name: Nightly 05:33:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:37 INFO - mozversion INFO | application_name: Firefox 05:33:37 INFO - mozversion INFO | application_remotingname: firefox 05:33:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:33:37 INFO - mozversion INFO | application_vendor: Mozilla 05:33:37 INFO - mozversion INFO | application_version: 46.0a1 05:33:37 INFO - mozversion INFO | platform_buildid: 20151220030223 05:33:37 INFO - mozversion INFO | platform_changeset: 388bdc46ba51ee31da8b8abe977e0ca38d117434 05:33:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:33:37 INFO - mozversion INFO | platform_version: 46.0a1 05:33:37 INFO - 2015-12-20 05:33:37,247 DEBUG : using testdate: 1450618417 05:33:37 INFO - 2015-12-20 05:33:37,247 DEBUG : actual date: 1450618417 05:33:37 INFO - 2015-12-20 05:33:37,324 INFO : starting webserver on 'localhost:34486' 05:33:37 INFO - 2015-12-20 05:33:37,325 INFO : Starting test suite talos-linux64-ix-054.e 05:33:37 INFO - 2015-12-20 05:33:37,325 INFO : Starting test dromaeo_css 05:33:37 INFO - 2015-12-20 05:33:37,326 DEBUG : operating with platform_type : linux_ 05:33:37 INFO - 2015-12-20 05:33:37,326 INFO : Initialising browser for dromaeo_css test... 05:33:37 INFO - 2015-12-20 05:33:37,340 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSSI2FR/profile http://localhost:34486/getInfo.html 05:33:42 INFO - 2015-12-20 05:33:42,230 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:33:42 INFO - 2015-12-20 05:33:42,230 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:33:42 INFO - 2015-12-20 05:33:42,230 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:33:42 INFO - 2015-12-20 05:33:42,230 DEBUG : BROWSER_OUTPUT: __metrics 05:33:42 INFO - 2015-12-20 05:33:42,353 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] 05:33:43 INFO - 2015-12-20 05:33:43,290 INFO : Browser initialized. 05:33:43 INFO - 2015-12-20 05:33:43,291 INFO : Running cycle 1/1 for dromaeo_css test... 05:33:43 INFO - 2015-12-20 05:33:43,291 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSSI2FR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:33:55 INFO - 2015-12-20 05:33:55,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 150695936 05:33:55 INFO - 2015-12-20 05:33:55,057 DEBUG : BROWSER_OUTPUT: 05:34:44 INFO - 2015-12-20 05:34:44,680 DEBUG : BROWSER_OUTPUT: 1450618484679 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] 05:36:53 INFO - 2015-12-20 05:36:53,557 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34486/tests/dromaeo/cssquery-ext.html) 05:36:54 INFO - 2015-12-20 05:36:54,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 156880896 05:36:54 INFO - 2015-12-20 05:36:54,784 DEBUG : BROWSER_OUTPUT: 05:39:56 INFO - 2015-12-20 05:39:56,707 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-ext.html (next: http://localhost:34486/tests/dromaeo/cssquery-jquery.html) 05:39:57 INFO - 2015-12-20 05:39:57,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 154931200 05:39:57 INFO - 2015-12-20 05:39:57,296 DEBUG : BROWSER_OUTPUT: 05:42:58 INFO - 2015-12-20 05:42:58,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34486/tests/dromaeo/cssquery-mootools.html) 05:42:59 INFO - 2015-12-20 05:42:59,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 154488832 05:42:59 INFO - 2015-12-20 05:42:59,538 DEBUG : BROWSER_OUTPUT: 05:46:01 INFO - 2015-12-20 05:46:01,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34486/tests/dromaeo/cssquery-prototype.html) 05:46:01 INFO - 2015-12-20 05:46:01,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 152653824 05:46:01 INFO - 2015-12-20 05:46:01,785 DEBUG : BROWSER_OUTPUT: 05:49:04 INFO - 2015-12-20 05:49:04,136 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34486/tests/dromaeo/cssquery-yui.html) 05:49:04 INFO - 2015-12-20 05:49:04,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 153133056 05:49:04 INFO - 2015-12-20 05:49:04,726 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,396 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/dromaeo/cssquery-yui.html (next: http://localhost:34486/tests/dromaeo/cssquery-dojo.html) 05:52:07 INFO - 2015-12-20 05:52:07,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 155893760 05:52:07 INFO - 2015-12-20 05:52:07,647 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,647 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:52:07 INFO - 2015-12-20 05:52:07,647 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:52:07 INFO - 2015-12-20 05:52:07,648 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:52:07 INFO - 2015-12-20 05:52:07,648 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:52:07 INFO - 2015-12-20 05:52:07,649 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3179.85690643921;3237.8866739664118;3238.514222866568;3258.543803867459;3276.3119744853593;5168.560672342846;5380.166074258486;5389.622726409153;5426.077566813641;5430.91853622196;5265.842024739254;5346.732663366826;5369.650972686773;5382.650127741704;5389.326334208224;4913.140200464923;4983.078130545842;5024.874378140539;5044.2937988681615;5049.924251136236;4900.9754951225195;4962.702237865721;5006.874828129289;5033.471485494026;5083.517065878736;4842.467328593852;4911.993041426499;4946.752662366867;5033.597312215014;5043.646944713872;4970.179920313144;4993.550580447741;4994.850154495371;4996.1756310208775;5006.148954677714;4993.875153121165;5006.299118123466;5008.924866127011;5009.849704508871;5015.072211640858;5113.054084994288;5192.9740351298005;5264.973675131638;5267.051930652481;5344.652597581145;5740.8851822963725;5837.153612726173;5848.356680765155;5874.5887787854435;5910.438508341701;5884.529237660977;5936.97031514844;5945.197398351211;5954.702264886781;5973.223480947449;5321.787128514893;5413.296271484761;5544.77820887164;5550.4449555044575;5560.332760068777;5792.681402522901;5878.617889837147;5881.500072493886;5886.705664716747;5937;5811.506021988178;5812.941870581325;5815.069588865761;5822.737976791054;5825.941740582624;5766.769329226868;5782.190493330937;5844.970775146182;5881.059030555176;5908.675022873783;5870.647761134345;5899.970500147472;5913.05391137416;5914.083317085859;5926.111083337534;5853.09277062056;5854.092615644582;5890;5891.675957822275;5896.970515147397;4392.2752745797225;4462.486814016364;4473.239549276631;4477.552244775497;4540.455145382575;4014.297497937849;4023.1752490739327;4023.215472982799;4025.7383270087353;4028.6575641070835;5487.64330318528;5500.944990550123;5512.586556008316;5544.889102217933;5562.582806289463;5603.439656034364;5738.655680659091;5831.979403604437;5955.67243801595;5970.940290597038;5508.504234618904;5518.779248830082;5544.805931792449;5574.944250557523;5582.525485333792;5066.518680725399;5107.591392688576;5112.463191364853;5115.514026167508;5163.948360516422;5708.086706127;5797.710114494258;5892.7642894283745;5981.820545383645;6015.789447369321;5717.799877004285;5817.883642327045;5834.533237341087;5841.094630332305;5891.440313170242;5738.8278351649515;5851.21008663825;5855.560832937462;5868.2077919480325;5890.970545147246;5677.176809362537;5736.196932429547;5737.971310143423;5739.454751798572;5796.797112101056;5915.231019967377;5917.881642367214;5930.11048342752;5952.0476723724;5952.970235148797;5475.561955043667;5608.523275521463;5774.48029677331;5795.7391917362975;5800.4489573490455;5628.60599758013;5661.773529058792;5663.858403539944;5672.829815105553;5712.171735098471;5643.32280126371;5683.687397193194;5691.345495267931;5691.516221121251;5721.22763426932;5571.9442805571425;5669.149627555899;5684.403137670485;5750.568707346882;5808.912866306924;5506.03644362227;5565.666060036411;5568.248286481273;5619.8595035124445;5639.351474580311 05:52:07 INFO - 2015-12-20 05:52:07,653 DEBUG : BROWSER_OUTPUT: |1;ext.html;546.879686468977;600.9218801555797;609.1715267236559;613.3375953969713;619.8915189841777;29952;38154;38673.41989870154;38679.226415471676;38710.22579548414;40641.796791016;40878;40967.59032409675;41197.588024119825;41302.38046429306;8460.788480287996;8689.478631282143;8760.561971901412;8765.737027889174;8768.035516093225;8549.829003419927;8607;8616.181462761027;8630.913690863104;8631.43895646784;8591.570421478933;8621.439606425594;8666.526690462637;8679.479231246114;8682.565871706389;8540.82918341636;8623.137686231326;8639.179277968582;8640.611172497222;8663.566821658891;8449.45078569891;8558.61486233121;8565.357598180162;8618.095100014534;8632.438891472097;38187.04532386685;38204;38351.61648383508;38423.61576384228;38563.8071809642;2997.8800847966054;3033.196203006205;3039.2705750619693;3279.754018448602;3332.800031998064;28378.29054273639;29055.41889162205;29096.563551546744;29277.414451710847;29643.851780741174;3606.855725770966;3615.367310720613;3656.561212654472;3724.0689827543115;3744.913974947262;234809;234868.82565587232;235070;235569.99999999828;237572;52715.73642131765;54120.45879541233;54280;54508.72745636326;54564;48535;63624;63902.36097639057;64358;64626;14788.63028424298;15022.549323520312;15045.473408430653;15071.623209419633;15083.095014299177;11408.315501069963;11489;11507.71230719222;11547.942260288644;11559.76880462386;18459.723104153447;18544.721829172573;18718.906405467886;18728.531786705167;18767.718484222747;7439.144498382647;7645.5794931279315;7734.685103532413;7762.844743105106;7769.572673503011;1025.6564051042835;1032.9070383665505;1057.154276578734;1060.4061725433783;1090.7218659242;27687.72312276892;27711;27731.306717331823;27930.44139117247;28212.576811347848;1023.7901230247782;1075.091547642241;1118.2954738514684;1140.766142940695;1185.8043422835308;12079.939600301943;13033.674158146123;13049.608511744664;13370.331483425789;13409.731805363837;290452;293281;293801;295542;295681;47452.525474745504;47755.28367074497;47915.281270780964;47997;48065.27902081471;1336.1248382309602;1364.9967274053563;1366.0983750724474;1370.095736813698;1370.136813807295;24617.753822461545;25657.61513577335;25774.226773196835;25894.35264118412;26006;40293.19413611653;40626.593734062284;40635.390469143575;40709;40763.184736304516;282885;282977;284278;284686;284779;29579.408411831213;31157.41689705713;31360.529592056595;31448.38343590425;31651.20871978219;36462;36898;37358.439623405124;37408.81295593505;37595.436068458446;31383.686163138078;31425.685743142276;31468.685313147493;33878.15304617404;34153.65846341505;33382.83308583441;33917;34232;35071;35194.64805352016;31084.22289442782;31367.372652546364;31370.215744605655;31474.37051258916;31590.684093158776;26856.32859178536;28830;30999.225019374695;31244.218894526908;31271.218219544695;23629.527409452057;24003.759962400152;24051.278461645477;24368.6344704826;24389.14637987733 05:52:07 INFO - 2015-12-20 05:52:07,653 DEBUG : BROWSER_OUTPUT: |2;jquery.html;350.70715952179927;357.10188874979434;358.0547354982844;358.39431360999924;361.154897539757;16149.919250403746;16947.83052169478;17387.304507819703;17655.38206162786;18078.186481608325;10755.300905441141;10788.892111078907;10809.783804323908;11048.06091482225;11072.169587280945;4993.176125939216;5013.197888337867;5061.493850614927;5078.162103252959;5645.096784514479;5575.442455754413;5584.636998595099;5593.161025846115;5598.832035038955;5623;4943.901121977558;5146.562542183919;5148.613853960949;5557.138643510242;5590.748416321255;5050.747462626854;5067.670601410897;5125.256837758541;5153.252778347138;5153.6907785532985;4932.062908047459;4946.455889852114;4957.900841983176;4958.851234462972;4983.501649835024;12118.575849845212;13108.2135071896;18058;19691.310804121786;19784.80215197844;1824.078840185711;1873.5035215667858;1944.2028768204968;1946.5912158446743;1978.8416926645834;8946.910530894673;9052.683156089572;9185.724428267162;9570.042995700443;11853.288802671867;1797.9460616181536;1855.5639424735177;1878.3425800969687;1914.2725764209652;1948.9220431182737;58735;59463;59718.40281597172;61788;63125.68437157831;9455.858162127573;10150.644727434505;10429.217808664229;10499.68500944973;10532.315399499008;13414.262215578041;13581.32093395346;13593.728125437436;13598.38807253676;13657.590272291649;7366.484346095829;7435.51669141504;7457;7628.54228746277;7812.726554570563;6950.3049695030095;6995.300469952964;7007.649617519103;7010.088688470466;7033.472489563201;4366.366876802846;4391.7584532851;4532.456105267389;4550.681452298308;4571.428571428572;1787.8480329171905;1828.1499102916941;1894.355918987551;1898.943031709051;1901.6577016137228;441.6488891331399;827.5531213144834;924.4268553496875;1005.5776573839005;1036.9837559191883;10220.233482488844;10684.893151068545;10734.624288150033;10769.138468922467;10931.070858976916;432.6171338365569;434.3463088052461;435.3317657395882;455.2465669317285;463.3443677196729;5664.348599910981;5985.461308482258;9714.854277185848;10294.536745846455;10314;530652;545548;546139;548998;559459;82509.58745206236;83519;84447.15552844392;85238.57380713057;85316;1168.404113901899;1181.0433548825479;1185.7569198314497;1191.8688944216128;1192.3859212505567;15477.5356739298;15546.300416481512;15653.21733913277;15671.138087405303;15837.604059898134;33277.83361083179;33720.83139584286;34949.30101398009;35346.11634709153;35431.64568354283;45551.31673024841;45749.31376029429;46401.30398043965;49513.75243123761;50647.240291396396;9412.67055653049;9602.375845570015;9652.131308182297;9675.564599592895;9888;10918.945405272923;10950.616728414467;11051.447427628747;11200.607978720705;15748.291326890547;14859.108453492825;14881.553553393416;15069.47256846005;15437.15095669704;15461.226938653248;14433.7834932474;14586.635334116732;14592.416303348173;15071.171085590398;15207.163606001775;15116.697666046837;15210.695786084438;15300.540983770505;15574.377024918876;15614.141222232432;9612.66355677548;9626.181774549243;9804.26468014916;9813.656522021694;9834.803303933739;10236.283460157718;13148.539801106916;14839.851601484279;15248.31382587764;15361.923190383977 05:52:07 INFO - 2015-12-20 05:52:07,654 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1487.5388629524841;1709.7264437689971;1723.6983527882617;1752.2377765671934;1760.322275923769;6764.32356764324;7128.821779455516;7282.963585182073;7288.307610776974;7293.525920815143;7253.891191632129;7294.708211671526;7352.48532602718;7356.485046046779;7359.227281135471;2135.4981579328846;2160.621891169047;2165.9241926532573;2166.794154555315;2174.8042676159143;2096.433962830035;2139.3795799218287;2178.292055082097;2183.661532462463;2194.8244140468805;2102.190656597206;2114.598226336999;2116.5237821490196;2153.0203757290396;2161.459635091227;2098.4229336932312;2117.597656445278;2147.6671115977047;2148.1622167354744;2148.334016454904;2102.190656597206;2112.207922029239;2130.60583791999;2137.316058861165;2140.5397839464435;7008.474364422688;7041.225465198824;7045.788626341218;7102.715891364339;7104.147502299757;4252.149570085995;4295.720778149442;4305.913881722379;4312.568743125694;4329.718568293082;2827.6889542150348;2879.43850949065;2882.30824602094;2917.139443864065;2924.6051783009448;932.9533523323875;962.821877952581;969.699393188114;979.294907666479;991.8760154980628;9574.664886729;9623.085806848409;9623.663171789023;9669.371490853073;9691.515424228832;3789.7347185696735;3809.3524100902914;3844.1735026969336;3849.40334248192;3877;7910.208979102159;7942.880856787153;7966.920330796734;7981.561014144162;7985.5208687478935;4197.811098500575;4208.116295577902;4214.346776249687;4219.198352313112;4224.38746381779;3824;3826.1582451860545;3837;3873.0317420644838;3886.8639597614515;2966.5401862711315;3035.089473158044;3043.7412819910555;3059.9082027538766;3066.47869862124;957.2437774158476;960.5485421851719;965.5799727118585;987.2200961240683;999.8300288950895;1034.4362322534237;1034.637876743149;1077.6982444915436;1170.1224081938547;1308.8756568126014;6671.199456065303;6698.56459330142;6725.562838415487;6727.394534491821;6733.888908330071;1028.3161697471098;1056.8784589772122;1120.6581992492206;1128.7629597784558;1128.8758236593967;5363.973180134074;5442.319710036246;5446.537044351196;5459.044667183227;5479.780808767605;8248.711295104642;8278.793030174294;8346.165383461726;8631.309495240366;8680.6527738891;8375.455595386402;8438.620262088221;8476.533790641573;8498.66005359791;8515.701950431829;6778.186617605918;6794.558353706993;6886.51794374399;6886.621235832077;6887.173539175231;6585.6377899216;6639.302873198438;6659.766908158192;6659.933400666125;6671.46628269747;7455.4035677146785;7661.88507172404;7676.769696909102;7707.460477766503;7810.804729881798;7587.279208475186;7691.307782299396;7820;7830;7901.249381308767;6119.418655227746;6156.692165391623;6180.165677633642;6217.813465596039;6227.283862355887;6802.081718967876;6894.758683446055;6948.9305106950305;6977.546459480118;6992.1259842519685;6574.901376479261;6596.934030659824;6660.866782664417;6705.932940670531;6767.492438067066;5917;6215.782447614311;6269.059641053878;6282.591631543935;6439.710213040331;6416.037594360883;6472.838179045561;6504.9674751625935;6512.511561632802;6538.934610654022;5528.7235638218735;5979.073243647242;6051.515878729779;6058.848528786815;6082.847928801815;6010.068439391726;6037.577369584087;6040.214772079602;6052.092186172109;6064.302605200459 05:52:07 INFO - 2015-12-20 05:52:07,655 DEBUG : BROWSER_OUTPUT: |4;prototype.html;296.62476966637206;300.73084589292574;310.97667674924384;314.2787303139294;326.5575145677607;6269.185005949226;6416.582922110059;6429;6437.420632143107;6453.677316134198;6311.936880631192;6322.336154703758;6332.493400527958;6335.239771227448;6345.206849143857;4649.093426781779;4709.552592503724;4740.1704701677245;4746.21687421575;4747.952520474785;4733.053389322132;4743.407074115736;4760.500147484516;4769.689970151946;4788.449328327235;4709.434867815867;4712.858614241578;4713.9629281558;4720.268358404435;4721.315409265671;4704.764761761915;4709.599684026844;4721.008588196466;4740.620750339965;4742.075295317414;4055.3105971984537;4094.160697057126;4094.529129150126;4097.836086556535;4103.4870641169855;5949.791757288474;6000.369961154103;6002.729877155539;6006.519478441758;6011.278646562397;5594.664320140804;5611.7474713637575;5615.129654903497;5615.719214039282;5620.325560932673;6707.128073350482;6708.865822683568;6712.899306510406;6713.563618364839;6727.159105111861;4559.316102584606;4616.39986801717;4621.537846215351;4636.907261854744;4648.697834640738;6929.1684997800085;6993.05593744843;6994.195667498252;7000.684969176399;7016.052832867548;4671.883202919954;4679.344891715163;4695.460022097434;4730;4731.432228132646;4697.859064228079;4777.092352453093;4779.115863565252;4794.856154315376;4803.159447096744;5555.666660000413;5565.526930210977;5566.443355664401;5566.55467562594;5594.944050559524;6738.59568425896;6806.183258008971;6807.217170025411;6811.1145551079035;6814.965925170442;1658.5853536615846;1686.8903521271322;1696.9575760606083;1700.396359292458;1703.5485596316985;1116.5868628607468;1118.994405027986;1123.6235860986499;1123.7078359626555;1144.719543711796;6381.936180638134;6475.352464753487;6479.708413121421;6484.383983521558;6509;7044.788656340318;7064.682089305891;7078.469114816409;7078.469114816409;7103.573785572882;6462.095306657072;6497.512686548528;6514.3811337922825;6519.674016299261;6532.836679082966;3762.2099359134886;3791.3933770596573;3814.1418180909072;3828.559715632682;3845.6155783917766;7191.676374563157;7218.422526197892;7242.710291588278;7252.782416527512;7264.455165862581;7263.455240856957;7274.418046556263;7286.672099755524;7290.307420795122;7321.890171647429;7010.614416207056;7191.676374563052;7217.350438460564;7221.277872212737;7252.02097716812;7021.648917554204;7024.297570242935;7024.438044956289;7037.964810175917;7038.8944165838575;6956.617386043817;6970.546914450544;6974.37230649244;7002.0547226125855;7004.789856304319;6931.861362772817;7161.534500257467;7229.277072292939;7243.891341629775;7266;4687.648426368104;4752.9287060694805;4760.857174284777;4763.595094417014;4768.973186395699;7040.542364746275;7048.682809273492;7086.503944724026;7097.396721278543;7105.715771369088;6727.260001399744;6759.966200168968;6805.727770889109;6818.897716534355;6883.7590684325805;6789.388954994074;6830.692618832065;6852.794416167523;6856.24581296067;6866.34769696878;6577.276499585138;6694.933050669431;6719.899201511884;6729.057931889637;6733.158355205599;6715.294894036056;6899.93100069013;6910.032595436744;6912.377885990285;6925.065116209247;6640.435562977201;6717.2946840581035;6741.797746067626;6757.054012438164;6764.695588698619 05:52:07 INFO - 2015-12-20 05:52:07,655 DEBUG : BROWSER_OUTPUT: |5;yui.html;764.8049747314436;768.6694721269852;822.1244374740896;844.6325848256005;869.3479890082439;13038.543650972219;13152.539661111863;13291.867081329185;13367.131136476122;13415;12841.762218467906;13480.865191348084;13486.730265394686;13501.864981350183;13528.391222394992;822.9547374894394;823.4524041512387;835.7952301686096;836.1262480707675;837.1879277101202;832.2218725491684;838.8322335532887;844.0167205205959;844.8099177685033;847.1274587175219;862.9827403451927;870.2820173356974;872.0973792125152;873.2010210657241;876.8903887014123;862.6808081010035;864.6714248585529;866.5710473315718;867.9913200867974;870.4211699220011;632.9525285603552;641.9679016049179;643.3309358267396;645.2257291250518;645.7062036773257;13795.172289662552;13866.029377943549;13897.861021389757;13929.721405571932;13933.442662293495;12293.385330733518;12454.190477619017;12632.49470021198;12890.097693161482;13009.658261512925;10181.745456363577;10206.438645874474;10208.183345332356;10249.590016399336;10444.373337599693;3635.381985062546;3665.321915445651;3683.5027271318295;3701.0747313171705;3793.165503589206;47109.52890471086;47509.524904750855;47535.52464475346;47616.52383476121;47638.28542571864;9961.203103751683;10125.696229113139;10145.543450544743;10205.94897025525;10251.28241023136;17801.109944502725;17836.286548538174;17836.375726849757;17851.196696148705;17865.374711885022;7701.614919254015;7828.804279893048;8051.5571643559;8060.193980601893;8063.677452901936;4451.799669014902;4663.230566956414;4679.087577922272;4719.292106184032;4730.787114579852;3030.1515575638855;3049.969500305013;3056.43455960648;3058.938821223563;3065.8926937557526;533.7358007786171;540.6215649045683;541.2746919128386;546.1234718277187;557.8103444828778;648.7178077536287;651.4169818012851;683.5625199872086;684.9554778939353;698.8113209433425;8477.194666506672;8606.139386061344;8754.068014211854;8832.8675069874;9003.099690031075;573.2748073686789;575.8704291534372;637.2030872938269;700.961447120403;707.1549498349423;4128.690348223835;4342.413774140514;4367.934480982788;4400.471943366752;4402.823887044482;53397;53622.7318863411;53874.730626346616;54061.45938540643;55125.44874551203;49714.50285497171;50200.49799502031;50948.74525627348;51139.744301279;51767;44480.77759611181;45143.548564513934;45472;45650;46003;8426.789330266547;8724.738257852274;8726.214640682369;8726.214640682369;8993.32550058735;32708.34583308273;33050.17374565655;33426;33831.15422114368;34487.65512344844;41877.162456750084;41937.79031104825;41949.58050419579;42195.7890210547;43166.56833431751;11193.720156995814;11216.270942388719;11222.158338124837;11450.885491145315;11602.535898563963;12823.743525129632;12873.549425770054;13058.608241752763;13241.536546220836;13632.65918352049;11241.775164496828;11423.771524569296;11840.05279577649;12412.62762117138;12936.547220847226;11776.234544754563;11884;12145.939270303592;12166.57416990401;12295.877041229473;11071.72320691989;11087.889121108685;11679.474423650787;11905.82141267899;12083.275003499819;9557.283203759607;9783.88455430507;9892;10259.230557708053;10316.793664126826;9257.544530402185;9864;10048.100500819826;10084.445355505517;10161.441120738134 05:52:07 INFO - 2015-12-20 05:52:07,655 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:52:07 INFO - 2015-12-20 05:52:07,656 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:52:07 INFO - 2015-12-20 05:52:07,656 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,299 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: __startTimestamp1450619527645__endTimestamp 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:52:07 INFO - 2015-12-20 05:52:07,657 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5416.30 Median:5619.86 stddev:595.61 (10.6%) stddev-sans-first:571.02 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: Values: 3179.9 3237.9 3238.5 3258.5 3276.3 5168.6 5380.2 5389.6 5426.1 5430.9 5265.8 5346.7 5369.7 5382.7 5389.3 4913.1 4983.1 5024.9 5044.3 5049.9 4901.0 4962.7 5006.9 5033.5 5083.5 4842.5 4912.0 4946.8 5033.6 5043.6 4970.2 4993.6 4994.9 4996.2 5006.1 4993.9 5006.3 5008.9 5009.8 5015.1 5113.1 5193.0 5265.0 5267.1 5344.7 5740.9 5837.2 5848.4 5874.6 5910.4 5884.5 5937.0 5945.2 5954.7 5973.2 5321.8 5413.3 5544.8 5550.4 5560.3 5792.7 5878.6 5881.5 5886.7 5937.0 5811.5 5812.9 5815.1 5822.7 5825.9 5766.8 5782.2 5845.0 5881.1 5908.7 5870.6 5900.0 5913.1 5914.1 5926.1 5853.1 5854.1 5890.0 5891.7 5897.0 4392.3 4462.5 4473.2 4477.6 4540.5 4014.3 4023.2 4023.2 4025.7 4028.7 5487.6 5500.9 5512.6 5544.9 5562.6 5603.4 5738.7 5832.0 5955.7 5970.9 5508.5 5518.8 5544.8 5574.9 5582.5 5066.5 5107.6 5112.5 5115.5 5163.9 5708.1 5797.7 5892.8 5981.8 6015.8 5717.8 5817.9 5834.5 5841.1 5891.4 5738.8 5851.2 5855.6 5868.2 5891.0 5677.2 5736.2 5738.0 5739.5 5796.8 5915.2 5917.9 5930.1 5952.0 5953.0 5475.6 5608.5 5774.5 5795.7 5800.4 5628.6 5661.8 5663.9 5672.8 5712.2 5643.3 5683.7 5691.3 5691.5 5721.2 5571.9 5669.1 5684.4 5750.6 5808.9 5506.0 5565.7 5568.2 5619.9 5639.4 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43211.93 Median:31620.95 stddev:71098.59 (224.8%) stddev-sans-first:71225.87 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: Values: 546.9 600.9 609.2 613.3 619.9 29952.0 38154.0 38673.4 38679.2 38710.2 40641.8 40878.0 40967.6 41197.6 41302.4 8460.8 8689.5 8760.6 8765.7 8768.0 8549.8 8607.0 8616.2 8630.9 8631.4 8591.6 8621.4 8666.5 8679.5 8682.6 8540.8 8623.1 8639.2 8640.6 8663.6 8449.5 8558.6 8565.4 8618.1 8632.4 38187.0 38204.0 38351.6 38423.6 38563.8 2997.9 3033.2 3039.3 3279.8 3332.8 28378.3 29055.4 29096.6 29277.4 29643.9 3606.9 3615.4 3656.6 3724.1 3744.9 234809.0 234868.8 235070.0 235570.0 237572.0 52715.7 54120.5 54280.0 54508.7 54564.0 48535.0 63624.0 63902.4 64358.0 64626.0 14788.6 15022.5 15045.5 15071.6 15083.1 11408.3 11489.0 11507.7 11547.9 11559.8 18459.7 18544.7 18718.9 18728.5 18767.7 7439.1 7645.6 7734.7 7762.8 7769.6 1025.7 1032.9 1057.2 1060.4 1090.7 27687.7 27711.0 27731.3 27930.4 28212.6 1023.8 1075.1 1118.3 1140.8 1185.8 12079.9 13033.7 13049.6 13370.3 13409.7 290452.0 293281.0 293801.0 295542.0 295681.0 47452.5 47755.3 47915.3 47997.0 48065.3 1336.1 1365.0 1366.1 1370.1 1370.1 24617.8 25657.6 25774.2 25894.4 26006.0 40293.2 40626.6 40635.4 40709.0 40763.2 282885.0 282977.0 284278.0 284686.0 284779.0 29579.4 31157.4 31360.5 31448.4 31651.2 36462.0 36898.0 37358.4 37408.8 37595.4 31383.7 31425.7 31468.7 33878.2 34153.7 33382.8 33917.0 34232.0 35071.0 35194.6 31084.2 31367.4 31370.2 31474.4 31590.7 26856.3 28830.0 30999.2 31244.2 31271.2 23629.5 24003.8 24051.3 24368.6 24389.1 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,658 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28609.95 Median:357.58 stddev:89401.96 (25002.1%) stddev-sans-first:89627.57 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: Values: 350.7 357.1 358.1 358.4 361.2 16149.9 16947.8 17387.3 17655.4 18078.2 10755.3 10788.9 10809.8 11048.1 11072.2 4993.2 5013.2 5061.5 5078.2 5645.1 5575.4 5584.6 5593.2 5598.8 5623.0 4943.9 5146.6 5148.6 5557.1 5590.7 5050.7 5067.7 5125.3 5153.3 5153.7 4932.1 4946.5 4957.9 4958.9 4983.5 12118.6 13108.2 18058.0 19691.3 19784.8 1824.1 1873.5 1944.2 1946.6 1978.8 8946.9 9052.7 9185.7 9570.0 11853.3 1797.9 1855.6 1878.3 1914.3 1948.9 58735.0 59463.0 59718.4 61788.0 63125.7 9455.9 10150.6 10429.2 10499.7 10532.3 13414.3 13581.3 13593.7 13598.4 13657.6 7366.5 7435.5 7457.0 7628.5 7812.7 6950.3 6995.3 7007.6 7010.1 7033.5 4366.4 4391.8 4532.5 4550.7 4571.4 1787.8 1828.1 1894.4 1898.9 1901.7 441.6 827.6 924.4 1005.6 1037.0 10220.2 10684.9 10734.6 10769.1 10931.1 432.6 434.3 435.3 455.2 463.3 5664.3 5985.5 9714.9 10294.5 10314.0 530652.0 545548.0 546139.0 548998.0 559459.0 82509.6 83519.0 84447.2 85238.6 85316.0 1168.4 1181.0 1185.8 1191.9 1192.4 15477.5 15546.3 15653.2 15671.1 15837.6 33277.8 33720.8 34949.3 35346.1 35431.6 45551.3 45749.3 46401.3 49513.8 50647.2 9412.7 9602.4 9652.1 9675.6 9888.0 10918.9 10950.6 11051.4 11200.6 15748.3 14859.1 14881.6 15069.5 15437.2 15461.2 14433.8 14586.6 14592.4 15071.2 15207.2 15116.7 15210.7 15300.5 15574.4 15614.1 9612.7 9626.2 9804.3 9813.7 9834.8 10236.3 13148.5 14839.9 15248.3 15361.9 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5006.17 Median:6222.55 stddev:2549.88 (41.0%) stddev-sans-first:2543.32 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: Values: 1487.5 1709.7 1723.7 1752.2 1760.3 6764.3 7128.8 7283.0 7288.3 7293.5 7253.9 7294.7 7352.5 7356.5 7359.2 2135.5 2160.6 2165.9 2166.8 2174.8 2096.4 2139.4 2178.3 2183.7 2194.8 2102.2 2114.6 2116.5 2153.0 2161.5 2098.4 2117.6 2147.7 2148.2 2148.3 2102.2 2112.2 2130.6 2137.3 2140.5 7008.5 7041.2 7045.8 7102.7 7104.1 4252.1 4295.7 4305.9 4312.6 4329.7 2827.7 2879.4 2882.3 2917.1 2924.6 933.0 962.8 969.7 979.3 991.9 9574.7 9623.1 9623.7 9669.4 9691.5 3789.7 3809.4 3844.2 3849.4 3877.0 7910.2 7942.9 7966.9 7981.6 7985.5 4197.8 4208.1 4214.3 4219.2 4224.4 3824.0 3826.2 3837.0 3873.0 3886.9 2966.5 3035.1 3043.7 3059.9 3066.5 957.2 960.5 965.6 987.2 999.8 1034.4 1034.6 1077.7 1170.1 1308.9 6671.2 6698.6 6725.6 6727.4 6733.9 1028.3 1056.9 1120.7 1128.8 1128.9 5364.0 5442.3 5446.5 5459.0 5479.8 8248.7 8278.8 8346.2 8631.3 8680.7 8375.5 8438.6 8476.5 8498.7 8515.7 6778.2 6794.6 6886.5 6886.6 6887.2 6585.6 6639.3 6659.8 6659.9 6671.5 7455.4 7661.9 7676.8 7707.5 7810.8 7587.3 7691.3 7820.0 7830.0 7901.2 6119.4 6156.7 6180.2 6217.8 6227.3 6802.1 6894.8 6948.9 6977.5 6992.1 6574.9 6596.9 6660.9 6705.9 6767.5 5917.0 6215.8 6269.1 6282.6 6439.7 6416.0 6472.8 6505.0 6512.5 6538.9 5528.7 5979.1 6051.5 6058.8 6082.8 6010.1 6037.6 6040.2 6052.1 6064.3 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,660 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5641.70 Median:6457.89 stddev:1739.44 (26.9%) stddev-sans-first:1697.43 05:52:07 INFO - 2015-12-20 05:52:07,661 DEBUG : BROWSER_OUTPUT: Values: 296.6 300.7 311.0 314.3 326.6 6269.2 6416.6 6429.0 6437.4 6453.7 6311.9 6322.3 6332.5 6335.2 6345.2 4649.1 4709.6 4740.2 4746.2 4748.0 4733.1 4743.4 4760.5 4769.7 4788.4 4709.4 4712.9 4714.0 4720.3 4721.3 4704.8 4709.6 4721.0 4740.6 4742.1 4055.3 4094.2 4094.5 4097.8 4103.5 5949.8 6000.4 6002.7 6006.5 6011.3 5594.7 5611.7 5615.1 5615.7 5620.3 6707.1 6708.9 6712.9 6713.6 6727.2 4559.3 4616.4 4621.5 4636.9 4648.7 6929.2 6993.1 6994.2 7000.7 7016.1 4671.9 4679.3 4695.5 4730.0 4731.4 4697.9 4777.1 4779.1 4794.9 4803.2 5555.7 5565.5 5566.4 5566.6 5594.9 6738.6 6806.2 6807.2 6811.1 6815.0 1658.6 1686.9 1697.0 1700.4 1703.5 1116.6 1119.0 1123.6 1123.7 1144.7 6381.9 6475.4 6479.7 6484.4 6509.0 7044.8 7064.7 7078.5 7078.5 7103.6 6462.1 6497.5 6514.4 6519.7 6532.8 3762.2 3791.4 3814.1 3828.6 3845.6 7191.7 7218.4 7242.7 7252.8 7264.5 7263.5 7274.4 7286.7 7290.3 7321.9 7010.6 7191.7 7217.4 7221.3 7252.0 7021.6 7024.3 7024.4 7038.0 7038.9 6956.6 6970.5 6974.4 7002.1 7004.8 6931.9 7161.5 7229.3 7243.9 7266.0 4687.6 4752.9 4760.9 4763.6 4769.0 7040.5 7048.7 7086.5 7097.4 7105.7 6727.3 6760.0 6805.7 6818.9 6883.8 6789.4 6830.7 6852.8 6856.2 6866.3 6577.3 6694.9 6719.9 6729.1 6733.2 6715.3 6899.9 6910.0 6912.4 6925.1 6640.4 6717.3 6741.8 6757.1 6764.7 05:52:07 INFO - 2015-12-20 05:52:07,662 DEBUG : BROWSER_OUTPUT: 05:52:07 INFO - 2015-12-20 05:52:07,662 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13679.57 Median:24797.67 stddev:15330.52 (61.8%) stddev-sans-first:15342.85 05:52:07 INFO - 2015-12-20 05:52:07,662 DEBUG : BROWSER_OUTPUT: Values: 764.8 768.7 822.1 844.6 869.3 13038.5 13152.5 13291.9 13367.1 13415.0 12841.8 13480.9 13486.7 13501.9 13528.4 823.0 823.5 835.8 836.1 837.2 832.2 838.8 844.0 844.8 847.1 863.0 870.3 872.1 873.2 876.9 862.7 864.7 866.6 868.0 870.4 633.0 642.0 643.3 645.2 645.7 13795.2 13866.0 13897.9 13929.7 13933.4 12293.4 12454.2 12632.5 12890.1 13009.7 10181.7 10206.4 10208.2 10249.6 10444.4 3635.4 3665.3 3683.5 3701.1 3793.2 47109.5 47509.5 47535.5 47616.5 47638.3 9961.2 10125.7 10145.5 10205.9 10251.3 17801.1 17836.3 17836.4 17851.2 17865.4 7701.6 7828.8 8051.6 8060.2 8063.7 4451.8 4663.2 4679.1 4719.3 4730.8 3030.2 3050.0 3056.4 3058.9 3065.9 533.7 540.6 541.3 546.1 557.8 648.7 651.4 683.6 685.0 698.8 8477.2 8606.1 8754.1 8832.9 9003.1 573.3 575.9 637.2 701.0 707.2 4128.7 4342.4 4367.9 4400.5 4402.8 53397.0 53622.7 53874.7 54061.5 55125.4 49714.5 50200.5 50948.7 51139.7 51767.0 44480.8 45143.5 45472.0 45650.0 46003.0 8426.8 8724.7 8726.2 8726.2 8993.3 32708.3 33050.2 33426.0 33831.2 34487.7 41877.2 41937.8 41949.6 42195.8 43166.6 11193.7 11216.3 11222.2 11450.9 11602.5 12823.7 12873.5 13058.6 13241.5 13632.7 11241.8 11423.8 11840.1 12412.6 12936.5 11776.2 11884.0 12145.9 12166.6 12295.9 11071.7 11087.9 11679.5 11905.8 12083.3 9557.3 9783.9 9892.0 10259.2 10316.8 9257.5 9864.0 10048.1 10084.4 10161.4 05:52:07 INFO - 2015-12-20 05:52:07,662 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:52:07 INFO - 2015-12-20 05:52:07,662 DEBUG : BROWSER_OUTPUT: 05:52:08 INFO - 2015-12-20 05:52:08,371 INFO : Browser exited with error code: 0 05:52:08 INFO - 2015-12-20 05:52:08,392 INFO : Completed test dromaeo_css (00:18:31) 05:52:08 INFO - 2015-12-20 05:52:08,392 INFO : Starting test kraken 05:52:08 INFO - 2015-12-20 05:52:08,392 DEBUG : operating with platform_type : linux_ 05:52:08 INFO - 2015-12-20 05:52:08,392 INFO : Initialising browser for kraken test... 05:52:08 INFO - 2015-12-20 05:52:08,398 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSZ_otO/profile http://localhost:34486/getInfo.html 05:52:13 INFO - 2015-12-20 05:52:13,162 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:52:13 INFO - 2015-12-20 05:52:13,162 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:52:13 INFO - 2015-12-20 05:52:13,162 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:52:13 INFO - 2015-12-20 05:52:13,162 DEBUG : BROWSER_OUTPUT: __metrics 05:52:13 INFO - 2015-12-20 05:52:13,243 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] 05:52:14 INFO - 2015-12-20 05:52:14,098 INFO : Browser initialized. 05:52:14 INFO - 2015-12-20 05:52:14,099 INFO : Running cycle 1/1 for kraken test... 05:52:14 INFO - 2015-12-20 05:52:14,099 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSZ_otO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:52:25 INFO - 2015-12-20 05:52:25,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 151273472 05:52:25 INFO - 2015-12-20 05:52:25,900 DEBUG : BROWSER_OUTPUT: 05:52:28 INFO - 2015-12-20 05:52:28,383 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34486/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:28 INFO - 2015-12-20 05:52:28,985 DEBUG : BROWSER_OUTPUT: RSS: Main: 152666112 05:52:28 INFO - 2015-12-20 05:52:28,985 DEBUG : BROWSER_OUTPUT: 05:52:31 INFO - 2015-12-20 05:52:31,612 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34486/tests/kraken/driver.html?testName=audio-dft) 05:52:32 INFO - 2015-12-20 05:52:32,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 152014848 05:52:32 INFO - 2015-12-20 05:52:32,201 DEBUG : BROWSER_OUTPUT: 05:52:35 INFO - 2015-12-20 05:52:35,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34486/tests/kraken/driver.html?testName=audio-fft) 05:52:35 INFO - 2015-12-20 05:52:35,637 DEBUG : BROWSER_OUTPUT: RSS: Main: 146620416 05:52:35 INFO - 2015-12-20 05:52:35,637 DEBUG : BROWSER_OUTPUT: 05:52:37 INFO - 2015-12-20 05:52:37,770 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34486/tests/kraken/driver.html?testName=audio-oscillator) 05:52:38 INFO - 2015-12-20 05:52:38,392 DEBUG : BROWSER_OUTPUT: RSS: Main: 146186240 05:52:38 INFO - 2015-12-20 05:52:38,392 DEBUG : BROWSER_OUTPUT: 05:52:40 INFO - 2015-12-20 05:52:40,633 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:41 INFO - 2015-12-20 05:52:41,215 DEBUG : BROWSER_OUTPUT: RSS: Main: 147156992 05:52:41 INFO - 2015-12-20 05:52:41,215 DEBUG : BROWSER_OUTPUT: 05:52:44 INFO - 2015-12-20 05:52:44,199 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34486/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:44 INFO - 2015-12-20 05:52:44,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 147546112 05:52:44 INFO - 2015-12-20 05:52:44,785 DEBUG : BROWSER_OUTPUT: 05:52:48 INFO - 2015-12-20 05:52:48,211 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34486/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:48 INFO - 2015-12-20 05:52:48,805 DEBUG : BROWSER_OUTPUT: RSS: Main: 148680704 05:52:48 INFO - 2015-12-20 05:52:48,805 DEBUG : BROWSER_OUTPUT: 05:52:51 INFO - 2015-12-20 05:52:51,588 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34486/tests/kraken/driver.html?testName=json-parse-financial) 05:52:52 INFO - 2015-12-20 05:52:52,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 149467136 05:52:52 INFO - 2015-12-20 05:52:52,182 DEBUG : BROWSER_OUTPUT: 05:52:53 INFO - 2015-12-20 05:52:53,778 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:54 INFO - 2015-12-20 05:52:54,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 149729280 05:52:54 INFO - 2015-12-20 05:52:54,364 DEBUG : BROWSER_OUTPUT: 05:52:56 INFO - 2015-12-20 05:52:56,200 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:56 INFO - 2015-12-20 05:52:56,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 148598784 05:52:56 INFO - 2015-12-20 05:52:56,802 DEBUG : BROWSER_OUTPUT: 05:52:58 INFO - 2015-12-20 05:52:58,906 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:59 INFO - 2015-12-20 05:52:59,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 148881408 05:52:59 INFO - 2015-12-20 05:52:59,499 DEBUG : BROWSER_OUTPUT: 05:53:01 INFO - 2015-12-20 05:53:01,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:53:02 INFO - 2015-12-20 05:53:02,548 DEBUG : BROWSER_OUTPUT: RSS: Main: 148754432 05:53:02 INFO - 2015-12-20 05:53:02,548 DEBUG : BROWSER_OUTPUT: 05:53:05 INFO - 2015-12-20 05:53:05,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:53:05 INFO - 2015-12-20 05:53:05,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 148746240 05:53:05 INFO - 2015-12-20 05:53:05,736 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34486/tests/kraken/driver.html?testName=ai-astar) 05:53:07 INFO - 2015-12-20 05:53:07,649 DEBUG : BROWSER_OUTPUT: RSS: Main: 149606400 05:53:07 INFO - 2015-12-20 05:53:07,649 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;105;113;111;114;122;113;112;109;111 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;131;150;163;124;157;150;128;169;133 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |2;audio-dft;166;163;161;162;162;161;161;163;172;162 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |3;audio-fft;74;71;73;74;73;73;130;72;74;70 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;138;94;95;93;140;94;93;141 05:53:07 INFO - 2015-12-20 05:53:07,650 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;110;110;110;109;110;110 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;149;146;147;147;147;146;146;146;146 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;98;95;96;97;96;96;95;96;97 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;60;63;61;67;61;63;61;58;59 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;68;68;68;69;68;69;68 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;86;94;94;85;85;86;86;86;93 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;121;123;123;119;132;127;123;117;121 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;153;166;147;153;143;154;153;154;142 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;66;70;69;66;65;66;66;68;68 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,720 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: __startTimestamp1450619587644__endTimestamp 05:53:07 INFO - 2015-12-20 05:53:07,651 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:4.30 (3.8%) stddev-sans-first:4.55 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: Values: 113.0 105.0 113.0 111.0 114.0 122.0 113.0 112.0 109.0 111.0 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.00 Median:152.50 stddev:15.83 (10.4%) stddev-sans-first:16.45 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: Values: 155.0 131.0 150.0 163.0 124.0 157.0 150.0 128.0 169.0 133.0 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:3.40 (2.1%) stddev-sans-first:3.46 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: Values: 166.0 163.0 161.0 162.0 162.0 161.0 161.0 163.0 172.0 162.0 05:53:07 INFO - 2015-12-20 05:53:07,653 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.40 Median:73.00 stddev:18.18 (24.9%) stddev-sans-first:19.21 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 73.0 74.0 73.0 73.0 130.0 72.0 74.0 70.0 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.70 Median:94.00 stddev:22.08 (23.5%) stddev-sans-first:22.86 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 138.0 94.0 95.0 93.0 140.0 94.0 93.0 141.0 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,654 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:1.03 (0.7%) stddev-sans-first:1.00 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: Values: 148.0 149.0 146.0 147.0 147.0 147.0 146.0 146.0 146.0 146.0 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: Values: 96.0 98.0 95.0 96.0 97.0 96.0 96.0 95.0 96.0 97.0 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:2.50 (4.1%) stddev-sans-first:2.65 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: Values: 61.0 60.0 63.0 61.0 67.0 61.0 63.0 61.0 58.0 59.0 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 68.0 68.0 69.0 68.0 69.0 68.0 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:86.00 stddev:4.04 (4.7%) stddev-sans-first:4.03 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: Values: 84.0 86.0 94.0 94.0 85.0 85.0 86.0 86.0 86.0 93.0 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,655 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:123.00 stddev:4.71 (3.8%) stddev-sans-first:4.43 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: Values: 116.0 121.0 123.0 123.0 119.0 132.0 127.0 123.0 117.0 121.0 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:153.00 stddev:6.92 (4.5%) stddev-sans-first:7.18 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: Values: 147.0 153.0 166.0 147.0 153.0 143.0 154.0 153.0 154.0 142.0 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.60 (2.4%) stddev-sans-first:1.69 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: Values: 67.0 66.0 70.0 69.0 66.0 65.0 66.0 66.0 68.0 68.0 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:53:07 INFO - 2015-12-20 05:53:07,656 DEBUG : BROWSER_OUTPUT: 05:53:08 INFO - 2015-12-20 05:53:08,450 INFO : Browser exited with error code: 0 05:53:08 INFO - 2015-12-20 05:53:08,504 INFO : Completed test kraken (00:01:00) 05:53:08 INFO - 2015-12-20 05:53:08,504 INFO : Starting test v8_7 05:53:08 INFO - 2015-12-20 05:53:08,504 DEBUG : operating with platform_type : linux_ 05:53:08 INFO - 2015-12-20 05:53:08,504 INFO : Initialising browser for v8_7 test... 05:53:08 INFO - 2015-12-20 05:53:08,510 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZtXvmk/profile http://localhost:34486/getInfo.html 05:53:13 INFO - 2015-12-20 05:53:13,177 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:53:13 INFO - 2015-12-20 05:53:13,178 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:53:13 INFO - 2015-12-20 05:53:13,178 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:53:13 INFO - 2015-12-20 05:53:13,178 DEBUG : BROWSER_OUTPUT: __metrics 05:53:13 INFO - 2015-12-20 05:53:13,306 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] 05:53:14 INFO - 2015-12-20 05:53:14,158 INFO : Browser initialized. 05:53:14 INFO - 2015-12-20 05:53:14,159 INFO : Running cycle 1/1 for v8_7 test... 05:53:14 INFO - 2015-12-20 05:53:14,159 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZtXvmk/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:53:25 INFO - 2015-12-20 05:53:25,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 151158784 05:53:25 INFO - 2015-12-20 05:53:25,957 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,359 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34486/tests/v8_7/run.html (next: http://localhost:34486/tests/v8_7/run.html) 05:53:47 INFO - 2015-12-20 05:53:47,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 147189760 05:53:47 INFO - 2015-12-20 05:53:47,609 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,610 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:53:47 INFO - 2015-12-20 05:53:47,610 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |0;Richards;166.16816218012627 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.89378288832586 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.3332385555872 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5154.639175257732 05:53:47 INFO - 2015-12-20 05:53:47,611 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1085.7763300760043 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: |5;Earley;756.6137566137567 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: |6;Boyer;12219.512195121952 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: |7;RegExp;25666.666666666668 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: |8;Splay;645.385587863464 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:53:47 INFO - 2015-12-20 05:53:47,612 DEBUG : BROWSER_OUTPUT: __startTimestamp1450619627609__endTimestamp 05:53:47 INFO - 2015-12-20 05:53:47,613 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,613 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:53:47 INFO - 2015-12-20 05:53:47,614 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:53:47 INFO - 2015-12-20 05:53:47,614 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,614 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.17 Median:166.17 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,615 DEBUG : BROWSER_OUTPUT: Values: 166.2 05:53:47 INFO - 2015-12-20 05:53:47,615 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,615 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.89 Median:194.89 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,615 DEBUG : BROWSER_OUTPUT: Values: 194.9 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.33 Median:284.33 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: Values: 284.3 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5154.64 Median:5154.64 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,616 DEBUG : BROWSER_OUTPUT: Values: 5154.6 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1085.78 Median:1085.78 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: Values: 1085.8 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:756.61 Median:756.61 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: Values: 756.6 05:53:47 INFO - 2015-12-20 05:53:47,617 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: Values: 12219.5 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25666.67 Median:25666.67 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: Values: 25666.7 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,618 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:645.39 Median:645.39 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: Values: 645.4 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:53:47 INFO - 2015-12-20 05:53:47,619 DEBUG : BROWSER_OUTPUT: 05:53:48 INFO - 2015-12-20 05:53:48,450 INFO : Browser exited with error code: 0 05:53:48 INFO - 2015-12-20 05:53:48,465 INFO : Completed test v8_7 (00:00:39) 05:53:48 INFO - 2015-12-20 05:53:48,618 INFO : Completed test suite (00:20:11) 05:53:48 INFO - 2015-12-20 05:53:48,618 DEBUG : Working with test: dromaeo_css 05:53:48 INFO - 2015-12-20 05:53:48,618 DEBUG : Generating results file: dromaeo_css 05:53:48 INFO - 2015-12-20 05:53:48,619 DEBUG : Working with test: kraken 05:53:48 INFO - 2015-12-20 05:53:48,619 DEBUG : Generating results file: kraken 05:53:48 INFO - 2015-12-20 05:53:48,620 INFO : javascript benchmark 05:53:48 INFO - 2015-12-20 05:53:48,620 DEBUG : Working with test: v8_7 05:53:48 INFO - 2015-12-20 05:53:48,620 DEBUG : Generating results file: v8_7 05:53:48 INFO - 2015-12-20 05:53:48,620 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:53:48 INFO - 2015-12-20 05:53:48,758 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 05:53:48 INFO - 2015-12-20 05:53:48,758 DEBUG : process_Request line: dromaeo_css 6879.71 graph.html#tests=[[72,1,43]] 05:53:48 INFO - 2015-12-20 05:53:48,758 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:53:48 INFO - 2015-12-20 05:53:48,797 DEBUG : process_Request line: kraken 1518.60 graph.html#tests=[[232,1,43]] 05:53:48 INFO - 2015-12-20 05:53:48,797 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:53:48 INFO - 2015-12-20 05:53:48,831 DEBUG : process_Request line: v8_7 20957.57 graph.html#tests=[[230,1,43]] 05:53:48 INFO - 2015-12-20 05:53:48,833 INFO : javascript benchmark 05:53:48 INFO - 2015-12-20 05:53:48,837 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3179.85690643921, 3237.8866739664118, 3238.514222866568, 3258.543803867459, 3276.3119744853593, 5168.560672342846, 5380.166074258486, 5389.622726409153, 5426.077566813641, 5430.91853622196, 5265.842024739254, 5346.732663366826, 5369.650972686773, 5382.650127741704, 5389.326334208224, 4913.140200464923, 4983.078130545842, 5024.874378140539, 5044.2937988681615, 5049.924251136236, 4900.9754951225195, 4962.702237865721, 5006.874828129289, 5033.471485494026, 5083.517065878736, 4842.467328593852, 4911.993041426499, 4946.752662366867, 5033.597312215014, 5043.646944713872, 4970.179920313144, 4993.550580447741, 4994.850154495371, 4996.1756310208775, 5006.148954677714, 4993.875153121165, 5006.299118123466, 5008.924866127011, 5009.849704508871, 5015.072211640858, 5113.054084994288, 5192.9740351298005, 5264.973675131638, 5267.051930652481, 5344.652597581145, 5740.8851822963725, 5837.153612726173, 5848.356680765155, 5874.5887787854435, 5910.438508341701, 5884.529237660977, 5936.97031514844, 5945.197398351211, 5954.702264886781, 5973.223480947449, 5321.787128514893, 5413.296271484761, 5544.77820887164, 5550.4449555044575, 5560.332760068777, 5792.681402522901, 5878.617889837147, 5881.500072493886, 5886.705664716747, 5937.0, 5811.506021988178, 5812.941870581325, 5815.069588865761, 5822.737976791054, 5825.941740582624, 5766.769329226868, 5782.190493330937, 5844.970775146182, 5881.059030555176, 5908.675022873783, 5870.647761134345, 5899.970500147472, 5913.05391137416, 5914.083317085859, 5926.111083337534, 5853.09277062056, 5854.092615644582, 5890.0, 5891.675957822275, 5896.970515147397, 4392.2752745797225, 4462.486814016364, 4473.239549276631, 4477.552244775497, 4540.455145382575, 4014.297497937849, 4023.1752490739327, 4023.215472982799, 4025.7383270087353, 4028.6575641070835, 5487.64330318528, 5500.944990550123, 5512.586556008316, 5544.889102217933, 5562.582806289463, 5603.439656034364, 5738.655680659091, 5831.979403604437, 5955.67243801595, 5970.940290597038, 5508.504234618904, 5518.779248830082, 5544.805931792449, 5574.944250557523, 5582.525485333792, 5066.518680725399, 5107.591392688576, 5112.463191364853, 5115.514026167508, 5163.948360516422, 5708.086706127, 5797.710114494258, 5892.7642894283745, 5981.820545383645, 6015.789447369321, 5717.799877004285, 5817.883642327045, 5834.533237341087, 5841.094630332305, 5891.440313170242, 5738.8278351649515, 5851.21008663825, 5855.560832937462, 5868.2077919480325, 5890.970545147246, 5677.176809362537, 5736.196932429547, 5737.971310143423, 5739.454751798572, 5796.797112101056, 5915.231019967377, 5917.881642367214, 5930.11048342752, 5952.0476723724, 5952.970235148797, 5475.561955043667, 5608.523275521463, 5774.48029677331, 5795.7391917362975, 5800.4489573490455, 5628.60599758013, 5661.773529058792, 5663.858403539944, 5672.829815105553, 5712.171735098471, 5643.32280126371, 5683.687397193194, 5691.345495267931, 5691.516221121251, 5721.22763426932, 5571.9442805571425, 5669.149627555899, 5684.403137670485, 5750.568707346882, 5808.912866306924, 5506.03644362227, 5565.666060036411, 5568.248286481273, 5619.8595035124445, 5639.351474580311], "unit": "score", "name": "dojo.html", "value": 5377.831020180523}, {"lowerIsBetter": false, "replicates": [546.879686468977, 600.9218801555797, 609.1715267236559, 613.3375953969713, 619.8915189841777, 29952.0, 38154.0, 38673.41989870154, 38679.226415471676, 38710.22579548414, 40641.796791016, 40878.0, 40967.59032409675, 41197.588024119825, 41302.38046429306, 8460.788480287996, 8689.478631282143, 8760.561971901412, 8765.737027889174, 8768.035516093225, 8549.829003419927, 8607.0, 8616.181462761027, 8630.913690863104, 8631.43895646784, 8591.570421478933, 8621.439606425594, 8666.526690462637, 8679.479231246114, 8682.565871706389, 8540.82918341636, 8623.137686231326, 8639.179277968582, 8640.611172497222, 8663.566821658891, 8449.45078569891, 8558.61486233121, 8565.357598180162, 8618.095100014534, 8632.438891472097, 38187.04532386685, 38204.0, 38351.61648383508, 38423.61576384228, 38563.8071809642, 2997.8800847966054, 3033.196203006205, 3039.2705750619693, 3279.754018448602, 3332.800031998064, 28378.29054273639, 29055.41889162205, 29096.563551546744, 29277.414451710847, 29643.851780741174, 3606.855725770966, 3615.367310720613, 3656.561212654472, 3724.0689827543115, 3744.913974947262, 234809.0, 234868.82565587232, 235070.0, 235569.99999999828, 237572.0, 52715.73642131765, 54120.45879541233, 54280.0, 54508.72745636326, 54564.0, 48535.0, 63624.0, 63902.36097639057, 64358.0, 64626.0, 14788.63028424298, 15022.549323520312, 15045.473408430653, 15071.623209419633, 15083.095014299177, 11408.315501069963, 11489.0, 11507.71230719222, 11547.942260288644, 11559.76880462386, 18459.723104153447, 18544.721829172573, 18718.906405467886, 18728.531786705167, 18767.718484222747, 7439.144498382647, 7645.5794931279315, 7734.685103532413, 7762.844743105106, 7769.572673503011, 1025.6564051042835, 1032.9070383665505, 1057.154276578734, 1060.4061725433783, 1090.7218659242, 27687.72312276892, 27711.0, 27731.306717331823, 27930.44139117247, 28212.576811347848, 1023.7901230247782, 1075.091547642241, 1118.2954738514684, 1140.766142940695, 1185.8043422835308, 12079.939600301943, 13033.674158146123, 13049.608511744664, 13370.331483425789, 13409.731805363837, 290452.0, 293281.0, 293801.0, 295542.0, 295681.0, 47452.525474745504, 47755.28367074497, 47915.281270780964, 47997.0, 48065.27902081471, 1336.1248382309602, 1364.9967274053563, 1366.0983750724474, 1370.095736813698, 1370.136813807295, 24617.753822461545, 25657.61513577335, 25774.226773196835, 25894.35264118412, 26006.0, 40293.19413611653, 40626.593734062284, 40635.390469143575, 40709.0, 40763.184736304516, 282885.0, 282977.0, 284278.0, 284686.0, 284779.0, 29579.408411831213, 31157.41689705713, 31360.529592056595, 31448.38343590425, 31651.20871978219, 36462.0, 36898.0, 37358.439623405124, 37408.81295593505, 37595.436068458446, 31383.686163138078, 31425.685743142276, 31468.685313147493, 33878.15304617404, 34153.65846341505, 33382.83308583441, 33917.0, 34232.0, 35071.0, 35194.64805352016, 31084.22289442782, 31367.372652546364, 31370.215744605655, 31474.37051258916, 31590.684093158776, 26856.32859178536, 28830.0, 30999.225019374695, 31244.218894526908, 31271.218219544695, 23629.527409452057, 24003.759962400152, 24051.278461645477, 24368.6344704826, 24389.14637987733], "unit": "score", "name": "ext.html", "value": 17632.913675933796}, {"lowerIsBetter": false, "replicates": [350.70715952179927, 357.10188874979434, 358.0547354982844, 358.39431360999924, 361.154897539757, 16149.919250403746, 16947.83052169478, 17387.304507819703, 17655.38206162786, 18078.186481608325, 10755.300905441141, 10788.892111078907, 10809.783804323908, 11048.06091482225, 11072.169587280945, 4993.176125939216, 5013.197888337867, 5061.493850614927, 5078.162103252959, 5645.096784514479, 5575.442455754413, 5584.636998595099, 5593.161025846115, 5598.832035038955, 5623.0, 4943.901121977558, 5146.562542183919, 5148.613853960949, 5557.138643510242, 5590.748416321255, 5050.747462626854, 5067.670601410897, 5125.256837758541, 5153.252778347138, 5153.6907785532985, 4932.062908047459, 4946.455889852114, 4957.900841983176, 4958.851234462972, 4983.501649835024, 12118.575849845212, 13108.2135071896, 18058.0, 19691.310804121786, 19784.80215197844, 1824.078840185711, 1873.5035215667858, 1944.2028768204968, 1946.5912158446743, 1978.8416926645834, 8946.910530894673, 9052.683156089572, 9185.724428267162, 9570.042995700443, 11853.288802671867, 1797.9460616181536, 1855.5639424735177, 1878.3425800969687, 1914.2725764209652, 1948.9220431182737, 58735.0, 59463.0, 59718.40281597172, 61788.0, 63125.68437157831, 9455.858162127573, 10150.644727434505, 10429.217808664229, 10499.68500944973, 10532.315399499008, 13414.262215578041, 13581.32093395346, 13593.728125437436, 13598.38807253676, 13657.590272291649, 7366.484346095829, 7435.51669141504, 7457.0, 7628.54228746277, 7812.726554570563, 6950.3049695030095, 6995.300469952964, 7007.649617519103, 7010.088688470466, 7033.472489563201, 4366.366876802846, 4391.7584532851, 4532.456105267389, 4550.681452298308, 4571.428571428572, 1787.8480329171905, 1828.1499102916941, 1894.355918987551, 1898.943031709051, 1901.6577016137228, 441.6488891331399, 827.5531213144834, 924.4268553496875, 1005.5776573839005, 1036.9837559191883, 10220.233482488844, 10684.893151068545, 10734.624288150033, 10769.138468922467, 10931.070858976916, 432.6171338365569, 434.3463088052461, 435.3317657395882, 455.2465669317285, 463.3443677196729, 5664.348599910981, 5985.461308482258, 9714.854277185848, 10294.536745846455, 10314.0, 530652.0, 545548.0, 546139.0, 548998.0, 559459.0, 82509.58745206236, 83519.0, 84447.15552844392, 85238.57380713057, 85316.0, 1168.404113901899, 1181.0433548825479, 1185.7569198314497, 1191.8688944216128, 1192.3859212505567, 15477.5356739298, 15546.300416481512, 15653.21733913277, 15671.138087405303, 15837.604059898134, 33277.83361083179, 33720.83139584286, 34949.30101398009, 35346.11634709153, 35431.64568354283, 45551.31673024841, 45749.31376029429, 46401.30398043965, 49513.75243123761, 50647.240291396396, 9412.67055653049, 9602.375845570015, 9652.131308182297, 9675.564599592895, 9888.0, 10918.945405272923, 10950.616728414467, 11051.447427628747, 11200.607978720705, 15748.291326890547, 14859.108453492825, 14881.553553393416, 15069.47256846005, 15437.15095669704, 15461.226938653248, 14433.7834932474, 14586.635334116732, 14592.416303348173, 15071.171085590398, 15207.163606001775, 15116.697666046837, 15210.695786084438, 15300.540983770505, 15574.377024918876, 15614.141222232432, 9612.66355677548, 9626.181774549243, 9804.26468014916, 9813.656522021694, 9834.803303933739, 10236.283460157718, 13148.539801106916, 14839.851601484279, 15248.31382587764, 15361.923190383977], "unit": "score", "name": "jquery.html", "value": 8331.486220157598}, {"lowerIsBetter": false, "replicates": [1487.5388629524841, 1709.7264437689971, 1723.6983527882617, 1752.2377765671934, 1760.322275923769, 6764.32356764324, 7128.821779455516, 7282.963585182073, 7288.307610776974, 7293.525920815143, 7253.891191632129, 7294.708211671526, 7352.48532602718, 7356.485046046779, 7359.227281135471, 2135.4981579328846, 2160.621891169047, 2165.9241926532573, 2166.794154555315, 2174.8042676159143, 2096.433962830035, 2139.3795799218287, 2178.292055082097, 2183.661532462463, 2194.8244140468805, 2102.190656597206, 2114.598226336999, 2116.5237821490196, 2153.0203757290396, 2161.459635091227, 2098.4229336932312, 2117.597656445278, 2147.6671115977047, 2148.1622167354744, 2148.334016454904, 2102.190656597206, 2112.207922029239, 2130.60583791999, 2137.316058861165, 2140.5397839464435, 7008.474364422688, 7041.225465198824, 7045.788626341218, 7102.715891364339, 7104.147502299757, 4252.149570085995, 4295.720778149442, 4305.913881722379, 4312.568743125694, 4329.718568293082, 2827.6889542150348, 2879.43850949065, 2882.30824602094, 2917.139443864065, 2924.6051783009448, 932.9533523323875, 962.821877952581, 969.699393188114, 979.294907666479, 991.8760154980628, 9574.664886729, 9623.085806848409, 9623.663171789023, 9669.371490853073, 9691.515424228832, 3789.7347185696735, 3809.3524100902914, 3844.1735026969336, 3849.40334248192, 3877.0, 7910.208979102159, 7942.880856787153, 7966.920330796734, 7981.561014144162, 7985.5208687478935, 4197.811098500575, 4208.116295577902, 4214.346776249687, 4219.198352313112, 4224.38746381779, 3824.0, 3826.1582451860545, 3837.0, 3873.0317420644838, 3886.8639597614515, 2966.5401862711315, 3035.089473158044, 3043.7412819910555, 3059.9082027538766, 3066.47869862124, 957.2437774158476, 960.5485421851719, 965.5799727118585, 987.2200961240683, 999.8300288950895, 1034.4362322534237, 1034.637876743149, 1077.6982444915436, 1170.1224081938547, 1308.8756568126014, 6671.199456065303, 6698.56459330142, 6725.562838415487, 6727.394534491821, 6733.888908330071, 1028.3161697471098, 1056.8784589772122, 1120.6581992492206, 1128.7629597784558, 1128.8758236593967, 5363.973180134074, 5442.319710036246, 5446.537044351196, 5459.044667183227, 5479.780808767605, 8248.711295104642, 8278.793030174294, 8346.165383461726, 8631.309495240366, 8680.6527738891, 8375.455595386402, 8438.620262088221, 8476.533790641573, 8498.66005359791, 8515.701950431829, 6778.186617605918, 6794.558353706993, 6886.51794374399, 6886.621235832077, 6887.173539175231, 6585.6377899216, 6639.302873198438, 6659.766908158192, 6659.933400666125, 6671.46628269747, 7455.4035677146785, 7661.88507172404, 7676.769696909102, 7707.460477766503, 7810.804729881798, 7587.279208475186, 7691.307782299396, 7820.0, 7830.0, 7901.249381308767, 6119.418655227746, 6156.692165391623, 6180.165677633642, 6217.813465596039, 6227.283862355887, 6802.081718967876, 6894.758683446055, 6948.9305106950305, 6977.546459480118, 6992.1259842519685, 6574.901376479261, 6596.934030659824, 6660.866782664417, 6705.932940670531, 6767.492438067066, 5917.0, 6215.782447614311, 6269.059641053878, 6282.591631543935, 6439.710213040331, 6416.037594360883, 6472.838179045561, 6504.9674751625935, 6512.511561632802, 6538.934610654022, 5528.7235638218735, 5979.073243647242, 6051.515878729779, 6058.848528786815, 6082.847928801815, 6010.068439391726, 6037.577369584087, 6040.214772079602, 6052.092186172109, 6064.302605200459], "unit": "score", "name": "mootools.html", "value": 4145.18818579446}, {"lowerIsBetter": false, "replicates": [296.62476966637206, 300.73084589292574, 310.97667674924384, 314.2787303139294, 326.5575145677607, 6269.185005949226, 6416.582922110059, 6429.0, 6437.420632143107, 6453.677316134198, 6311.936880631192, 6322.336154703758, 6332.493400527958, 6335.239771227448, 6345.206849143857, 4649.093426781779, 4709.552592503724, 4740.1704701677245, 4746.21687421575, 4747.952520474785, 4733.053389322132, 4743.407074115736, 4760.500147484516, 4769.689970151946, 4788.449328327235, 4709.434867815867, 4712.858614241578, 4713.9629281558, 4720.268358404435, 4721.315409265671, 4704.764761761915, 4709.599684026844, 4721.008588196466, 4740.620750339965, 4742.075295317414, 4055.3105971984537, 4094.160697057126, 4094.529129150126, 4097.836086556535, 4103.4870641169855, 5949.791757288474, 6000.369961154103, 6002.729877155539, 6006.519478441758, 6011.278646562397, 5594.664320140804, 5611.7474713637575, 5615.129654903497, 5615.719214039282, 5620.325560932673, 6707.128073350482, 6708.865822683568, 6712.899306510406, 6713.563618364839, 6727.159105111861, 4559.316102584606, 4616.39986801717, 4621.537846215351, 4636.907261854744, 4648.697834640738, 6929.1684997800085, 6993.05593744843, 6994.195667498252, 7000.684969176399, 7016.052832867548, 4671.883202919954, 4679.344891715163, 4695.460022097434, 4730.0, 4731.432228132646, 4697.859064228079, 4777.092352453093, 4779.115863565252, 4794.856154315376, 4803.159447096744, 5555.666660000413, 5565.526930210977, 5566.443355664401, 5566.55467562594, 5594.944050559524, 6738.59568425896, 6806.183258008971, 6807.217170025411, 6811.1145551079035, 6814.965925170442, 1658.5853536615846, 1686.8903521271322, 1696.9575760606083, 1700.396359292458, 1703.5485596316985, 1116.5868628607468, 1118.994405027986, 1123.6235860986499, 1123.7078359626555, 1144.719543711796, 6381.936180638134, 6475.352464753487, 6479.708413121421, 6484.383983521558, 6509.0, 7044.788656340318, 7064.682089305891, 7078.469114816409, 7078.469114816409, 7103.573785572882, 6462.095306657072, 6497.512686548528, 6514.3811337922825, 6519.674016299261, 6532.836679082966, 3762.2099359134886, 3791.3933770596573, 3814.1418180909072, 3828.559715632682, 3845.6155783917766, 7191.676374563157, 7218.422526197892, 7242.710291588278, 7252.782416527512, 7264.455165862581, 7263.455240856957, 7274.418046556263, 7286.672099755524, 7290.307420795122, 7321.890171647429, 7010.614416207056, 7191.676374563052, 7217.350438460564, 7221.277872212737, 7252.02097716812, 7021.648917554204, 7024.297570242935, 7024.438044956289, 7037.964810175917, 7038.8944165838575, 6956.617386043817, 6970.546914450544, 6974.37230649244, 7002.0547226125855, 7004.789856304319, 6931.861362772817, 7161.534500257467, 7229.277072292939, 7243.891341629775, 7266.0, 4687.648426368104, 4752.9287060694805, 4760.857174284777, 4763.595094417014, 4768.973186395699, 7040.542364746275, 7048.682809273492, 7086.503944724026, 7097.396721278543, 7105.715771369088, 6727.260001399744, 6759.966200168968, 6805.727770889109, 6818.897716534355, 6883.7590684325805, 6789.388954994074, 6830.692618832065, 6852.794416167523, 6856.24581296067, 6866.34769696878, 6577.276499585138, 6694.933050669431, 6719.899201511884, 6729.057931889637, 6733.158355205599, 6715.294894036056, 6899.93100069013, 6910.032595436744, 6912.377885990285, 6925.065116209247, 6640.435562977201, 6717.2946840581035, 6741.797746067626, 6757.054012438164, 6764.695588698619], "unit": "score", "name": "prototype.html", "value": 5058.111579356693}, {"lowerIsBetter": false, "replicates": [764.8049747314436, 768.6694721269852, 822.1244374740896, 844.6325848256005, 869.3479890082439, 13038.543650972219, 13152.539661111863, 13291.867081329185, 13367.131136476122, 13415.0, 12841.762218467906, 13480.865191348084, 13486.730265394686, 13501.864981350183, 13528.391222394992, 822.9547374894394, 823.4524041512387, 835.7952301686096, 836.1262480707675, 837.1879277101202, 832.2218725491684, 838.8322335532887, 844.0167205205959, 844.8099177685033, 847.1274587175219, 862.9827403451927, 870.2820173356974, 872.0973792125152, 873.2010210657241, 876.8903887014123, 862.6808081010035, 864.6714248585529, 866.5710473315718, 867.9913200867974, 870.4211699220011, 632.9525285603552, 641.9679016049179, 643.3309358267396, 645.2257291250518, 645.7062036773257, 13795.172289662552, 13866.029377943549, 13897.861021389757, 13929.721405571932, 13933.442662293495, 12293.385330733518, 12454.190477619017, 12632.49470021198, 12890.097693161482, 13009.658261512925, 10181.745456363577, 10206.438645874474, 10208.183345332356, 10249.590016399336, 10444.373337599693, 3635.381985062546, 3665.321915445651, 3683.5027271318295, 3701.0747313171705, 3793.165503589206, 47109.52890471086, 47509.524904750855, 47535.52464475346, 47616.52383476121, 47638.28542571864, 9961.203103751683, 10125.696229113139, 10145.543450544743, 10205.94897025525, 10251.28241023136, 17801.109944502725, 17836.286548538174, 17836.375726849757, 17851.196696148705, 17865.374711885022, 7701.614919254015, 7828.804279893048, 8051.5571643559, 8060.193980601893, 8063.677452901936, 4451.799669014902, 4663.230566956414, 4679.087577922272, 4719.292106184032, 4730.787114579852, 3030.1515575638855, 3049.969500305013, 3056.43455960648, 3058.938821223563, 3065.8926937557526, 533.7358007786171, 540.6215649045683, 541.2746919128386, 546.1234718277187, 557.8103444828778, 648.7178077536287, 651.4169818012851, 683.5625199872086, 684.9554778939353, 698.8113209433425, 8477.194666506672, 8606.139386061344, 8754.068014211854, 8832.8675069874, 9003.099690031075, 573.2748073686789, 575.8704291534372, 637.2030872938269, 700.961447120403, 707.1549498349423, 4128.690348223835, 4342.413774140514, 4367.934480982788, 4400.471943366752, 4402.823887044482, 53397.0, 53622.7318863411, 53874.730626346616, 54061.45938540643, 55125.44874551203, 49714.50285497171, 50200.49799502031, 50948.74525627348, 51139.744301279, 51767.0, 44480.77759611181, 45143.548564513934, 45472.0, 45650.0, 46003.0, 8426.789330266547, 8724.738257852274, 8726.214640682369, 8726.214640682369, 8993.32550058735, 32708.34583308273, 33050.17374565655, 33426.0, 33831.15422114368, 34487.65512344844, 41877.162456750084, 41937.79031104825, 41949.58050419579, 42195.7890210547, 43166.56833431751, 11193.720156995814, 11216.270942388719, 11222.158338124837, 11450.885491145315, 11602.535898563963, 12823.743525129632, 12873.549425770054, 13058.608241752763, 13241.536546220836, 13632.65918352049, 11241.775164496828, 11423.771524569296, 11840.05279577649, 12412.62762117138, 12936.547220847226, 11776.234544754563, 11884.0, 12145.939270303592, 12166.57416990401, 12295.877041229473, 11071.72320691989, 11087.889121108685, 11679.474423650787, 11905.82141267899, 12083.275003499819, 9557.283203759607, 9783.88455430507, 9892.0, 10259.230557708053, 10316.793664126826, 9257.544530402185, 9864.0, 10048.100500819826, 10084.445355505517, 10161.441120738134], "unit": "score", "name": "yui.html", "value": 6400.241106520413}], "name": "dromaeo_css", "value": 6879.707739609166}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 105.0, 113.0, 111.0, 114.0, 122.0, 113.0, 112.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.3}, {"lowerIsBetter": true, "replicates": [155.0, 131.0, 150.0, 163.0, 124.0, 157.0, 150.0, 128.0, 169.0, 133.0], "unit": "score", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [166.0, 163.0, 161.0, 162.0, 162.0, 161.0, 161.0, 163.0, 172.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 73.0, 74.0, 73.0, 73.0, 130.0, 72.0, 74.0, 70.0], "unit": "score", "name": "audio-fft", "value": 78.4}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 138.0, 94.0, 95.0, 93.0, 140.0, 94.0, 93.0, 141.0], "unit": "score", "name": "audio-oscillator", "value": 107.7}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [148.0, 149.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [96.0, 98.0, 95.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.2}, {"lowerIsBetter": true, "replicates": [61.0, 60.0, 63.0, 61.0, 67.0, 61.0, 63.0, 61.0, 58.0, 59.0], "unit": "score", "name": "json-parse-financial", "value": 61.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 94.0, 94.0, 85.0, 85.0, 86.0, 86.0, 86.0, 93.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.9}, {"lowerIsBetter": true, "replicates": [116.0, 121.0, 123.0, 123.0, 119.0, 132.0, 127.0, 123.0, 117.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.2}, {"lowerIsBetter": true, "replicates": [147.0, 153.0, 166.0, 147.0, 153.0, 143.0, 154.0, 153.0, 154.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 151.2}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 70.0, 69.0, 66.0, 65.0, 66.0, 66.0, 68.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken", "value": 1518.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.16816218012627], "unit": "score", "name": "Richards", "value": 212.4474360000001}, {"lowerIsBetter": false, "replicates": [194.89378288832586], "unit": "score", "name": "DeltaBlue", "value": 339.25145799999996}, {"lowerIsBetter": false, "replicates": [284.3332385555872], "unit": "score", "name": "Encrypt", "value": 936.1585769999997}, {"lowerIsBetter": false, "replicates": [5154.639175257732], "unit": "score", "name": "Decrypt", "value": 51.63911399999997}, {"lowerIsBetter": false, "replicates": [1085.7763300760043], "unit": "score", "name": "RayTrace", "value": 681.5298690000002}, {"lowerIsBetter": false, "replicates": [756.6137566137567], "unit": "score", "name": "Earley", "value": 880.8496993006994}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [25666.666666666668], "unit": "score", "name": "RegExp", "value": 35.49292207792205}, {"lowerIsBetter": false, "replicates": [645.385587863464], "unit": "score", "name": "Splay", "value": 126.26715181194903}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20957.568900967388}]} 05:53:48 INFO - RETURN: dromaeo_css: 6879.71 05:53:48 INFO - RETURN: kraken: 1518.60 05:53:48 INFO - RETURN: v8_7: 20957.57 05:53:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "20957.57"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1518.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6879.71"}}} 05:53:48 INFO - Return code: 0 05:53:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:53:48 INFO - # TBPL SUCCESS # 05:53:48 INFO - Running post-action listener: _resource_record_post_action 05:53:48 INFO - Running post-run listener: _resource_record_post_run 05:53:50 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 24576; Write bytes: 555483136; Read time: 36; Write time: 580004 05:53:50 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234713088; Read time: 0; Write time: 316432 05:53:50 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 24576; Write bytes: 320770048; Read time: 36; Write time: 263572 05:53:50 INFO - Running post-run listener: _upload_blobber_files 05:53:50 INFO - Blob upload gear active. 05:53:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:53:50 INFO - Copying logs to upload dir... 05:53:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.920659 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-12-20 05:53:49.606844) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-20 05:53:49.611802) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010095 build_url: 'https://queue.taskcluster.net/v1/task/9F5c3q79SpuVjSjdwSVq7A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-20 05:53:49.666551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:53:49.666920) ========= 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=752f8c75933e5bfb3b8f10bb000001ce-1450618010.403597-1216201995 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-20 05:53:49.720973) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-20 05:53:49.721389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-20 05:53:49.721737) ========= ========= Total master_lag: 0.27 =========