builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1451568684.58 results: success (0) buildid: 20151231030214 builduid: 3d8ccad6f4f14ea0b06b35e13524f591 revision: 22f51211915bf7daff076180847a7140d35aa353 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.584277) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.584648) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.584968) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006484 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.619602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.619875) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.642874) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:24.643149) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.60 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.250684) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.251008) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.251417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.251758) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-31 05:31:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.5M=0s 2015-12-31 05:31:25 (34.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101031 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.397789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.398135) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019772 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.466942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:25.467284) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22f51211915bf7daff076180847a7140d35aa353 --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 22f51211915bf7daff076180847a7140d35aa353 --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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-31 05:31:25,550 truncating revision to first 12 chars 2015-12-31 05:31:25,550 Setting DEBUG logging. 2015-12-31 05:31:25,550 attempt 1/10 2015-12-31 05:31:25,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22f51211915b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-31 05:31:25,929 unpacking tar archive at: mozilla-central-22f51211915b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.706373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:26.190560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:26.190866) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:26.201130) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:26.201532) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-31 05:31:26.202177) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2015-12-31 05:31:26.202517) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:31:26 INFO - MultiFileLogger online at 20151231 05:31:26 in /builds/slave/test-pgo 05:31:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:31:26 INFO - {'append_to_log': False, 05:31:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:31:26 INFO - 'blob_upload_branch': 'Firefox', 05:31:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:31:26 INFO - 'branch': 'Firefox', 05:31:26 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:26 INFO - 'config_files': ('talos/linux_config.py',), 05:31:26 INFO - 'default_actions': ('clobber', 05:31:26 INFO - 'read-buildbot-config', 05:31:26 INFO - 'download-and-extract', 05:31:26 INFO - 'populate-webroot', 05:31:26 INFO - 'create-virtualenv', 05:31:26 INFO - 'install', 05:31:26 INFO - 'run-tests'), 05:31:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:26 INFO - 'download_minidump_stackwalk': True, 05:31:26 INFO - 'download_symbols': 'ondemand', 05:31:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:26 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:26 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:26 INFO - 'installer_path': 'installer.exe', 05:31:26 INFO - 'log_level': 'info', 05:31:26 INFO - 'log_name': 'talos', 05:31:26 INFO - 'log_to_console': True, 05:31:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:31:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:31:26 INFO - 'opt_config_files': (), 05:31:26 INFO - 'pip_index': False, 05:31:26 INFO - 'sps_profile': False, 05:31:26 INFO - 'sps_profile_interval': 0, 05:31:26 INFO - 'suite': 'dromaeojs', 05:31:26 INFO - 'system_bits': '32', 05:31:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:26 INFO - 'title': 'talos-linux64-ix-031', 05:31:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:26 INFO - 'use_talos_json': True, 05:31:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:31:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:26 INFO - 'work_dir': 'build'} 05:31:26 INFO - ##### 05:31:26 INFO - ##### Running clobber step. 05:31:26 INFO - ##### 05:31:26 INFO - Running pre-action listener: _resource_record_pre_action 05:31:26 INFO - Running main action method: clobber 05:31:26 INFO - rmtree: /builds/slave/test-pgo/build 05:31:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:31:27 INFO - Running post-action listener: _resource_record_post_action 05:31:27 INFO - ##### 05:31:27 INFO - ##### Running read-buildbot-config step. 05:31:27 INFO - ##### 05:31:27 INFO - Running pre-action listener: _resource_record_pre_action 05:31:27 INFO - Running main action method: read_buildbot_config 05:31:27 INFO - Using buildbot properties: 05:31:27 INFO - { 05:31:27 INFO - "project": "", 05:31:27 INFO - "product": "firefox", 05:31:27 INFO - "script_repo_revision": "production", 05:31:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:31:27 INFO - "repository": "", 05:31:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:31:27 INFO - "buildid": "20151231030214", 05:31:27 INFO - "stage_platform": "linux64-pgo", 05:31:27 INFO - "basedir": "/builds/slave/test-pgo", 05:31:27 INFO - "nightly_build": "True", 05:31:27 INFO - "buildnumber": 624, 05:31:27 INFO - "slavename": "talos-linux64-ix-031", 05:31:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:31:27 INFO - "platform": "ubuntu64_hw", 05:31:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:31:27 INFO - "branch": "mozilla-central", 05:31:27 INFO - "repo_path": "mozilla-central", 05:31:27 INFO - "revision": "22f51211915bf7daff076180847a7140d35aa353", 05:31:27 INFO - "pgo_build": "True", 05:31:27 INFO - "builduid": "3d8ccad6f4f14ea0b06b35e13524f591", 05:31:27 INFO - "slavebuilddir": "test" 05:31:27 INFO - } 05:31:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:31:27 INFO - Running post-action listener: _resource_record_post_action 05:31:27 INFO - ##### 05:31:27 INFO - ##### Running download-and-extract step. 05:31:27 INFO - ##### 05:31:27 INFO - Running pre-action listener: _resource_record_pre_action 05:31:27 INFO - Running main action method: download_and_extract 05:31:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:31:27 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:31:27 INFO - https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/test_packages.json 05:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/test_packages.json 05:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:31:35 INFO - Downloaded 1302 bytes. 05:31:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:31:35 INFO - Using the following test package requirements: 05:31:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:31:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'jsshell-linux-x86_64.zip'], 05:31:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:31:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:31:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:31:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:31:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:31:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:31:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:31:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:35 INFO - https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:39 INFO - Downloaded 23195510 bytes. 05:31:39 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:31:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:39 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:31:40 INFO - Return code: 0 05:31:40 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:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:40 INFO - https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:31:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:42 INFO - Downloaded 23195510 bytes. 05:31:42 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:31:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:31:42 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:31:43 INFO - Return code: 0 05:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:43 INFO - https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:45 INFO - Downloaded 11132285 bytes. 05:31:45 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:31:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:31:45 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:31:45 INFO - Return code: 0 05:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:45 INFO - https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:31:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VW1VHzOJTL6IEpARlPKw9w/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:31:46 INFO - Downloaded 54948183 bytes. 05:31:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:46 INFO - mkdir: /builds/slave/test-pgo/properties 05:31:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:31:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:31:46 INFO - Contents: 05:31:46 INFO - build_url:https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:31:46 INFO - Running post-action listener: _resource_record_post_action 05:31:46 INFO - Running post-action listener: set_extra_try_arguments 05:31:46 INFO - ##### 05:31:46 INFO - ##### Running populate-webroot step. 05:31:46 INFO - ##### 05:31:46 INFO - Running pre-action listener: _resource_record_pre_action 05:31:46 INFO - Running main action method: populate_webroot 05:31:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:31:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:31:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:31:46 INFO - '--tppagecycles', 05:31:46 INFO - '7'], 05:31:46 INFO - 'tests': ['tsvgm']}}, 05:31:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:31:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:31:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:31:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:31:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'tests': ['damp', 'tps']}, 05:31:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['damp', 'tps']}, 05:31:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:31:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:31:46 INFO - 'other': {'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'other_l64': {'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'other_nol64': {'tests': ['a11yr', 05:31:46 INFO - 'ts_paint', 05:31:46 INFO - 'tpaint', 05:31:46 INFO - 'sessionrestore', 05:31:46 INFO - 'sessionrestore_no_auto_restore']}, 05:31:46 INFO - 'svgr': {'tests': ['tsvgx', 05:31:46 INFO - 'tsvgr_opacity', 05:31:46 INFO - 'tart', 05:31:46 INFO - 'tscrollx', 05:31:46 INFO - 'cart']}, 05:31:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['tsvgx', 05:31:46 INFO - 'tsvgr_opacity', 05:31:46 INFO - 'tart', 05:31:46 INFO - 'tscrollx', 05:31:46 INFO - 'cart']}, 05:31:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'tests': ['tp5o']}, 05:31:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'talos_options': ['--e10s'], 05:31:46 INFO - 'tests': ['tp5o']}, 05:31:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'talos_options': ['--xperf_path', 05:31:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:46 INFO - 'tests': ['tp5n']}, 05:31:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:31:46 INFO - 'talos_options': ['--e10s', 05:31:46 INFO - '--xperf_path', 05:31:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:31:46 INFO - 'tests': ['tp5n']}}, 05:31:46 INFO - 'talos.zip': {'path': '', 05:31:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:31:46 INFO - Running post-action listener: _resource_record_post_action 05:31:46 INFO - ##### 05:31:46 INFO - ##### Running create-virtualenv step. 05:31:46 INFO - ##### 05:31:46 INFO - Running pre-action listener: _resource_record_pre_action 05:31:46 INFO - Running main action method: create_virtualenv 05:31:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:31:46 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:31:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:31:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:31:46 INFO - Using real prefix '/usr' 05:31:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:31:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:49 INFO - Installing pip.................done. 05:31:49 INFO - Return code: 0 05:31:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:31:49 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:31:49 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:31:49 INFO - Using env: {'DISPLAY': ':0', 05:31:49 INFO - 'HOME': '/home/cltbld', 05:31:49 INFO - 'LANG': 'en_US.UTF-8', 05:31:49 INFO - 'LANGUAGE': 'en_US:en', 05:31:49 INFO - 'LOGNAME': 'cltbld', 05:31:49 INFO - 'MAIL': '/var/mail/cltbld', 05:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:49 INFO - 'MOZ_NO_REMOTE': '1', 05:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:49 INFO - 'NO_EM_RESTART': '1', 05:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:49 INFO - 'SHELL': '/bin/bash', 05:31:49 INFO - 'SHLVL': '1', 05:31:49 INFO - 'TERM': 'linux', 05:31:49 INFO - 'TMOUT': '86400', 05:31:49 INFO - 'USER': 'cltbld', 05:31:49 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:49 INFO - '_': '/tools/buildbot/bin/python'} 05:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:49 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:31:49 INFO - Cleaning up... 05:31:49 INFO - Return code: 0 05:31:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:49 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:31:49 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:31:49 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:31:49 INFO - Using env: {'DISPLAY': ':0', 05:31:49 INFO - 'HOME': '/home/cltbld', 05:31:49 INFO - 'LANG': 'en_US.UTF-8', 05:31:49 INFO - 'LANGUAGE': 'en_US:en', 05:31:49 INFO - 'LOGNAME': 'cltbld', 05:31:49 INFO - 'MAIL': '/var/mail/cltbld', 05:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:49 INFO - 'MOZ_NO_REMOTE': '1', 05:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:49 INFO - 'NO_EM_RESTART': '1', 05:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:49 INFO - 'SHELL': '/bin/bash', 05:31:49 INFO - 'SHLVL': '1', 05:31:49 INFO - 'TERM': 'linux', 05:31:49 INFO - 'TMOUT': '86400', 05:31:49 INFO - 'USER': 'cltbld', 05:31:49 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:49 INFO - '_': '/tools/buildbot/bin/python'} 05:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:49 INFO - Downloading/unpacking psutil>=0.7.1 05:31:49 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:31:49 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:31:49 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:31:49 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:31:49 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:31:49 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:31:51 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:31:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:31:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:51 INFO - Installing collected packages: psutil 05:31:51 INFO - Running setup.py install for psutil 05:31:51 INFO - building 'psutil._psutil_linux' extension 05:31:51 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:31:51 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:31:51 INFO - building 'psutil._psutil_posix' extension 05:31:51 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:31:52 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:31:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:52 INFO - Successfully installed psutil 05:31:52 INFO - Cleaning up... 05:31:52 INFO - Return code: 0 05:31:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:31:52 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:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:52 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:31:52 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:31:52 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:31:52 INFO - Using env: {'DISPLAY': ':0', 05:31:52 INFO - 'HOME': '/home/cltbld', 05:31:52 INFO - 'LANG': 'en_US.UTF-8', 05:31:52 INFO - 'LANGUAGE': 'en_US:en', 05:31:52 INFO - 'LOGNAME': 'cltbld', 05:31:52 INFO - 'MAIL': '/var/mail/cltbld', 05:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:52 INFO - 'MOZ_NO_REMOTE': '1', 05:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:52 INFO - 'NO_EM_RESTART': '1', 05:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:52 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:52 INFO - 'SHELL': '/bin/bash', 05:31:52 INFO - 'SHLVL': '1', 05:31:52 INFO - 'TERM': 'linux', 05:31:52 INFO - 'TMOUT': '86400', 05:31:52 INFO - 'USER': 'cltbld', 05:31:52 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:52 INFO - '_': '/tools/buildbot/bin/python'} 05:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:31:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:31:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:54 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:31:54 INFO - Installing collected packages: mozsystemmonitor 05:31:54 INFO - Running setup.py install for mozsystemmonitor 05:31:54 INFO - Successfully installed mozsystemmonitor 05:31:54 INFO - Cleaning up... 05:31:54 INFO - Return code: 0 05:31:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:31: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:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:31:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:31:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:31:54 INFO - Using env: {'DISPLAY': ':0', 05:31:54 INFO - 'HOME': '/home/cltbld', 05:31:54 INFO - 'LANG': 'en_US.UTF-8', 05:31:54 INFO - 'LANGUAGE': 'en_US:en', 05:31:54 INFO - 'LOGNAME': 'cltbld', 05:31:54 INFO - 'MAIL': '/var/mail/cltbld', 05:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:54 INFO - 'MOZ_NO_REMOTE': '1', 05:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:54 INFO - 'NO_EM_RESTART': '1', 05:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:54 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:54 INFO - 'SHELL': '/bin/bash', 05:31:54 INFO - 'SHLVL': '1', 05:31:54 INFO - 'TERM': 'linux', 05:31:54 INFO - 'TMOUT': '86400', 05:31:54 INFO - 'USER': 'cltbld', 05:31:54 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:54 INFO - '_': '/tools/buildbot/bin/python'} 05:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:54 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:56 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:31:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:56 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:31:56 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:31:56 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:31:56 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:31:56 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:31:56 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:31:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:31:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:31:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:56 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:31:56 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:31:56 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:31:56 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:31:56 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:31:56 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:31:56 INFO - Downloading docopt-0.6.1.tar.gz 05:31:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:31:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:56 INFO - Running setup.py install for blobuploader 05:31:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:31:56 INFO - Running setup.py install for requests 05:31:57 INFO - Running setup.py install for docopt 05:31:57 INFO - Successfully installed blobuploader requests docopt 05:31:57 INFO - Cleaning up... 05:31:57 INFO - Return code: 0 05:31:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:57 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:31:57 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:31:57 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:31:57 INFO - Using env: {'DISPLAY': ':0', 05:31:57 INFO - 'HOME': '/home/cltbld', 05:31:57 INFO - 'LANG': 'en_US.UTF-8', 05:31:57 INFO - 'LANGUAGE': 'en_US:en', 05:31:57 INFO - 'LOGNAME': 'cltbld', 05:31:57 INFO - 'MAIL': '/var/mail/cltbld', 05:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:57 INFO - 'MOZ_NO_REMOTE': '1', 05:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:31:57 INFO - 'NO_EM_RESTART': '1', 05:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:31:57 INFO - 'PWD': '/builds/slave/test-pgo', 05:31:57 INFO - 'SHELL': '/bin/bash', 05:31:57 INFO - 'SHLVL': '1', 05:31:57 INFO - 'TERM': 'linux', 05:31:57 INFO - 'TMOUT': '86400', 05:31:57 INFO - 'USER': 'cltbld', 05:31:57 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:57 INFO - '_': '/tools/buildbot/bin/python'} 05:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:57 INFO - Running setup.py (path:/tmp/pip-Zf5w1X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:57 INFO - Running setup.py (path:/tmp/pip-PTn0_T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:57 INFO - Running setup.py (path:/tmp/pip-zAl6A8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:57 INFO - Running setup.py (path:/tmp/pip-MXBRLF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:57 INFO - Running setup.py (path:/tmp/pip-xd1wXv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:58 INFO - Running setup.py (path:/tmp/pip-Yz0Cc6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:58 INFO - Running setup.py (path:/tmp/pip-1GkZLN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:58 INFO - Running setup.py (path:/tmp/pip-WmJrre-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:58 INFO - Running setup.py (path:/tmp/pip-XgRIbL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:58 INFO - Running setup.py (path:/tmp/pip-xLUXPo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:58 INFO - Running setup.py (path:/tmp/pip-aSGseu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:58 INFO - Running setup.py (path:/tmp/pip-127fdk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:58 INFO - Running setup.py (path:/tmp/pip-cfSFLa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:58 INFO - Running setup.py (path:/tmp/pip-xbQ97S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:58 INFO - Running setup.py (path:/tmp/pip-WZmOit-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:58 INFO - Running setup.py (path:/tmp/pip-Ilf7Xa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:58 INFO - Running setup.py (path:/tmp/pip-eo8XVY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:31:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:58 INFO - Running setup.py install for manifestparser 05:31:59 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for mozcrash 05:31:59 INFO - Running setup.py install for mozdebug 05:31:59 INFO - Running setup.py install for mozdevice 05:31:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for mozfile 05:31:59 INFO - Running setup.py install for mozhttpd 05:31:59 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for mozinfo 05:31:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for mozInstall 05:31:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for mozleak 05:31:59 INFO - Running setup.py install for mozlog 05:31:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:31:59 INFO - Running setup.py install for moznetwork 05:32:00 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Running setup.py install for mozprocess 05:32:00 INFO - Running setup.py install for mozprofile 05:32:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Running setup.py install for mozrunner 05:32:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Running setup.py install for mozscreenshot 05:32:00 INFO - Running setup.py install for moztest 05:32:00 INFO - Running setup.py install for mozversion 05:32:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:32:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:00 INFO - Cleaning up... 05:32:00 INFO - Return code: 0 05:32:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:00 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:00 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:32:00 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:32:00 INFO - Using env: {'DISPLAY': ':0', 05:32:00 INFO - 'HOME': '/home/cltbld', 05:32:00 INFO - 'LANG': 'en_US.UTF-8', 05:32:00 INFO - 'LANGUAGE': 'en_US:en', 05:32:00 INFO - 'LOGNAME': 'cltbld', 05:32:00 INFO - 'MAIL': '/var/mail/cltbld', 05:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:00 INFO - 'MOZ_NO_REMOTE': '1', 05:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:00 INFO - 'NO_EM_RESTART': '1', 05:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:00 INFO - 'SHELL': '/bin/bash', 05:32:00 INFO - 'SHLVL': '1', 05:32:00 INFO - 'TERM': 'linux', 05:32:00 INFO - 'TMOUT': '86400', 05:32:00 INFO - 'USER': 'cltbld', 05:32:00 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:00 INFO - '_': '/tools/buildbot/bin/python'} 05:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:00 INFO - Running setup.py (path:/tmp/pip-9xfFXQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:32:00 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:32:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:00 INFO - Running setup.py (path:/tmp/pip-_yiBjc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:01 INFO - Running setup.py (path:/tmp/pip-Aca8NR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:01 INFO - Running setup.py (path:/tmp/pip-0nDiMw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:01 INFO - Running setup.py (path:/tmp/pip-exVsQ2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:01 INFO - Running setup.py (path:/tmp/pip-Hw7GHd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:01 INFO - Running setup.py (path:/tmp/pip-Blc4Ai-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:01 INFO - Running setup.py (path:/tmp/pip-sJOXLO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:01 INFO - Running setup.py (path:/tmp/pip-efiBua-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:01 INFO - Running setup.py (path:/tmp/pip-a3IJlY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:01 INFO - Running setup.py (path:/tmp/pip-y4vM0e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:01 INFO - Running setup.py (path:/tmp/pip-FWMjjj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:01 INFO - Running setup.py (path:/tmp/pip-dngV0F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:01 INFO - Running setup.py (path:/tmp/pip-rmBu57-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:32:01 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:32:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:01 INFO - Running setup.py (path:/tmp/pip-hWH2MP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:32:02 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:32:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:02 INFO - Running setup.py (path:/tmp/pip-36W0l4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:32:02 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:32:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:02 INFO - Running setup.py (path:/tmp/pip-fn4_It-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 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:32:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:32: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:32: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:32: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:32: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:32: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:32: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:32:03 INFO - Downloading blessings-1.5.1.tar.gz 05:32:03 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:32:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:03 INFO - Installing collected packages: blessings 05:32:03 INFO - Running setup.py install for blessings 05:32:04 INFO - Successfully installed blessings 05:32:04 INFO - Cleaning up... 05:32:04 INFO - Return code: 0 05:32:04 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:32:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:32:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:04 INFO - Reading from file tmpfile_stdout 05:32:04 INFO - Current package versions: 05:32:04 INFO - argparse == 1.2.1 05:32:04 INFO - blessings == 1.5.1 05:32:04 INFO - blobuploader == 1.2.4 05:32:04 INFO - docopt == 0.6.1 05:32:04 INFO - manifestparser == 1.1 05:32:04 INFO - mozInstall == 1.12 05:32:04 INFO - mozcrash == 0.16 05:32:04 INFO - mozdebug == 0.1 05:32:04 INFO - mozdevice == 0.47 05:32:04 INFO - mozfile == 1.2 05:32:04 INFO - mozhttpd == 0.7 05:32:04 INFO - mozinfo == 0.9 05:32:04 INFO - mozleak == 0.1 05:32:04 INFO - mozlog == 3.1 05:32:04 INFO - moznetwork == 0.27 05:32:04 INFO - mozprocess == 0.22 05:32:04 INFO - mozprofile == 0.28 05:32:04 INFO - mozrunner == 6.11 05:32:04 INFO - mozscreenshot == 0.1 05:32:04 INFO - mozsystemmonitor == 0.0 05:32:04 INFO - moztest == 0.7 05:32:04 INFO - mozversion == 1.4 05:32:04 INFO - psutil == 3.1.1 05:32:04 INFO - requests == 1.2.3 05:32:04 INFO - wsgiref == 0.1.2 05:32:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:32: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:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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', '-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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:04 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:32:04 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:32:04 INFO - Using env: {'DISPLAY': ':0', 05:32:04 INFO - 'HOME': '/home/cltbld', 05:32:04 INFO - 'LANG': 'en_US.UTF-8', 05:32:04 INFO - 'LANGUAGE': 'en_US:en', 05:32:04 INFO - 'LOGNAME': 'cltbld', 05:32:04 INFO - 'MAIL': '/var/mail/cltbld', 05:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:04 INFO - 'MOZ_NO_REMOTE': '1', 05:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:04 INFO - 'NO_EM_RESTART': '1', 05:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:04 INFO - 'SHELL': '/bin/bash', 05:32:04 INFO - 'SHLVL': '1', 05:32:04 INFO - 'TERM': 'linux', 05:32:04 INFO - 'TMOUT': '86400', 05:32:04 INFO - 'USER': 'cltbld', 05:32:04 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:04 INFO - '_': '/tools/buildbot/bin/python'} 05:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:32:04 INFO - Cleaning up... 05:32:04 INFO - Return code: 0 05:32:04 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:32: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:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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: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', 'jsonschema'] in /builds/slave/test-pgo/build 05:32: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 jsonschema 05:32:04 INFO - Using env: {'DISPLAY': ':0', 05:32:04 INFO - 'HOME': '/home/cltbld', 05:32:04 INFO - 'LANG': 'en_US.UTF-8', 05:32:04 INFO - 'LANGUAGE': 'en_US:en', 05:32:04 INFO - 'LOGNAME': 'cltbld', 05:32:04 INFO - 'MAIL': '/var/mail/cltbld', 05:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:04 INFO - 'MOZ_NO_REMOTE': '1', 05:32:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:04 INFO - 'NO_EM_RESTART': '1', 05:32:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:04 INFO - 'SHELL': '/bin/bash', 05:32:04 INFO - 'SHLVL': '1', 05:32:04 INFO - 'TERM': 'linux', 05:32:04 INFO - 'TMOUT': '86400', 05:32:04 INFO - 'USER': 'cltbld', 05:32:04 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:04 INFO - '_': '/tools/buildbot/bin/python'} 05:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:04 INFO - Downloading/unpacking jsonschema 05:32: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:32: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:32: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:32: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:32: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:32: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:32:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:06 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:32:06 INFO - Downloading/unpacking functools32 (from jsonschema) 05:32: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:32: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:32: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:32: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:32: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:32: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:32:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:06 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:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:06 INFO - warning: no files found matching '*.txt' 05:32:06 INFO - no previously-included directories found matching 'build' 05:32:06 INFO - no previously-included directories found matching 'dist' 05:32:06 INFO - no previously-included directories found matching '.git*' 05:32:06 INFO - Installing collected packages: jsonschema, functools32 05:32:06 INFO - Running setup.py install for functools32 05:32:06 INFO - warning: no files found matching '*.txt' 05:32:06 INFO - no previously-included directories found matching 'build' 05:32:06 INFO - no previously-included directories found matching 'dist' 05:32:06 INFO - no previously-included directories found matching '.git*' 05:32:06 INFO - Successfully installed jsonschema functools32 05:32:06 INFO - Cleaning up... 05:32:06 INFO - Return code: 0 05:32:06 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:32:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:06 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 0x7f1856d80f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1090630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, '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': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', '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:06 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:32:06 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:32:06 INFO - Using env: {'DISPLAY': ':0', 05:32:06 INFO - 'HOME': '/home/cltbld', 05:32:06 INFO - 'LANG': 'en_US.UTF-8', 05:32:06 INFO - 'LANGUAGE': 'en_US:en', 05:32:06 INFO - 'LOGNAME': 'cltbld', 05:32:06 INFO - 'MAIL': '/var/mail/cltbld', 05:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:06 INFO - 'MOZ_NO_REMOTE': '1', 05:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:06 INFO - 'NO_EM_RESTART': '1', 05:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:06 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:06 INFO - 'SHELL': '/bin/bash', 05:32:06 INFO - 'SHLVL': '1', 05:32:06 INFO - 'TERM': 'linux', 05:32:06 INFO - 'TMOUT': '86400', 05:32:06 INFO - 'USER': 'cltbld', 05:32:06 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:06 INFO - '_': '/tools/buildbot/bin/python'} 05:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:07 INFO - Downloading/unpacking flake8 05:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:32:08 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:32:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:32:08 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:08 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:08 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:08 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:08 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:08 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:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:32:08 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:32:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:32:08 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:08 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:08 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:08 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:08 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:08 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:09 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:32:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:32:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:32:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:09 INFO - no previously-included directories found matching 'docs/_build' 05:32:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:32:09 INFO - Running setup.py install for pep8 05:32:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:32:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:32:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:32:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:32:09 INFO - no previously-included directories found matching 'docs/_build' 05:32:09 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:32:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:32:09 INFO - Cleaning up... 05:32:09 INFO - Return code: 0 05:32:09 INFO - Running post-action listener: _resource_record_post_action 05:32:09 INFO - Running post-action listener: _start_resource_monitoring 05:32:09 INFO - Starting resource monitoring. 05:32:09 INFO - ##### 05:32:09 INFO - ##### Running install step. 05:32:09 INFO - ##### 05:32:09 INFO - Running pre-action listener: _resource_record_pre_action 05:32:09 INFO - Running main action method: install 05:32:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:32:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:32:09 INFO - Reading from file tmpfile_stdout 05:32:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:32:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:32:09 INFO - Reading from file tmpfile_stdout 05:32:09 INFO - Output received: 05:32:09 INFO - Usage: mozinstall [options] installer 05:32:09 INFO - Options: 05:32:09 INFO - -h, --help show this help message and exit 05:32:09 INFO - -d DEST, --destination=DEST 05:32:09 INFO - Directory to install application into. [default: 05:32:09 INFO - "/builds/slave/test-pgo"] 05:32:09 INFO - --app=APP Application being installed. [default: firefox] 05:32:09 INFO - mkdir: /builds/slave/test-pgo/build/application 05:32:09 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:32:09 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:32:25 INFO - Reading from file tmpfile_stdout 05:32:25 INFO - Output received: 05:32:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:32:25 INFO - Running post-action listener: _resource_record_post_action 05:32:25 INFO - ##### 05:32:25 INFO - ##### Running run-tests step. 05:32:25 INFO - ##### 05:32:25 INFO - Running pre-action listener: _resource_record_pre_action 05:32:25 INFO - Running main action method: run_tests 05:32:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:32:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:32:25 INFO - Python 2.7.3 05:32:25 INFO - Return code: 0 05:32:25 INFO - grabbing minidump binary from tooltool 05:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:32:25 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 0x1121840>, '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 0x108e6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108eb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:32:25 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:32:25 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:32:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:25 INFO - Return code: 0 05:32:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:32:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:32:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:32:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:32:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:32:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:32:26 INFO - Return code: 0 05:32:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 05:32:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 05:32:26 INFO - Using env: {'DISPLAY': ':0', 05:32:26 INFO - 'HOME': '/home/cltbld', 05:32:26 INFO - 'LANG': 'en_US.UTF-8', 05:32:26 INFO - 'LANGUAGE': 'en_US:en', 05:32:26 INFO - 'LOGNAME': 'cltbld', 05:32:26 INFO - 'MAIL': '/var/mail/cltbld', 05:32:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:26 INFO - 'MOZ_NO_REMOTE': '1', 05:32:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:26 INFO - 'NO_EM_RESTART': '1', 05:32:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:32:26 INFO - 'PWD': '/builds/slave/test-pgo', 05:32:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:32:26 INFO - 'SHELL': '/bin/bash', 05:32:26 INFO - 'SHLVL': '1', 05:32:26 INFO - 'TERM': 'linux', 05:32:26 INFO - 'TMOUT': '86400', 05:32:26 INFO - 'USER': 'cltbld', 05:32:26 INFO - 'XDG_SESSION_COOKIE': 'a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599', 05:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:26 INFO - '_': '/tools/buildbot/bin/python'} 05:32:26 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:32:26 INFO - mozversion application_buildid: 20151231030214 05:32:26 INFO - mozversion application_changeset: 22f51211915bf7daff076180847a7140d35aa353 05:32:26 INFO - mozversion application_display_name: Nightly 05:32:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:26 INFO - mozversion application_name: Firefox 05:32:26 INFO - mozversion application_remotingname: firefox 05:32:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:32:26 INFO - mozversion application_vendor: Mozilla 05:32:26 INFO - mozversion application_version: 46.0a1 05:32:26 INFO - mozversion platform_buildid: 20151231030214 05:32:26 INFO - mozversion platform_changeset: 22f51211915bf7daff076180847a7140d35aa353 05:32:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:32:26 INFO - mozversion platform_version: 46.0a1 05:32:26 INFO - using testdate: 1451568746 05:32:26 INFO - actual date: 1451568746 05:32:27 INFO - starting webserver on 'localhost:57948' 05:32:27 INFO - SUITE-START | Running 3 tests 05:32:27 INFO - TEST-START | dromaeo_css 05:32:27 INFO - operating with platform_type : linux_ 05:32:27 INFO - Initialising browser for dromaeo_css test... 05:32:27 INFO - TEST-INFO | started process 17570 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBeFaEu/profile http://localhost:57948/getInfo.html) 05:32:31 INFO - PROCESS | 17570 | __metrics Screen width/height:1600/1200 05:32:31 INFO - PROCESS | 17570 | colorDepth:24 05:32:31 INFO - PROCESS | 17570 | Browser inner width/height: 1024/697 05:32:31 INFO - PROCESS | 17570 | __metrics 05:32:31 INFO - PROCESS | 17570 | 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:32:31 INFO - PROCESS | 17570 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:32 INFO - TEST-INFO | 17570: exit 0 05:32:32 INFO - Browser initialized. 05:32:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:32 INFO - TEST-INFO | started process 17662 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBeFaEu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:44 INFO - PROCESS | 17662 | RSS: Main: 149389312 05:32:44 INFO - PROCESS | 17662 | 05:33:35 INFO - PROCESS | 17662 | 1451568815125 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:35:44 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57948/tests/dromaeo/cssquery-ext.html) 05:35:45 INFO - PROCESS | 17662 | RSS: Main: 277078016 05:35:45 INFO - PROCESS | 17662 | 05:38:48 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-ext.html (next: http://localhost:57948/tests/dromaeo/cssquery-jquery.html) 05:38:48 INFO - PROCESS | 17662 | RSS: Main: 225771520 05:38:48 INFO - PROCESS | 17662 | 05:41:50 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57948/tests/dromaeo/cssquery-mootools.html) 05:41:51 INFO - PROCESS | 17662 | RSS: Main: 228380672 05:41:51 INFO - PROCESS | 17662 | 05:44:53 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57948/tests/dromaeo/cssquery-prototype.html) 05:44:53 INFO - PROCESS | 17662 | RSS: Main: 208060416 05:44:53 INFO - PROCESS | 17662 | 05:47:56 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57948/tests/dromaeo/cssquery-yui.html) 05:47:56 INFO - PROCESS | 17662 | RSS: Main: 213733376 05:47:56 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | Cycle 1(1): loaded http://localhost:57948/tests/dromaeo/cssquery-yui.html (next: http://localhost:57948/tests/dromaeo/cssquery-dojo.html) 05:50:59 INFO - PROCESS | 17662 | RSS: Main: 271704064 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | __start_tp_report 05:50:59 INFO - PROCESS | 17662 | _x_x_mozilla_page_load 05:50:59 INFO - PROCESS | 17662 | _x_x_mozilla_page_load_details 05:50:59 INFO - PROCESS | 17662 | |i|pagename|runs| 05:50:59 INFO - PROCESS | 17662 | |0;dojo.html;3123.7500999920007;3153.905382838516;3194.0737186215997;3195.424823531763;3218.42068427683;5110.616703747224;5188.792448302068;5197.766100525476;5207.192885102811;5261.815836445725;5228.947710522893;5229.267902493654;5299.178627312763;5303.23103150044;5328.653637513559;4798.808047678089;4803.495632958541;4807.663463557552;4874.439439464471;4897.167481528131;4722.527747225267;4850.8059677612855;4878.804847806101;4921.064997650453;4960.280759289901;4706.152892479352;4835.564799168074;4854.975725121387;4878.414590249157;4891.3396691446915;4818.204996175627;4822.903541929177;4823.25239587861;4828.782704778293;4866.46468888422;4812.422509298906;4815.398075240595;4815.951840481585;4839.540243676845;4849.660523763338;4999.525045120708;5045.924311135336;5094.566961808251;5121.28302037715;5156.123459011977;5680.829575112753;5681.602287839853;5682.289713785777;5685.061964775807;5688.089905615082;5583.441655834425;5693.146028095819;5698.458646428583;5709.628874123168;5714.171445140431;5267.525922666979;5294.41761406245;5303.284056652341;5352.357717073976;5380.596455265871;5803.303603567599;5823.475887170175;5824.068149096209;5832.12518122285;5875.529957603381;5518.255035570227;5649.858753531113;5654.067078932012;5655.293088363955;5661.264035675335;5590.608657393944;5598.860028499239;5668.51817595509;5730.426957304319;5767.048437007847;5641.2384328116;5641.943580564224;5646.604737668324;5653.858653533694;5659.349174844946;5567.415421380778;5645.2096706461125;5647.463490968352;5655.830325090254;5657;4330.155619654201;4364.54172311909;4401.515833258339;4403.075354175595;4433.866983990485;3900.0444891001885;3908.7459315144993;3924.8822535323984;3936.1340505088833;3937.17319362937;5080.949190508048;5201.245819356248;5260.736963151827;5263.18420644793;5314;5608.635438696471;5662.603617746719;5686.061799803069;5692.0608099663095;5735.283089613798;5243.449437809051;5266.578673706094;5273.419923808378;5274.024305503494;5276.498732620395;4761.857144285677;4835.685680430831;4884.633652476078;4911.8280860169725;4946.307516947631;5693.772249109989;5711.600187986801;5720.11338242573;5765.740541675718;5766.971165144315;5625.46558076982;5661.886762264814;5675.801346952837;5689.2319536862;5691.4877661010705;5593.804216852391;5695.345035320991;5702.60081794287;5711.086226203787;5759.8560035999435;5518.862028449321;5523.1991361253195;5529.170624406372;5556.666600004012;5575.1915972184615;5556.083246264321;5557.833265002057;5578.135389014709;5587.0502014656795;5603.607747457639;5372.919406208988;5441.945580544302;5476.342838859362;5494.340679118532;5498.0653288940175;5396.568274538106;5480.506754391964;5484.177373394023;5484.808031719031;5524.640898341595;5390.811321603725;5465.617406781487;5468.72656367188;5468.890622187613;5469.124940009579;5365.490278423544;5523.8066667666435;5566.165075238585;5605.943940560543;5613.691246981291;5390.460953904578;5458.781648734006;5460.153676180198;5463.863403414946;5502.174673798962 05:50:59 INFO - PROCESS | 17662 | |1;ext.html;536.02443552734;583.8265087174779;585.9003969325212;591.1871177131444;605.7667797897809;26503.734962650393;27092.5936110958;27330.726692733067;27485.862570687143;27535.724642753543;27782.86108569454;35670.2865942681;35985.46021809675;36008.27983440329;36063.09842253945;8486.066532681416;8592.914070859273;8642.567871606394;8650.48097114172;8655.221030107288;7443.367313778335;7620.847583048336;8385.62264698087;8504.702335418262;8505.829883402328;10212.795744085113;10424.63513777018;10427.374357538534;10442.530086146142;10450.05949464548;10018.448985305806;10120.645777397829;10129.949350253275;10133.290669653128;10146.340487868301;7494.362979146779;7691.077070751489;9803.529470579399;10115.342502737298;10355.068043876086;34777.304453911034;35279;35287.82356088229;35292.47061294083;35400.46899296513;3128.9374212515622;3211.8715251389917;3402.7788193767574;3987.4018897165365;4010.3312968757705;27569.448611027667;27601;27707.307317317027;27821;28161.436771264664;3673.1945902061198;3929.862454814068;3952.1107750756137;4022.8189731462153;4065.8983525411804;273749;275518;275779;275880;276446;51041.48958510441;52396.21405678918;55940.720296398256;56364.43635563592;56406.43593564094;62726.804561840116;63002.369976300564;63510;63576;63800.68099659472;14727.337269822883;14880.553583392515;14913.776293355608;14981.925090374696;15025.924870375578;11425.542978280942;11516.53933842637;11580.536778528765;11613.76772464563;11626.941865290619;18199.27202911895;18358.265669373344;18588.535286617673;19082.33211837579;19186.61626767457;7371.9262807372315;7545.660445279976;7565.356944659766;7661.157272699998;7707.416051536063;963.1572374172599;1009.152312057875;1029.2743615750878;1113.3411217286348;1130.7964566377966;28096.85951570229;28135.155945321672;28507.144785656463;28559.714402855705;28596;1005.2762011351815;1028.4035259549444;1104.2435931387026;1108.684025052856;1142.3374442823142;14678.853211467962;14779.926100369645;14802;14885.255737213096;14987.775183372258;294017;299452;299541;299707;300009;50643;50844.74577627162;50938.74530627397;51021.489785102414;51040.234396484084;1294.2428679222571;1309.5612969655272;1331.034999625264;1334.8998825088352;1339.243327519948;24779.87610061938;27054.594181086904;29184.27039323949;30244.395112097194;30789.23026924345;40637.390439142844;40935.7953210232;41012.38481422721;41017.58982410138;41024;195842;196974;197227;197306;382377;23135.652965205198;31090.844545777127;31387.68612313939;31726;31911.68088319087;36000.63999360077;36649.81675091608;37075.07312317214;37415.43876841795;37446.25107497889;29706.851465742533;31469;34610.82694586511;35774.8211258942;36401.45397818982;31738.365232694756;32058;32111.839440802647;32331.676683232865;32478.83760581182;32063.519047213846;32189.517157242193;32324.191895202806;32364.190895227806;32472.02583922486;28889;29510.40979180447;29927.251818704706;30282.697173027987;30412.84793576018;31662.36675266528;32152.839235804608;32209.67790322067;32401.35197296088;32684.673153268162 05:50:59 INFO - PROCESS | 17662 | |2;jquery.html;356.2660918507874;356.9446735755958;359.0413595699482;359.20077826835313;360.3423751653233;14169.504067357644;14222.359993800277;16359.263833127508;16810.159492025396;17643.47069587911;15024.17367044812;15642.217889105528;15719.685606287867;16243;16318.265678044487;5186.3257776489;5303.946960530403;5312.601554883399;5315.920261196085;5320.14877619581;4049.331860243071;4157.750534967897;5322.547583455411;5334.946650533503;5845.09401042839;5891.086881533369;5893.793717219898;5912.3496415394275;5930.635953730644;5931.347551769302;5782.508486778608;5902.173695682608;5935.703214839263;5938.168656388066;5943.04911214208;5236.973815130938;5240.9475905241225;5251.396089449724;5253.080710875582;5255.632105752599;20500.282490112924;20941;20984;21141.15435382583;21625.378096642813;2192.5176461178517;2196.1544805249932;2236.809871160937;2238.160689741347;2353.0587764894008;13191.736165276641;13533.120347177402;13590.864091359157;13633.454661813515;13729.862701372958;1874.4938866506104;1878.671232534301;1884.415831092352;1899.7625296837896;1902.6289873474677;60252.69873650648;62429.37570624281;62758;63249.36750632481;63930;9691.176250018687;9864.358816676893;10088.546015429323;10125.493725313705;10162.542685579167;13434.126781759154;13451.192928424325;13483.595492135251;13495.59513214605;13498;7767.300942915164;7799.571023593757;7802.531848089019;7808.843823123506;7938.364930805522;7487.064116985377;7521.623918804147;7545.396368290523;7547.0943486781935;7590.1650818409935;6100.847478813065;6138.324784273726;6171.56799024064;6173.660448675276;6199.411055949678;1672.9247183876755;1717.6822287876532;1741.5559032446645;1781.4299424184137;1796.820317968219;892.3664198419195;901.8827552418275;903.5075883643431;974.2692980264801;995.2833959549113;16266;16353.018818870905;16517.421890234025;17005.57486062858;17318.826811731975;892.4020905993;912.3659056955353;924.7826760711228;942.3262367407336;942.5522876633681;8997.235235005099;9096.545172741337;9186.908130918606;9259.814803704023;9307.3019523536;392673;395562;397202;399674;403544;70222;70411.29588704047;75597.24402756013;78624.60687696525;79451;1101.9504122314515;1126.926749761263;1161.3148242536943;1163.5171403867469;1171.9824202636967;14792.408303667733;15544.844551554339;15619.765703514684;16048.03711777297;16401.42595009169;32348.514772277966;32414.675853242108;32638.673613263563;32662.346753064332;32765;37908.05229869275;40830.79584602058;41383.37924931189;42169.57830421779;43563.56436435595;9504.95247523758;9626.47054411986;9653.517324133905;9724.756881077747;9725.9027409725;17528.82471175272;17611.82388176153;18175.818241817942;18390.172442239862;18392.63214735671;11211.271267367594;11882.227655202385;12021.218620789621;14221.360038798486;14227.359768810222;14120.890647008015;14270;14282.714345713235;14317.355718992461;14372.784408234094;11175.552977880794;11406.087512999105;12503.749925001632;14829.555113346616;15021.774673380127;8559.358048146289;9204.539773011456;9281.443113413217;9765.658201962897;9783.46190959504;14070.577882663536;14202.644933876736;14241.287935602972;14541.563753087425;14586.781198282246 05:50:59 INFO - PROCESS | 17662 | |3;mootools.html;1230.9938450307748;1430.6494908747354;1447.580201741495;1585.5888259449084;1777.9111044447782;6462.838429039276;6972.093627828388;7067.575945443271;7074.540154889942;7078.292170782919;6912.654367281641;7102.822429439267;7140.571565706061;7142.178649455302;7143.892841607367;1901.3440363074717;1929.199382256362;2165.76176620572;2171.1315473810523;2200.2189222825905;2076.107273872234;2082.500199952016;2083.4478863101212;2084.0205103601284;2084.2392526727713;2123.4691327168207;2134.647783115784;2169.078141789741;2169.501014766605;2172.8370372222066;2148.623990801619;2158.4388059104667;2172.8044475997235;2178.814800741939;2182.967255491169;2139;2140.743110826695;2163.729533808274;2164.1127137873395;2165.1123039553863;7154.284571542856;7220;7231.457640676917;7279.272072792732;7294.525855819408;4308.9784551077355;4346.261135606955;4375.7593332366705;4381.992141807402;4387.363832244307;2846.1746093632823;2904.0271509044496;2958.349163184096;2980.8062475938996;2983.0603359941765;1023.3706270643574;1034.053840735726;1051.8685164354456;1054.6994106679635;1070.352436775755;9585.185259252972;9586.568604412818;9626.470544120071;9637.277204209713;9670;3906.746061505993;3992.940105898414;3999.34010888206;4000.4799376080923;4048.6153815387493;8223.876641850258;8254.752357429286;8294.087650358455;8304.377171712145;8315.293200078062;4412.955870441318;4438.866833994985;4443.33349997503;4443.733375997451;4447.043885564619;4052.5339585947995;4061.472008638858;4074.6536544394057;4080.265552200573;4081.6530594899173;3174.206448387903;3254.381667483218;3357.1774915145947;3368.7136593389837;3385.610654774683;1102.2945314998408;1120.6357933671588;1123.466353482106;1123.6292023632182;1123.6966019174777;1067.1036329483272;1075.2634445404922;1092.874319453273;1096.7258185453636;1162.7267592115845;6675.866482670416;6778.72885084601;6780.32196780318;6785.084013658093;6786.660666966632;1069.1233188785122;1090.5419723716059;1100.2133474565724;1101.2236373356764;1122.5705469789707;5361.27622770921;5507.862303442446;5526.4197259288;5527.972360138254;5532.446755324436;8203.8359232815;8296.170382961776;8359.916400835913;8389.20302571267;8599.61301741423;8321.958390208132;8350.582470876432;8355.498670079814;8368.121347258571;8372.03721572015;6638.5684930479365;6672.966635166793;6758.594484330956;6786.355296246948;6839.487038472035;6684.8997265042035;6733.831654208684;6736.360045795642;6742.292059333699;6749.831254218684;7747.457677962489;7769.184235655401;7852.60736963161;7979.202079791974;7988.081370642451;7980.082290536662;8024.558649274346;8055;8055.275025247522;8190.918090819016;6068;6425.357464253724;6483.546151769331;6486.54594178403;6493;6331.145295385064;6450.516211284079;6515.413612774873;6630;6718.865622687421;6228.096925945803;6272.5922815016875;6335.683215839282;6403.551751377358;6455.515836312202;6125.019996800491;6176.197094377702;6182.990329779321;6221.595596286227;6260.154879091265;6245.843853903689;6310.463610593151;6318.62088274705;6370.203724534434;6381.138546296191;5951.166836642787;5961.046232602763;5989.700514974321;5997.6701281428195;5999.730012149376;5835.56233282497;6041.305249896142;6070.453659170696;6076.361981992004;6087.695615219132 05:50:59 INFO - PROCESS | 17662 | |4;prototype.html;315.9541866429368;326.663536557346;327.07274875727313;332.73880004196707;337.648845200991;5988.910166347503;6021.157038014676;6044;6121.785737499188;6159.753609855612;5673.234113394701;5763.366029736726;5796.5652576056755;5903.321118071423;5905.8228253152365;4563;4582.7250364978045;4591.793369298372;4591.9540804592025;4599.551141390468;4521.999999999984;4561.361409402686;4561.726296422209;4564.383808185902;4569.634429245669;4360.803763830635;4415.2494076007;4447.84432544861;4484.439445069366;4512.97743511282;4502.459704835408;4512.9620187357095;4528.796204170821;4533.3426653135275;4539.52335004823;3984.063745019918;3985.06351007513;3986.880393588197;3988.162485877969;3992.540857801361;5677.261955945701;5706;5706.1440783882335;5707.029804933171;5723.456271654186;5218.843434696965;5333.359996800409;5334.333208348957;5336.30628018359;5385.2999110115825;6261.06084087386;6277.152584401093;6328.968355158241;6340.175777148987;6343.555951083426;4506.076254367847;4510.977445112753;4517.616002639779;4517.887052823673;4521.163584736834;6432.807015789487;6485.740570377179;6539.836504087436;6557.93442065578;6561.884479638426;4520.570545798144;4534.909301813946;4539.137563862855;4539.273716205457;4542.273236282179;4574.794134263966;4604.447466303998;4605.631549476101;4607.378003969421;4610.76946152691;5291.947080529146;5293.12663410533;5298.470152984671;5307.522322990872;5312.814051508179;6222.844428889314;6369.904451433232;6375.744970201233;6377.617342959437;6384.776532821329;1627.0806994048326;1667.2997341116761;1667.374734474572;1668.8581470575136;1669.1987845834067;1097.1003776903024;1132.841402203692;1135.3868910788083;1136.9545218191179;1161.7850697620968;5980.102984552352;5985.281766188085;5990.820275391745;5997.490213331828;6006.159137720722;6538.542302038812;6539.934600654028;6543.312952139957;6554.246261679872;6554.63949482783;5818.5636077294375;5925.200097986716;5979.282486101609;6053.4551890330085;6080.179175811298;3538.1862171700654;3560.0209942265565;3565.322588708137;3572.160542272564;3575.3206890690685;6636.435902948207;6738.225104113089;6740.629265390353;6741.22475915276;6741.460683145337;6654.467642588581;6753.763618273337;6783.966080169567;6796.728130874809;6801.965990170116;6705.094812200388;6714.496412769062;6720.529562930548;6723.899141512881;6733.057371968026;6600.306967768315;6603.339666033358;6607.372299631527;6609.603423794588;6616.8345791355605;6409.134766806422;6520.9347906520325;6523.217213934358;6526.379993900572;6541.476681865344;6692.933070669231;6699.0286408471475;6702.530822842549;6704.966475167593;6714.328567143442;4605.286180642006;4608.423947006624;4611.354410382616;4611.976940115278;4616.076784643018;6434.453071488976;6595.340465953366;6599.208095028435;6606.47148228131;6609.339066093352;6399.55203135776;6422.582532135396;6425.710843011982;6428.55000149985;6429.32492088324;6435.646039467687;6444.806655800334;6445.613263204223;6451.548391612542;6454.612723236621;6197.845053873689;6311.621302721851;6322.462590679844;6324.430801227912;6327.303996560467;6447.0651755494255;6465.579737317059;6471.094046833353;6472.644004579794;6485;6259.186305780219;6263.123162757309;6269.0909818075215;6273.247210334606;6281.623102613858 05:50:59 INFO - PROCESS | 17662 | |5;yui.html;747.9813004674883;817.2767101115513;845.8223773007668;850.831471829238;861.220595361198;13557.525486607958;13744;13769.518066867662;13779.655508612277;13795.379207935643;12501.562445314416;12669.04982126342;12757.362131893391;12786.168899021557;13043.478260869553;836.7196989008689;852.9061803201643;853.5348235211825;853.7225401744458;853.9487630742144;838.0990435282066;840.4285086141422;844.3456321350941;845.4969293270525;845.8562044452427;869.5521806269776;876.2639382918347;877.972771856929;878.0078511282242;878.2052242720348;859.6862145316977;869.3740506835069;870.6604424274537;872.3152325424575;873.1486800369652;637.8469167399791;637.9138816259839;638.7061951502315;639.3957709964086;640.4108220437209;12451.190672606252;12547.498100076078;12560.81158782619;12576.182548134342;12594.68513287166;10916.181286403551;10947.288426252348;11094;11167.664970050912;11380.886191138065;9823.214142868554;9975.950120249425;9996.450195239258;10032.398056116583;10035.448050357227;3589.6589823966942;3624.0939765058733;3674.2835147146316;3706.87025954093;3825.9426108608395;42528.787356063025;42907.57092429067;48250.517494824955;48272.51727482715;48302.27546586804;9441.952790236004;9582.952085239529;9641.084097010773;9689.27330450219;9700.64268243168;17893.910530447523;17911.462656120337;17976.460706178834;18008;18015.639687206443;7337.559746415232;7506.84986300271;7513.023306970168;7583.886241706379;7844.37245020397;4058.3100872851687;4229.703920725581;4258.978705106455;4269.91460170801;4309.525952145262;3070.140360699008;3079.707427794356;3097.07087873637;3097.3650401668056;3098.7366073883522;540.446042806119;549.2118809508369;549.3215878390185;555.686037388874;558.4666643355586;490.5952589113995;492.1412135823012;507.69030891156365;530.2311648110256;546.7676237599004;8273.793155171048;8305.211004954519;8398.664053437917;8478.406511544134;8617.310615150773;526.4288247251775;529.8940211957624;567.9375268720437;600.7807150390164;619.7087368936683;3600.42393217084;3667;3667.2482141161;3735.1409175889153;3756.154865155318;60210.698946505865;60430.697846510484;60863.39136608577;61668.69165654233;61997.380026199164;44215;51696.22455663093;55020;55428.891422172135;55657;45863;48875.755621221666;49340.50659493431;49343.50656493461;49494.50505494971;8819;8966.775830604027;9009.941386619566;9025.472867597084;9302.581383837609;35822.10444738902;36059.63940360668;36198;36600.81699591485;36679.63320366869;41317.58682413137;43882;46088.769556152;46167.307490387;47366.52633473621;9145.63417463294;10687.786244274916;10723.678289651323;10857.34855908648;11122.499487522919;11785.764284714087;12015.879841201477;12030.63908082759;12105.939470302592;12493.18794278369;10614.469276535989;10689.839652405375;10999.120070394509;11083.72290692739;11169.609063682732;10271.615239060102;10426;10601;10648.89351106479;10889.891101088888;11451.656450306504;11510.136739744385;11516.078713702716;11801.17446335759;11882.22765520273;9512.830493756892;9871.874189235186;9890.357126786736;9954.253430992849;10131.949340253252;9001.594928228114;9189.724308270763;9224.584893679934;9525.237980961645;9793.216542676426 05:50:59 INFO - PROCESS | 17662 | __end_tp_report 05:50:59 INFO - PROCESS | 17662 | __start_cc_report 05:50:59 INFO - PROCESS | 17662 | _x_x_mozilla_cycle_collect,1047 05:50:59 INFO - PROCESS | 17662 | __end_cc_report 05:50:59 INFO - PROCESS | 17662 | __startTimestamp1451569859612__endTimestamp 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | ------- Summary: start ------- 05:50:59 INFO - PROCESS | 17662 | Number of tests: 6 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#0] dojo.html Cycles:165 Average:5240.93 Median:5460.15 stddev:565.10 (10.3%) stddev-sans-first:541.88 05:50:59 INFO - PROCESS | 17662 | Values: 3123.8 3153.9 3194.1 3195.4 3218.4 5110.6 5188.8 5197.8 5207.2 5261.8 5228.9 5229.3 5299.2 5303.2 5328.7 4798.8 4803.5 4807.7 4874.4 4897.2 4722.5 4850.8 4878.8 4921.1 4960.3 4706.2 4835.6 4855.0 4878.4 4891.3 4818.2 4822.9 4823.3 4828.8 4866.5 4812.4 4815.4 4816.0 4839.5 4849.7 4999.5 5045.9 5094.6 5121.3 5156.1 5680.8 5681.6 5682.3 5685.1 5688.1 5583.4 5693.1 5698.5 5709.6 5714.2 5267.5 5294.4 5303.3 5352.4 5380.6 5803.3 5823.5 5824.1 5832.1 5875.5 5518.3 5649.9 5654.1 5655.3 5661.3 5590.6 5598.9 5668.5 5730.4 5767.0 5641.2 5641.9 5646.6 5653.9 5659.3 5567.4 5645.2 5647.5 5655.8 5657.0 4330.2 4364.5 4401.5 4403.1 4433.9 3900.0 3908.7 3924.9 3936.1 3937.2 5080.9 5201.2 5260.7 5263.2 5314.0 5608.6 5662.6 5686.1 5692.1 5735.3 5243.4 5266.6 5273.4 5274.0 5276.5 4761.9 4835.7 4884.6 4911.8 4946.3 5693.8 5711.6 5720.1 5765.7 5767.0 5625.5 5661.9 5675.8 5689.2 5691.5 5593.8 5695.3 5702.6 5711.1 5759.9 5518.9 5523.2 5529.2 5556.7 5575.2 5556.1 5557.8 5578.1 5587.1 5603.6 5372.9 5441.9 5476.3 5494.3 5498.1 5396.6 5480.5 5484.2 5484.8 5524.6 5390.8 5465.6 5468.7 5468.9 5469.1 5365.5 5523.8 5566.2 5605.9 5613.7 5390.5 5458.8 5460.2 5463.9 5502.2 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#1] ext.html Cycles:180 Average:43081.96 Median:31428.34 stddev:71709.43 (228.2%) stddev-sans-first:71839.44 05:50:59 INFO - PROCESS | 17662 | Values: 536.0 583.8 585.9 591.2 605.8 26503.7 27092.6 27330.7 27485.9 27535.7 27782.9 35670.3 35985.5 36008.3 36063.1 8486.1 8592.9 8642.6 8650.5 8655.2 7443.4 7620.8 8385.6 8504.7 8505.8 10212.8 10424.6 10427.4 10442.5 10450.1 10018.4 10120.6 10129.9 10133.3 10146.3 7494.4 7691.1 9803.5 10115.3 10355.1 34777.3 35279.0 35287.8 35292.5 35400.5 3128.9 3211.9 3402.8 3987.4 4010.3 27569.4 27601.0 27707.3 27821.0 28161.4 3673.2 3929.9 3952.1 4022.8 4065.9 273749.0 275518.0 275779.0 275880.0 276446.0 51041.5 52396.2 55940.7 56364.4 56406.4 62726.8 63002.4 63510.0 63576.0 63800.7 14727.3 14880.6 14913.8 14981.9 15025.9 11425.5 11516.5 11580.5 11613.8 11626.9 18199.3 18358.3 18588.5 19082.3 19186.6 7371.9 7545.7 7565.4 7661.2 7707.4 963.2 1009.2 1029.3 1113.3 1130.8 28096.9 28135.2 28507.1 28559.7 28596.0 1005.3 1028.4 1104.2 1108.7 1142.3 14678.9 14779.9 14802.0 14885.3 14987.8 294017.0 299452.0 299541.0 299707.0 300009.0 50643.0 50844.7 50938.7 51021.5 51040.2 1294.2 1309.6 1331.0 1334.9 1339.2 24779.9 27054.6 29184.3 30244.4 30789.2 40637.4 40935.8 41012.4 41017.6 41024.0 195842.0 196974.0 197227.0 197306.0 382377.0 23135.7 31090.8 31387.7 31726.0 31911.7 36000.6 36649.8 37075.1 37415.4 37446.3 29706.9 31469.0 34610.8 35774.8 36401.5 31738.4 32058.0 32111.8 32331.7 32478.8 32063.5 32189.5 32324.2 32364.2 32472.0 28889.0 29510.4 29927.3 30282.7 30412.8 31662.4 32152.8 32209.7 32401.4 32684.7 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#2] jquery.html Cycles:180 Average:24705.22 Median:359.12 stddev:65183.23 (18150.8%) stddev-sans-first:65340.44 05:50:59 INFO - PROCESS | 17662 | Values: 356.3 356.9 359.0 359.2 360.3 14169.5 14222.4 16359.3 16810.2 17643.5 15024.2 15642.2 15719.7 16243.0 16318.3 5186.3 5303.9 5312.6 5315.9 5320.1 4049.3 4157.8 5322.5 5334.9 5845.1 5891.1 5893.8 5912.3 5930.6 5931.3 5782.5 5902.2 5935.7 5938.2 5943.0 5237.0 5240.9 5251.4 5253.1 5255.6 20500.3 20941.0 20984.0 21141.2 21625.4 2192.5 2196.2 2236.8 2238.2 2353.1 13191.7 13533.1 13590.9 13633.5 13729.9 1874.5 1878.7 1884.4 1899.8 1902.6 60252.7 62429.4 62758.0 63249.4 63930.0 9691.2 9864.4 10088.5 10125.5 10162.5 13434.1 13451.2 13483.6 13495.6 13498.0 7767.3 7799.6 7802.5 7808.8 7938.4 7487.1 7521.6 7545.4 7547.1 7590.2 6100.8 6138.3 6171.6 6173.7 6199.4 1672.9 1717.7 1741.6 1781.4 1796.8 892.4 901.9 903.5 974.3 995.3 16266.0 16353.0 16517.4 17005.6 17318.8 892.4 912.4 924.8 942.3 942.6 8997.2 9096.5 9186.9 9259.8 9307.3 392673.0 395562.0 397202.0 399674.0 403544.0 70222.0 70411.3 75597.2 78624.6 79451.0 1102.0 1126.9 1161.3 1163.5 1172.0 14792.4 15544.8 15619.8 16048.0 16401.4 32348.5 32414.7 32638.7 32662.3 32765.0 37908.1 40830.8 41383.4 42169.6 43563.6 9505.0 9626.5 9653.5 9724.8 9725.9 17528.8 17611.8 18175.8 18390.2 18392.6 11211.3 11882.2 12021.2 14221.4 14227.4 14120.9 14270.0 14282.7 14317.4 14372.8 11175.6 11406.1 12503.7 14829.6 15021.8 8559.4 9204.5 9281.4 9765.7 9783.5 14070.6 14202.6 14241.3 14541.6 14586.8 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#3] mootools.html Cycles:180 Average:5024.88 Median:6020.52 stddev:2538.47 (42.2%) stddev-sans-first:2529.57 05:50:59 INFO - PROCESS | 17662 | Values: 1231.0 1430.6 1447.6 1585.6 1777.9 6462.8 6972.1 7067.6 7074.5 7078.3 6912.7 7102.8 7140.6 7142.2 7143.9 1901.3 1929.2 2165.8 2171.1 2200.2 2076.1 2082.5 2083.4 2084.0 2084.2 2123.5 2134.6 2169.1 2169.5 2172.8 2148.6 2158.4 2172.8 2178.8 2183.0 2139.0 2140.7 2163.7 2164.1 2165.1 7154.3 7220.0 7231.5 7279.3 7294.5 4309.0 4346.3 4375.8 4382.0 4387.4 2846.2 2904.0 2958.3 2980.8 2983.1 1023.4 1034.1 1051.9 1054.7 1070.4 9585.2 9586.6 9626.5 9637.3 9670.0 3906.7 3992.9 3999.3 4000.5 4048.6 8223.9 8254.8 8294.1 8304.4 8315.3 4413.0 4438.9 4443.3 4443.7 4447.0 4052.5 4061.5 4074.7 4080.3 4081.7 3174.2 3254.4 3357.2 3368.7 3385.6 1102.3 1120.6 1123.5 1123.6 1123.7 1067.1 1075.3 1092.9 1096.7 1162.7 6675.9 6778.7 6780.3 6785.1 6786.7 1069.1 1090.5 1100.2 1101.2 1122.6 5361.3 5507.9 5526.4 5528.0 5532.4 8203.8 8296.2 8359.9 8389.2 8599.6 8322.0 8350.6 8355.5 8368.1 8372.0 6638.6 6673.0 6758.6 6786.4 6839.5 6684.9 6733.8 6736.4 6742.3 6749.8 7747.5 7769.2 7852.6 7979.2 7988.1 7980.1 8024.6 8055.0 8055.3 8190.9 6068.0 6425.4 6483.5 6486.5 6493.0 6331.1 6450.5 6515.4 6630.0 6718.9 6228.1 6272.6 6335.7 6403.6 6455.5 6125.0 6176.2 6183.0 6221.6 6260.2 6245.8 6310.5 6318.6 6370.2 6381.1 5951.2 5961.0 5989.7 5997.7 5999.7 5835.6 6041.3 6070.5 6076.4 6087.7 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#4] prototype.html Cycles:180 Average:5316.85 Median:5989.87 stddev:1590.24 (26.5%) stddev-sans-first:1549.77 05:50:59 INFO - PROCESS | 17662 | Values: 316.0 326.7 327.1 332.7 337.6 5988.9 6021.2 6044.0 6121.8 6159.8 5673.2 5763.4 5796.6 5903.3 5905.8 4563.0 4582.7 4591.8 4592.0 4599.6 4522.0 4561.4 4561.7 4564.4 4569.6 4360.8 4415.2 4447.8 4484.4 4513.0 4502.5 4513.0 4528.8 4533.3 4539.5 3984.1 3985.1 3986.9 3988.2 3992.5 5677.3 5706.0 5706.1 5707.0 5723.5 5218.8 5333.4 5334.3 5336.3 5385.3 6261.1 6277.2 6329.0 6340.2 6343.6 4506.1 4511.0 4517.6 4517.9 4521.2 6432.8 6485.7 6539.8 6557.9 6561.9 4520.6 4534.9 4539.1 4539.3 4542.3 4574.8 4604.4 4605.6 4607.4 4610.8 5291.9 5293.1 5298.5 5307.5 5312.8 6222.8 6369.9 6375.7 6377.6 6384.8 1627.1 1667.3 1667.4 1668.9 1669.2 1097.1 1132.8 1135.4 1137.0 1161.8 5980.1 5985.3 5990.8 5997.5 6006.2 6538.5 6539.9 6543.3 6554.2 6554.6 5818.6 5925.2 5979.3 6053.5 6080.2 3538.2 3560.0 3565.3 3572.2 3575.3 6636.4 6738.2 6740.6 6741.2 6741.5 6654.5 6753.8 6784.0 6796.7 6802.0 6705.1 6714.5 6720.5 6723.9 6733.1 6600.3 6603.3 6607.4 6609.6 6616.8 6409.1 6520.9 6523.2 6526.4 6541.5 6692.9 6699.0 6702.5 6705.0 6714.3 4605.3 4608.4 4611.4 4612.0 4616.1 6434.5 6595.3 6599.2 6606.5 6609.3 6399.6 6422.6 6425.7 6428.6 6429.3 6435.6 6444.8 6445.6 6451.5 6454.6 6197.8 6311.6 6322.5 6324.4 6327.3 6447.1 6465.6 6471.1 6472.6 6485.0 6259.2 6263.1 6269.1 6273.2 6281.6 05:50:59 INFO - PROCESS | 17662 | 05:50:59 INFO - PROCESS | 17662 | [#5] yui.html Cycles:180 Average:13836.70 Median:491.37 stddev:16421.65 (3342.0%) stddev-sans-first:16438.30 05:50:59 INFO - PROCESS | 17662 | Values: 748.0 817.3 845.8 850.8 861.2 13557.5 13744.0 13769.5 13779.7 13795.4 12501.6 12669.0 12757.4 12786.2 13043.5 836.7 852.9 853.5 853.7 853.9 838.1 840.4 844.3 845.5 845.9 869.6 876.3 878.0 878.0 878.2 859.7 869.4 870.7 872.3 873.1 637.8 637.9 638.7 639.4 640.4 12451.2 12547.5 12560.8 12576.2 12594.7 10916.2 10947.3 11094.0 11167.7 11380.9 9823.2 9976.0 9996.5 10032.4 10035.4 3589.7 3624.1 3674.3 3706.9 3825.9 42528.8 42907.6 48250.5 48272.5 48302.3 9442.0 9583.0 9641.1 9689.3 9700.6 17893.9 17911.5 17976.5 18008.0 18015.6 7337.6 7506.8 7513.0 7583.9 7844.4 4058.3 4229.7 4259.0 4269.9 4309.5 3070.1 3079.7 3097.1 3097.4 3098.7 540.4 549.2 549.3 555.7 558.5 490.6 492.1 507.7 530.2 546.8 8273.8 8305.2 8398.7 8478.4 8617.3 526.4 529.9 567.9 600.8 619.7 3600.4 3667.0 3667.2 3735.1 3756.2 60210.7 60430.7 60863.4 61668.7 61997.4 44215.0 51696.2 55020.0 55428.9 55657.0 45863.0 48875.8 49340.5 49343.5 49494.5 8819.0 8966.8 9009.9 9025.5 9302.6 35822.1 36059.6 36198.0 36600.8 36679.6 41317.6 43882.0 46088.8 46167.3 47366.5 9145.6 10687.8 10723.7 10857.3 11122.5 11785.8 12015.9 12030.6 12105.9 12493.2 10614.5 10689.8 10999.1 11083.7 11169.6 10271.6 10426.0 10601.0 10648.9 10889.9 11451.7 11510.1 11516.1 11801.2 11882.2 9512.8 9871.9 9890.4 9954.3 10131.9 9001.6 9189.7 9224.6 9525.2 9793.2 05:50:59 INFO - PROCESS | 17662 | -------- Summary: end -------- 05:50:59 INFO - PROCESS | 17662 | 05:51:00 INFO - TEST-INFO | 17662: exit 0 05:51:00 INFO - TEST-OK | dromaeo_css | took 1113276ms 05:51:00 INFO - TEST-START | kraken 05:51:00 INFO - operating with platform_type : linux_ 05:51:00 INFO - Initialising browser for kraken test... 05:51:00 INFO - TEST-INFO | started process 17849 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUHgkHv/profile http://localhost:57948/getInfo.html) 05:51:04 INFO - PROCESS | 17849 | __metrics Screen width/height:1600/1200 05:51:04 INFO - PROCESS | 17849 | colorDepth:24 05:51:04 INFO - PROCESS | 17849 | Browser inner width/height: 1024/697 05:51:04 INFO - PROCESS | 17849 | __metrics 05:51:04 INFO - PROCESS | 17849 | 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:51:04 INFO - PROCESS | 17849 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:05 INFO - TEST-INFO | 17849: exit 0 05:51:05 INFO - Browser initialized. 05:51:05 INFO - Running cycle 1/1 for kraken test... 05:51:05 INFO - TEST-INFO | started process 17929 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUHgkHv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:17 INFO - PROCESS | 17929 | RSS: Main: 149823488 05:51:17 INFO - PROCESS | 17929 | 05:51:19 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57948/tests/kraken/driver.html?testName=audio-beat-detection) 05:51:20 INFO - PROCESS | 17929 | RSS: Main: 183336960 05:51:20 INFO - PROCESS | 17929 | 05:51:22 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57948/tests/kraken/driver.html?testName=audio-dft) 05:51:23 INFO - PROCESS | 17929 | RSS: Main: 174096384 05:51:23 INFO - PROCESS | 17929 | 05:51:26 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57948/tests/kraken/driver.html?testName=audio-fft) 05:51:26 INFO - PROCESS | 17929 | RSS: Main: 161218560 05:51:26 INFO - PROCESS | 17929 | 05:51:28 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57948/tests/kraken/driver.html?testName=audio-oscillator) 05:51:29 INFO - PROCESS | 17929 | RSS: Main: 163168256 05:51:29 INFO - PROCESS | 17929 | 05:51:31 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:32 INFO - PROCESS | 17929 | RSS: Main: 163176448 05:51:32 INFO - PROCESS | 17929 | 05:51:35 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57948/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:35 INFO - PROCESS | 17929 | RSS: Main: 163786752 05:51:35 INFO - PROCESS | 17929 | 05:51:39 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57948/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:39 INFO - PROCESS | 17929 | RSS: Main: 165380096 05:51:39 INFO - PROCESS | 17929 | 05:51:42 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57948/tests/kraken/driver.html?testName=json-parse-financial) 05:51:43 INFO - PROCESS | 17929 | RSS: Main: 165347328 05:51:43 INFO - PROCESS | 17929 | 05:51:44 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:45 INFO - PROCESS | 17929 | RSS: Main: 177233920 05:51:45 INFO - PROCESS | 17929 | 05:51:47 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:47 INFO - PROCESS | 17929 | RSS: Main: 175538176 05:51:47 INFO - PROCESS | 17929 | 05:51:49 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:50 INFO - PROCESS | 17929 | RSS: Main: 182112256 05:51:50 INFO - PROCESS | 17929 | 05:51:52 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:53 INFO - PROCESS | 17929 | RSS: Main: 188395520 05:51:53 INFO - PROCESS | 17929 | 05:51:55 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:56 INFO - PROCESS | 17929 | RSS: Main: 187904000 05:51:56 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | Cycle 1(1): loaded http://localhost:57948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57948/tests/kraken/driver.html?testName=ai-astar) 05:51:58 INFO - PROCESS | 17929 | RSS: Main: 246431744 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | __start_tp_report 05:51:58 INFO - PROCESS | 17929 | _x_x_mozilla_page_load 05:51:58 INFO - PROCESS | 17929 | _x_x_mozilla_page_load_details 05:51:58 INFO - PROCESS | 17929 | |i|pagename|runs| 05:51:58 INFO - PROCESS | 17929 | |0;ai-astar;112;114;109;112;111;113;100;111;112;103 05:51:58 INFO - PROCESS | 17929 | |1;audio-beat-detection;159;134;152;166;125;168;152;124;162;125 05:51:58 INFO - PROCESS | 17929 | |2;audio-dft;161;160;159;159;162;161;161;161;162;161 05:51:58 INFO - PROCESS | 17929 | |3;audio-fft;73;72;84;95;72;73;72;105;90;71 05:51:58 INFO - PROCESS | 17929 | |4;audio-oscillator;95;96;143;96;97;97;144;96;97;138 05:51:58 INFO - PROCESS | 17929 | |5;imaging-gaussian-blur;110;109;110;109;109;109;109;110;109;110 05:51:58 INFO - PROCESS | 17929 | |6;imaging-darkroom;146;145;145;146;145;146;146;145;145;145 05:51:58 INFO - PROCESS | 17929 | |7;imaging-desaturate;96;96;96;97;95;95;95;96;96;95 05:51:58 INFO - PROCESS | 17929 | |8;json-parse-financial;58;59;60;58;60;60;58;70;56;56 05:51:58 INFO - PROCESS | 17929 | |9;json-stringify-tinderbox;68;69;69;70;70;74;69;69;68;69 05:51:58 INFO - PROCESS | 17929 | |10;stanford-crypto-aes;86;86;89;85;94;86;86;86;87;86 05:51:58 INFO - PROCESS | 17929 | |11;stanford-crypto-ccm;117;115;118;125;121;123;120;119;117;122 05:51:58 INFO - PROCESS | 17929 | |12;stanford-crypto-pbkdf2;144;144;155;153;155;145;144;155;159;152 05:51:58 INFO - PROCESS | 17929 | |13;stanford-crypto-sha256-iterative;70;68;70;68;71;67;67;69;69;69 05:51:58 INFO - PROCESS | 17929 | __end_tp_report 05:51:58 INFO - PROCESS | 17929 | __start_cc_report 05:51:58 INFO - PROCESS | 17929 | _x_x_mozilla_cycle_collect,949 05:51:58 INFO - PROCESS | 17929 | __end_cc_report 05:51:58 INFO - PROCESS | 17929 | __startTimestamp1451569918457__endTimestamp 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | ------- Summary: start ------- 05:51:58 INFO - PROCESS | 17929 | Number of tests: 14 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#0] ai-astar Cycles:10 Average:109.70 Median:112.00 stddev:4.57 (4.1%) stddev-sans-first:4.77 05:51:58 INFO - PROCESS | 17929 | Values: 112.0 114.0 109.0 112.0 111.0 113.0 100.0 111.0 112.0 103.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:155.50 stddev:17.91 (11.5%) stddev-sans-first:18.43 05:51:58 INFO - PROCESS | 17929 | Values: 159.0 134.0 152.0 166.0 125.0 168.0 152.0 124.0 162.0 125.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 05:51:58 INFO - PROCESS | 17929 | Values: 161.0 160.0 159.0 159.0 162.0 161.0 161.0 161.0 162.0 161.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#3] audio-fft Cycles:10 Average:80.70 Median:73.00 stddev:12.17 (16.7%) stddev-sans-first:12.58 05:51:58 INFO - PROCESS | 17929 | Values: 73.0 72.0 84.0 95.0 72.0 73.0 72.0 105.0 90.0 71.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#4] audio-oscillator Cycles:10 Average:109.90 Median:96.00 stddev:21.98 (22.9%) stddev-sans-first:22.64 05:51:58 INFO - PROCESS | 17929 | Values: 95.0 96.0 143.0 96.0 97.0 97.0 144.0 96.0 97.0 138.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:51:58 INFO - PROCESS | 17929 | Values: 110.0 109.0 110.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 05:51:58 INFO - PROCESS | 17929 | Values: 146.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:51:58 INFO - PROCESS | 17929 | Values: 96.0 96.0 96.0 97.0 95.0 95.0 95.0 96.0 96.0 95.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.50 stddev:3.98 (6.7%) stddev-sans-first:4.18 05:51:58 INFO - PROCESS | 17929 | Values: 58.0 59.0 60.0 58.0 60.0 60.0 58.0 70.0 56.0 56.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:1.72 (2.5%) stddev-sans-first:1.73 05:51:58 INFO - PROCESS | 17929 | Values: 68.0 69.0 69.0 70.0 70.0 74.0 69.0 69.0 68.0 69.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:86.00 stddev:2.64 (3.1%) stddev-sans-first:2.77 05:51:58 INFO - PROCESS | 17929 | Values: 86.0 86.0 89.0 85.0 94.0 86.0 86.0 86.0 87.0 86.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#11] stanford-crypto-ccm Cycles:10 Average:119.70 Median:120.50 stddev:3.09 (2.6%) stddev-sans-first:3.12 05:51:58 INFO - PROCESS | 17929 | Values: 117.0 115.0 118.0 125.0 121.0 123.0 120.0 119.0 117.0 122.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:154.00 stddev:5.76 (3.7%) stddev-sans-first:5.59 05:51:58 INFO - PROCESS | 17929 | Values: 144.0 144.0 155.0 153.0 155.0 145.0 144.0 155.0 159.0 152.0 05:51:58 INFO - PROCESS | 17929 | 05:51:58 INFO - PROCESS | 17929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.32 (1.9%) stddev-sans-first:1.32 05:51:58 INFO - PROCESS | 17929 | Values: 70.0 68.0 70.0 68.0 71.0 67.0 67.0 69.0 69.0 69.0 05:51:58 INFO - PROCESS | 17929 | -------- Summary: end -------- 05:51:58 INFO - PROCESS | 17929 | 05:51:59 INFO - TEST-INFO | 17929: exit 0 05:51:59 INFO - TEST-OK | kraken | took 59010ms 05:51:59 INFO - TEST-START | v8_7 05:51:59 INFO - operating with platform_type : linux_ 05:51:59 INFO - Initialising browser for v8_7 test... 05:51:59 INFO - TEST-INFO | started process 18179 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqg09Qz/profile http://localhost:57948/getInfo.html) 05:52:03 INFO - PROCESS | 18179 | __metrics Screen width/height:1600/1200 05:52:03 INFO - PROCESS | 18179 | colorDepth:24 05:52:03 INFO - PROCESS | 18179 | Browser inner width/height: 1024/697 05:52:03 INFO - PROCESS | 18179 | __metrics 05:52:03 INFO - PROCESS | 18179 | 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:03 INFO - PROCESS | 18179 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:52:04 INFO - TEST-INFO | 18179: exit 0 05:52:04 INFO - Browser initialized. 05:52:04 INFO - Running cycle 1/1 for v8_7 test... 05:52:04 INFO - TEST-INFO | started process 18260 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqg09Qz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:16 INFO - PROCESS | 18260 | RSS: Main: 150114304 05:52:16 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | Cycle 1(1): loaded http://localhost:57948/tests/v8_7/run.html (next: http://localhost:57948/tests/v8_7/run.html) 05:52:37 INFO - PROCESS | 18260 | RSS: Main: 553238528 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | __start_tp_report 05:52:37 INFO - PROCESS | 18260 | _x_x_mozilla_page_load 05:52:37 INFO - PROCESS | 18260 | _x_x_mozilla_page_load_details 05:52:37 INFO - PROCESS | 18260 | |i|pagename|runs| 05:52:37 INFO - PROCESS | 18260 | |0;Richards;168.03898504453034 05:52:37 INFO - PROCESS | 18260 | |1;DeltaBlue;191.38755980861245 05:52:37 INFO - PROCESS | 18260 | |2;Encrypt;280.50490883590464 05:52:37 INFO - PROCESS | 18260 | |3;Decrypt;5246.073298429319 05:52:37 INFO - PROCESS | 18260 | |4;RayTrace;1116.0714285714287 05:52:37 INFO - PROCESS | 18260 | |5;Earley;789.8894154818325 05:52:37 INFO - PROCESS | 18260 | |6;Boyer;12407.407407407407 05:52:37 INFO - PROCESS | 18260 | |7;RegExp;26102.5641025641 05:52:37 INFO - PROCESS | 18260 | |8;Splay;585.1375073142189 05:52:37 INFO - PROCESS | 18260 | |9;NavierStokes;4830.917874396136 05:52:37 INFO - PROCESS | 18260 | __end_tp_report 05:52:37 INFO - PROCESS | 18260 | __startTimestamp1451569957636__endTimestamp 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | ------- Summary: start ------- 05:52:37 INFO - PROCESS | 18260 | Number of tests: 10 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#0] Richards Cycles:1 Average:168.04 Median:168.04 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 168.0 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#1] DeltaBlue Cycles:1 Average:191.39 Median:191.39 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 191.4 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#2] Encrypt Cycles:1 Average:280.50 Median:280.50 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 280.5 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 5246.1 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#4] RayTrace Cycles:1 Average:1116.07 Median:1116.07 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 1116.1 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#5] Earley Cycles:1 Average:789.89 Median:789.89 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 789.9 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#6] Boyer Cycles:1 Average:12407.41 Median:12407.41 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 12407.4 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#7] RegExp Cycles:1 Average:26102.56 Median:26102.56 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 26102.6 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#8] Splay Cycles:1 Average:585.14 Median:585.14 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 585.1 05:52:37 INFO - PROCESS | 18260 | 05:52:37 INFO - PROCESS | 18260 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:52:37 INFO - PROCESS | 18260 | Values: 4830.9 05:52:37 INFO - PROCESS | 18260 | -------- Summary: end -------- 05:52:37 INFO - PROCESS | 18260 | 05:52:38 INFO - TEST-INFO | 18260: exit 0 05:52:38 INFO - TEST-OK | v8_7 | took 39131ms 05:52:38 INFO - SUITE-END | took 1211s 05:52:38 INFO - Completed test suite (00:20:11) 05:52:38 INFO - Working with test: dromaeo_css 05:52:38 INFO - Generating results file: dromaeo_css 05:52:38 INFO - Working with test: kraken 05:52:38 INFO - Generating results file: kraken 05:52:38 INFO - javascript benchmark 05:52:38 INFO - Working with test: v8_7 05:52:38 INFO - Generating results file: v8_7 05:52:38 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:38 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 05:52:38 INFO - process_Request line: dromaeo_css 6843.90 graph.html#tests=[[72,1,35]] 05:52:38 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:38 INFO - process_Request line: kraken 1513.40 graph.html#tests=[[232,1,35]] 05:52:38 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:52:38 INFO - process_Request line: v8_7 20954.82 graph.html#tests=[[230,1,35]] 05:52:38 INFO - RETURN: dromaeo_css: 6843.90 05:52:38 INFO - RETURN: kraken: 1513.40 05:52:38 INFO - RETURN: v8_7: 20954.82 05:52:38 INFO - javascript benchmark 05:52:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3123.7500999920007, 3153.905382838516, 3194.0737186215997, 3195.424823531763, 3218.42068427683, 5110.616703747224, 5188.792448302068, 5197.766100525476, 5207.192885102811, 5261.815836445725, 5228.947710522893, 5229.267902493654, 5299.178627312763, 5303.23103150044, 5328.653637513559, 4798.808047678089, 4803.495632958541, 4807.663463557552, 4874.439439464471, 4897.167481528131, 4722.527747225267, 4850.8059677612855, 4878.804847806101, 4921.064997650453, 4960.280759289901, 4706.152892479352, 4835.564799168074, 4854.975725121387, 4878.414590249157, 4891.3396691446915, 4818.204996175627, 4822.903541929177, 4823.25239587861, 4828.782704778293, 4866.46468888422, 4812.422509298906, 4815.398075240595, 4815.951840481585, 4839.540243676845, 4849.660523763338, 4999.525045120708, 5045.924311135336, 5094.566961808251, 5121.28302037715, 5156.123459011977, 5680.829575112753, 5681.602287839853, 5682.289713785777, 5685.061964775807, 5688.089905615082, 5583.441655834425, 5693.146028095819, 5698.458646428583, 5709.628874123168, 5714.171445140431, 5267.525922666979, 5294.41761406245, 5303.284056652341, 5352.357717073976, 5380.596455265871, 5803.303603567599, 5823.475887170175, 5824.068149096209, 5832.12518122285, 5875.529957603381, 5518.255035570227, 5649.858753531113, 5654.067078932012, 5655.293088363955, 5661.264035675335, 5590.608657393944, 5598.860028499239, 5668.51817595509, 5730.426957304319, 5767.048437007847, 5641.2384328116, 5641.943580564224, 5646.604737668324, 5653.858653533694, 5659.349174844946, 5567.415421380778, 5645.2096706461125, 5647.463490968352, 5655.830325090254, 5657.0, 4330.155619654201, 4364.54172311909, 4401.515833258339, 4403.075354175595, 4433.866983990485, 3900.0444891001885, 3908.7459315144993, 3924.8822535323984, 3936.1340505088833, 3937.17319362937, 5080.949190508048, 5201.245819356248, 5260.736963151827, 5263.18420644793, 5314.0, 5608.635438696471, 5662.603617746719, 5686.061799803069, 5692.0608099663095, 5735.283089613798, 5243.449437809051, 5266.578673706094, 5273.419923808378, 5274.024305503494, 5276.498732620395, 4761.857144285677, 4835.685680430831, 4884.633652476078, 4911.8280860169725, 4946.307516947631, 5693.772249109989, 5711.600187986801, 5720.11338242573, 5765.740541675718, 5766.971165144315, 5625.46558076982, 5661.886762264814, 5675.801346952837, 5689.2319536862, 5691.4877661010705, 5593.804216852391, 5695.345035320991, 5702.60081794287, 5711.086226203787, 5759.8560035999435, 5518.862028449321, 5523.1991361253195, 5529.170624406372, 5556.666600004012, 5575.1915972184615, 5556.083246264321, 5557.833265002057, 5578.135389014709, 5587.0502014656795, 5603.607747457639, 5372.919406208988, 5441.945580544302, 5476.342838859362, 5494.340679118532, 5498.0653288940175, 5396.568274538106, 5480.506754391964, 5484.177373394023, 5484.808031719031, 5524.640898341595, 5390.811321603725, 5465.617406781487, 5468.72656367188, 5468.890622187613, 5469.124940009579, 5365.490278423544, 5523.8066667666435, 5566.165075238585, 5605.943940560543, 5613.691246981291, 5390.460953904578, 5458.781648734006, 5460.153676180198, 5463.863403414946, 5502.174673798962], "unit": "score", "name": "dojo.html", "value": 5205.283104681315}, {"lowerIsBetter": false, "replicates": [536.02443552734, 583.8265087174779, 585.9003969325212, 591.1871177131444, 605.7667797897809, 26503.734962650393, 27092.5936110958, 27330.726692733067, 27485.862570687143, 27535.724642753543, 27782.86108569454, 35670.2865942681, 35985.46021809675, 36008.27983440329, 36063.09842253945, 8486.066532681416, 8592.914070859273, 8642.567871606394, 8650.48097114172, 8655.221030107288, 7443.367313778335, 7620.847583048336, 8385.62264698087, 8504.702335418262, 8505.829883402328, 10212.795744085113, 10424.63513777018, 10427.374357538534, 10442.530086146142, 10450.05949464548, 10018.448985305806, 10120.645777397829, 10129.949350253275, 10133.290669653128, 10146.340487868301, 7494.362979146779, 7691.077070751489, 9803.529470579399, 10115.342502737298, 10355.068043876086, 34777.304453911034, 35279.0, 35287.82356088229, 35292.47061294083, 35400.46899296513, 3128.9374212515622, 3211.8715251389917, 3402.7788193767574, 3987.4018897165365, 4010.3312968757705, 27569.448611027667, 27601.0, 27707.307317317027, 27821.0, 28161.436771264664, 3673.1945902061198, 3929.862454814068, 3952.1107750756137, 4022.8189731462153, 4065.8983525411804, 273749.0, 275518.0, 275779.0, 275880.0, 276446.0, 51041.48958510441, 52396.21405678918, 55940.720296398256, 56364.43635563592, 56406.43593564094, 62726.804561840116, 63002.369976300564, 63510.0, 63576.0, 63800.68099659472, 14727.337269822883, 14880.553583392515, 14913.776293355608, 14981.925090374696, 15025.924870375578, 11425.542978280942, 11516.53933842637, 11580.536778528765, 11613.76772464563, 11626.941865290619, 18199.27202911895, 18358.265669373344, 18588.535286617673, 19082.33211837579, 19186.61626767457, 7371.9262807372315, 7545.660445279976, 7565.356944659766, 7661.157272699998, 7707.416051536063, 963.1572374172599, 1009.152312057875, 1029.2743615750878, 1113.3411217286348, 1130.7964566377966, 28096.85951570229, 28135.155945321672, 28507.144785656463, 28559.714402855705, 28596.0, 1005.2762011351815, 1028.4035259549444, 1104.2435931387026, 1108.684025052856, 1142.3374442823142, 14678.853211467962, 14779.926100369645, 14802.0, 14885.255737213096, 14987.775183372258, 294017.0, 299452.0, 299541.0, 299707.0, 300009.0, 50643.0, 50844.74577627162, 50938.74530627397, 51021.489785102414, 51040.234396484084, 1294.2428679222571, 1309.5612969655272, 1331.034999625264, 1334.8998825088352, 1339.243327519948, 24779.87610061938, 27054.594181086904, 29184.27039323949, 30244.395112097194, 30789.23026924345, 40637.390439142844, 40935.7953210232, 41012.38481422721, 41017.58982410138, 41024.0, 195842.0, 196974.0, 197227.0, 197306.0, 382377.0, 23135.652965205198, 31090.844545777127, 31387.68612313939, 31726.0, 31911.68088319087, 36000.63999360077, 36649.81675091608, 37075.07312317214, 37415.43876841795, 37446.25107497889, 29706.851465742533, 31469.0, 34610.82694586511, 35774.8211258942, 36401.45397818982, 31738.365232694756, 32058.0, 32111.839440802647, 32331.676683232865, 32478.83760581182, 32063.519047213846, 32189.517157242193, 32324.191895202806, 32364.190895227806, 32472.02583922486, 28889.0, 29510.40979180447, 29927.251818704706, 30282.697173027987, 30412.84793576018, 31662.36675266528, 32152.839235804608, 32209.67790322067, 32401.35197296088, 32684.673153268162], "unit": "score", "name": "ext.html", "value": 17826.90840785343}, {"lowerIsBetter": false, "replicates": [356.2660918507874, 356.9446735755958, 359.0413595699482, 359.20077826835313, 360.3423751653233, 14169.504067357644, 14222.359993800277, 16359.263833127508, 16810.159492025396, 17643.47069587911, 15024.17367044812, 15642.217889105528, 15719.685606287867, 16243.0, 16318.265678044487, 5186.3257776489, 5303.946960530403, 5312.601554883399, 5315.920261196085, 5320.14877619581, 4049.331860243071, 4157.750534967897, 5322.547583455411, 5334.946650533503, 5845.09401042839, 5891.086881533369, 5893.793717219898, 5912.3496415394275, 5930.635953730644, 5931.347551769302, 5782.508486778608, 5902.173695682608, 5935.703214839263, 5938.168656388066, 5943.04911214208, 5236.973815130938, 5240.9475905241225, 5251.396089449724, 5253.080710875582, 5255.632105752599, 20500.282490112924, 20941.0, 20984.0, 21141.15435382583, 21625.378096642813, 2192.5176461178517, 2196.1544805249932, 2236.809871160937, 2238.160689741347, 2353.0587764894008, 13191.736165276641, 13533.120347177402, 13590.864091359157, 13633.454661813515, 13729.862701372958, 1874.4938866506104, 1878.671232534301, 1884.415831092352, 1899.7625296837896, 1902.6289873474677, 60252.69873650648, 62429.37570624281, 62758.0, 63249.36750632481, 63930.0, 9691.176250018687, 9864.358816676893, 10088.546015429323, 10125.493725313705, 10162.542685579167, 13434.126781759154, 13451.192928424325, 13483.595492135251, 13495.59513214605, 13498.0, 7767.300942915164, 7799.571023593757, 7802.531848089019, 7808.843823123506, 7938.364930805522, 7487.064116985377, 7521.623918804147, 7545.396368290523, 7547.0943486781935, 7590.1650818409935, 6100.847478813065, 6138.324784273726, 6171.56799024064, 6173.660448675276, 6199.411055949678, 1672.9247183876755, 1717.6822287876532, 1741.5559032446645, 1781.4299424184137, 1796.820317968219, 892.3664198419195, 901.8827552418275, 903.5075883643431, 974.2692980264801, 995.2833959549113, 16266.0, 16353.018818870905, 16517.421890234025, 17005.57486062858, 17318.826811731975, 892.4020905993, 912.3659056955353, 924.7826760711228, 942.3262367407336, 942.5522876633681, 8997.235235005099, 9096.545172741337, 9186.908130918606, 9259.814803704023, 9307.3019523536, 392673.0, 395562.0, 397202.0, 399674.0, 403544.0, 70222.0, 70411.29588704047, 75597.24402756013, 78624.60687696525, 79451.0, 1101.9504122314515, 1126.926749761263, 1161.3148242536943, 1163.5171403867469, 1171.9824202636967, 14792.408303667733, 15544.844551554339, 15619.765703514684, 16048.03711777297, 16401.42595009169, 32348.514772277966, 32414.675853242108, 32638.673613263563, 32662.346753064332, 32765.0, 37908.05229869275, 40830.79584602058, 41383.37924931189, 42169.57830421779, 43563.56436435595, 9504.95247523758, 9626.47054411986, 9653.517324133905, 9724.756881077747, 9725.9027409725, 17528.82471175272, 17611.82388176153, 18175.818241817942, 18390.172442239862, 18392.63214735671, 11211.271267367594, 11882.227655202385, 12021.218620789621, 14221.360038798486, 14227.359768810222, 14120.890647008015, 14270.0, 14282.714345713235, 14317.355718992461, 14372.784408234094, 11175.552977880794, 11406.087512999105, 12503.749925001632, 14829.555113346616, 15021.774673380127, 8559.358048146289, 9204.539773011456, 9281.443113413217, 9765.658201962897, 9783.46190959504, 14070.577882663536, 14202.644933876736, 14241.287935602972, 14541.563753087425, 14586.781198282246], "unit": "score", "name": "jquery.html", "value": 8887.288606109294}, {"lowerIsBetter": false, "replicates": [1230.9938450307748, 1430.6494908747354, 1447.580201741495, 1585.5888259449084, 1777.9111044447782, 6462.838429039276, 6972.093627828388, 7067.575945443271, 7074.540154889942, 7078.292170782919, 6912.654367281641, 7102.822429439267, 7140.571565706061, 7142.178649455302, 7143.892841607367, 1901.3440363074717, 1929.199382256362, 2165.76176620572, 2171.1315473810523, 2200.2189222825905, 2076.107273872234, 2082.500199952016, 2083.4478863101212, 2084.0205103601284, 2084.2392526727713, 2123.4691327168207, 2134.647783115784, 2169.078141789741, 2169.501014766605, 2172.8370372222066, 2148.623990801619, 2158.4388059104667, 2172.8044475997235, 2178.814800741939, 2182.967255491169, 2139.0, 2140.743110826695, 2163.729533808274, 2164.1127137873395, 2165.1123039553863, 7154.284571542856, 7220.0, 7231.457640676917, 7279.272072792732, 7294.525855819408, 4308.9784551077355, 4346.261135606955, 4375.7593332366705, 4381.992141807402, 4387.363832244307, 2846.1746093632823, 2904.0271509044496, 2958.349163184096, 2980.8062475938996, 2983.0603359941765, 1023.3706270643574, 1034.053840735726, 1051.8685164354456, 1054.6994106679635, 1070.352436775755, 9585.185259252972, 9586.568604412818, 9626.470544120071, 9637.277204209713, 9670.0, 3906.746061505993, 3992.940105898414, 3999.34010888206, 4000.4799376080923, 4048.6153815387493, 8223.876641850258, 8254.752357429286, 8294.087650358455, 8304.377171712145, 8315.293200078062, 4412.955870441318, 4438.866833994985, 4443.33349997503, 4443.733375997451, 4447.043885564619, 4052.5339585947995, 4061.472008638858, 4074.6536544394057, 4080.265552200573, 4081.6530594899173, 3174.206448387903, 3254.381667483218, 3357.1774915145947, 3368.7136593389837, 3385.610654774683, 1102.2945314998408, 1120.6357933671588, 1123.466353482106, 1123.6292023632182, 1123.6966019174777, 1067.1036329483272, 1075.2634445404922, 1092.874319453273, 1096.7258185453636, 1162.7267592115845, 6675.866482670416, 6778.72885084601, 6780.32196780318, 6785.084013658093, 6786.660666966632, 1069.1233188785122, 1090.5419723716059, 1100.2133474565724, 1101.2236373356764, 1122.5705469789707, 5361.27622770921, 5507.862303442446, 5526.4197259288, 5527.972360138254, 5532.446755324436, 8203.8359232815, 8296.170382961776, 8359.916400835913, 8389.20302571267, 8599.61301741423, 8321.958390208132, 8350.582470876432, 8355.498670079814, 8368.121347258571, 8372.03721572015, 6638.5684930479365, 6672.966635166793, 6758.594484330956, 6786.355296246948, 6839.487038472035, 6684.8997265042035, 6733.831654208684, 6736.360045795642, 6742.292059333699, 6749.831254218684, 7747.457677962489, 7769.184235655401, 7852.60736963161, 7979.202079791974, 7988.081370642451, 7980.082290536662, 8024.558649274346, 8055.0, 8055.275025247522, 8190.918090819016, 6068.0, 6425.357464253724, 6483.546151769331, 6486.54594178403, 6493.0, 6331.145295385064, 6450.516211284079, 6515.413612774873, 6630.0, 6718.865622687421, 6228.096925945803, 6272.5922815016875, 6335.683215839282, 6403.551751377358, 6455.515836312202, 6125.019996800491, 6176.197094377702, 6182.990329779321, 6221.595596286227, 6260.154879091265, 6245.843853903689, 6310.463610593151, 6318.62088274705, 6370.203724534434, 6381.138546296191, 5951.166836642787, 5961.046232602763, 5989.700514974321, 5997.6701281428195, 5999.730012149376, 5835.56233282497, 6041.305249896142, 6070.453659170696, 6076.361981992004, 6087.695615219132], "unit": "score", "name": "mootools.html", "value": 4175.2313774766635}, {"lowerIsBetter": false, "replicates": [315.9541866429368, 326.663536557346, 327.07274875727313, 332.73880004196707, 337.648845200991, 5988.910166347503, 6021.157038014676, 6044.0, 6121.785737499188, 6159.753609855612, 5673.234113394701, 5763.366029736726, 5796.5652576056755, 5903.321118071423, 5905.8228253152365, 4563.0, 4582.7250364978045, 4591.793369298372, 4591.9540804592025, 4599.551141390468, 4521.999999999984, 4561.361409402686, 4561.726296422209, 4564.383808185902, 4569.634429245669, 4360.803763830635, 4415.2494076007, 4447.84432544861, 4484.439445069366, 4512.97743511282, 4502.459704835408, 4512.9620187357095, 4528.796204170821, 4533.3426653135275, 4539.52335004823, 3984.063745019918, 3985.06351007513, 3986.880393588197, 3988.162485877969, 3992.540857801361, 5677.261955945701, 5706.0, 5706.1440783882335, 5707.029804933171, 5723.456271654186, 5218.843434696965, 5333.359996800409, 5334.333208348957, 5336.30628018359, 5385.2999110115825, 6261.06084087386, 6277.152584401093, 6328.968355158241, 6340.175777148987, 6343.555951083426, 4506.076254367847, 4510.977445112753, 4517.616002639779, 4517.887052823673, 4521.163584736834, 6432.807015789487, 6485.740570377179, 6539.836504087436, 6557.93442065578, 6561.884479638426, 4520.570545798144, 4534.909301813946, 4539.137563862855, 4539.273716205457, 4542.273236282179, 4574.794134263966, 4604.447466303998, 4605.631549476101, 4607.378003969421, 4610.76946152691, 5291.947080529146, 5293.12663410533, 5298.470152984671, 5307.522322990872, 5312.814051508179, 6222.844428889314, 6369.904451433232, 6375.744970201233, 6377.617342959437, 6384.776532821329, 1627.0806994048326, 1667.2997341116761, 1667.374734474572, 1668.8581470575136, 1669.1987845834067, 1097.1003776903024, 1132.841402203692, 1135.3868910788083, 1136.9545218191179, 1161.7850697620968, 5980.102984552352, 5985.281766188085, 5990.820275391745, 5997.490213331828, 6006.159137720722, 6538.542302038812, 6539.934600654028, 6543.312952139957, 6554.246261679872, 6554.63949482783, 5818.5636077294375, 5925.200097986716, 5979.282486101609, 6053.4551890330085, 6080.179175811298, 3538.1862171700654, 3560.0209942265565, 3565.322588708137, 3572.160542272564, 3575.3206890690685, 6636.435902948207, 6738.225104113089, 6740.629265390353, 6741.22475915276, 6741.460683145337, 6654.467642588581, 6753.763618273337, 6783.966080169567, 6796.728130874809, 6801.965990170116, 6705.094812200388, 6714.496412769062, 6720.529562930548, 6723.899141512881, 6733.057371968026, 6600.306967768315, 6603.339666033358, 6607.372299631527, 6609.603423794588, 6616.8345791355605, 6409.134766806422, 6520.9347906520325, 6523.217213934358, 6526.379993900572, 6541.476681865344, 6692.933070669231, 6699.0286408471475, 6702.530822842549, 6704.966475167593, 6714.328567143442, 4605.286180642006, 4608.423947006624, 4611.354410382616, 4611.976940115278, 4616.076784643018, 6434.453071488976, 6595.340465953366, 6599.208095028435, 6606.47148228131, 6609.339066093352, 6399.55203135776, 6422.582532135396, 6425.710843011982, 6428.55000149985, 6429.32492088324, 6435.646039467687, 6444.806655800334, 6445.613263204223, 6451.548391612542, 6454.612723236621, 6197.845053873689, 6311.621302721851, 6322.462590679844, 6324.430801227912, 6327.303996560467, 6447.0651755494255, 6465.579737317059, 6471.094046833353, 6472.644004579794, 6485.0, 6259.186305780219, 6263.123162757309, 6269.0909818075215, 6273.247210334606, 6281.623102613858], "unit": "score", "name": "prototype.html", "value": 4801.692521557812}, {"lowerIsBetter": false, "replicates": [747.9813004674883, 817.2767101115513, 845.8223773007668, 850.831471829238, 861.220595361198, 13557.525486607958, 13744.0, 13769.518066867662, 13779.655508612277, 13795.379207935643, 12501.562445314416, 12669.04982126342, 12757.362131893391, 12786.168899021557, 13043.478260869553, 836.7196989008689, 852.9061803201643, 853.5348235211825, 853.7225401744458, 853.9487630742144, 838.0990435282066, 840.4285086141422, 844.3456321350941, 845.4969293270525, 845.8562044452427, 869.5521806269776, 876.2639382918347, 877.972771856929, 878.0078511282242, 878.2052242720348, 859.6862145316977, 869.3740506835069, 870.6604424274537, 872.3152325424575, 873.1486800369652, 637.8469167399791, 637.9138816259839, 638.7061951502315, 639.3957709964086, 640.4108220437209, 12451.190672606252, 12547.498100076078, 12560.81158782619, 12576.182548134342, 12594.68513287166, 10916.181286403551, 10947.288426252348, 11094.0, 11167.664970050912, 11380.886191138065, 9823.214142868554, 9975.950120249425, 9996.450195239258, 10032.398056116583, 10035.448050357227, 3589.6589823966942, 3624.0939765058733, 3674.2835147146316, 3706.87025954093, 3825.9426108608395, 42528.787356063025, 42907.57092429067, 48250.517494824955, 48272.51727482715, 48302.27546586804, 9441.952790236004, 9582.952085239529, 9641.084097010773, 9689.27330450219, 9700.64268243168, 17893.910530447523, 17911.462656120337, 17976.460706178834, 18008.0, 18015.639687206443, 7337.559746415232, 7506.84986300271, 7513.023306970168, 7583.886241706379, 7844.37245020397, 4058.3100872851687, 4229.703920725581, 4258.978705106455, 4269.91460170801, 4309.525952145262, 3070.140360699008, 3079.707427794356, 3097.07087873637, 3097.3650401668056, 3098.7366073883522, 540.446042806119, 549.2118809508369, 549.3215878390185, 555.686037388874, 558.4666643355586, 490.5952589113995, 492.1412135823012, 507.69030891156365, 530.2311648110256, 546.7676237599004, 8273.793155171048, 8305.211004954519, 8398.664053437917, 8478.406511544134, 8617.310615150773, 526.4288247251775, 529.8940211957624, 567.9375268720437, 600.7807150390164, 619.7087368936683, 3600.42393217084, 3667.0, 3667.2482141161, 3735.1409175889153, 3756.154865155318, 60210.698946505865, 60430.697846510484, 60863.39136608577, 61668.69165654233, 61997.380026199164, 44215.0, 51696.22455663093, 55020.0, 55428.891422172135, 55657.0, 45863.0, 48875.755621221666, 49340.50659493431, 49343.50656493461, 49494.50505494971, 8819.0, 8966.775830604027, 9009.941386619566, 9025.472867597084, 9302.581383837609, 35822.10444738902, 36059.63940360668, 36198.0, 36600.81699591485, 36679.63320366869, 41317.58682413137, 43882.0, 46088.769556152, 46167.307490387, 47366.52633473621, 9145.63417463294, 10687.786244274916, 10723.678289651323, 10857.34855908648, 11122.499487522919, 11785.764284714087, 12015.879841201477, 12030.63908082759, 12105.939470302592, 12493.18794278369, 10614.469276535989, 10689.839652405375, 10999.120070394509, 11083.72290692739, 11169.609063682732, 10271.615239060102, 10426.0, 10601.0, 10648.89351106479, 10889.891101088888, 11451.656450306504, 11510.136739744385, 11516.078713702716, 11801.17446335759, 11882.22765520273, 9512.830493756892, 9871.874189235186, 9890.357126786736, 9954.253430992849, 10131.949340253252, 9001.594928228114, 9189.724308270763, 9224.584893679934, 9525.237980961645, 9793.216542676426], "unit": "score", "name": "yui.html", "value": 6214.6510597827555}], "name": "dromaeo_css", "value": 6843.899045807337}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 114.0, 109.0, 112.0, 111.0, 113.0, 100.0, 111.0, 112.0, 103.0], "unit": "score", "name": "ai-astar", "value": 109.7}, {"lowerIsBetter": true, "replicates": [159.0, 134.0, 152.0, 166.0, 125.0, 168.0, 152.0, 124.0, 162.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 146.7}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 159.0, 162.0, 161.0, 161.0, 161.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 84.0, 95.0, 72.0, 73.0, 72.0, 105.0, 90.0, 71.0], "unit": "score", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 143.0, 96.0, 97.0, 97.0, 144.0, 96.0, 97.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 109.9}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 97.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 60.0, 58.0, 60.0, 60.0, 58.0, 70.0, 56.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 69.0, 70.0, 70.0, 74.0, 69.0, 69.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.5}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 89.0, 85.0, 94.0, 86.0, 86.0, 86.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.1}, {"lowerIsBetter": true, "replicates": [117.0, 115.0, 118.0, 125.0, 121.0, 123.0, 120.0, 119.0, 117.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.7}, {"lowerIsBetter": true, "replicates": [144.0, 144.0, 155.0, 153.0, 155.0, 145.0, 144.0, 155.0, 159.0, 152.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 150.6}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 70.0, 68.0, 71.0, 67.0, 67.0, 69.0, 69.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.8}], "name": "kraken", "value": 1513.3999999999996}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.03898504453034], "unit": "score", "name": "Richards", "value": 210.08220199999997}, {"lowerIsBetter": false, "replicates": [191.38755980861245], "unit": "score", "name": "DeltaBlue", "value": 345.4665499999999}, {"lowerIsBetter": false, "replicates": [280.50490883590464], "unit": "score", "name": "Encrypt", "value": 948.9352650000003}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [1116.0714285714287], "unit": "score", "name": "RayTrace", "value": 663.0301440000001}, {"lowerIsBetter": false, "replicates": [789.8894154818325], "unit": "score", "name": "Earley", "value": 843.7421580000001}, {"lowerIsBetter": false, "replicates": [12407.407407407407], "unit": "score", "name": "Boyer", "value": 53.71492835820899}, {"lowerIsBetter": false, "replicates": [26102.5641025641], "unit": "score", "name": "RegExp", "value": 34.90021119842827}, {"lowerIsBetter": false, "replicates": [585.1375073142189], "unit": "score", "name": "Splay", "value": 139.26811900000004}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20954.81960225813}]} 05:52:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20954.82"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1513.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6843.90"}}} 05:52:38 INFO - Return code: 0 05:52:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:52:39 INFO - # TBPL SUCCESS # 05:52:39 INFO - Running post-action listener: _resource_record_post_action 05:52:39 INFO - Running post-run listener: _resource_record_post_run 05:52:40 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544256000; Read time: 12; Write time: 574332 05:52:40 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234573824; Read time: 0; Write time: 284892 05:52:40 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 308805632; Read time: 12; Write time: 289404 05:52:40 INFO - Running post-run listener: _upload_blobber_files 05:52:40 INFO - Blob upload gear active. 05:52:40 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:52:40 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:52:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 05:52:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 05:52:40 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:52:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:52:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:41 INFO - (blobuploader) - INFO - Done attempting. 05:52:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:52:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:52:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:42 INFO - (blobuploader) - INFO - Done attempting. 05:52:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:42 INFO - Return code: 0 05:52:42 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:52:42 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04cfff9d84d66cb0fd3f8154a91a3a39ea7c21f3b7b5380ddc0fdac9276609b157db0a3e20ea8b3e917a3448136d09f3d94164e0c1d0f5912431f3a1062b9d01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eb9713e3d1370196e944512a326a07fdcd6871eb311fcca11c857d248276d93f4bd97092d27e20a8db9e9afc749f114be464f9f3f8eaa098dcffa66ede96773"} 05:52:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:52:42 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:52:42 INFO - Contents: 05:52:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04cfff9d84d66cb0fd3f8154a91a3a39ea7c21f3b7b5380ddc0fdac9276609b157db0a3e20ea8b3e917a3448136d09f3d94164e0c1d0f5912431f3a1062b9d01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eb9713e3d1370196e944512a326a07fdcd6871eb311fcca11c857d248276d93f4bd97092d27e20a8db9e9afc749f114be464f9f3f8eaa098dcffa66ede96773"} 05:52:42 INFO - Copying logs to upload dir... 05:52:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1275.837950 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2015-12-31 05:52:42.068215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-31 05:52:42.071563) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04cfff9d84d66cb0fd3f8154a91a3a39ea7c21f3b7b5380ddc0fdac9276609b157db0a3e20ea8b3e917a3448136d09f3d94164e0c1d0f5912431f3a1062b9d01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eb9713e3d1370196e944512a326a07fdcd6871eb311fcca11c857d248276d93f4bd97092d27e20a8db9e9afc749f114be464f9f3f8eaa098dcffa66ede96773"} build_url:https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/VW1VHzOJTL6IEpARlPKw9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04cfff9d84d66cb0fd3f8154a91a3a39ea7c21f3b7b5380ddc0fdac9276609b157db0a3e20ea8b3e917a3448136d09f3d94164e0c1d0f5912431f3a1062b9d01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4eb9713e3d1370196e944512a326a07fdcd6871eb311fcca11c857d248276d93f4bd97092d27e20a8db9e9afc749f114be464f9f3f8eaa098dcffa66ede96773"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-31 05:52:42.097583) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:52:42.097983) ========= 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=a9e534cca236dfa67d42a089000001c1-1451567583.531188-2128748599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-31 05:52:42.179316) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-31 05:52:42.179667) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-31 05:52:42.180019) ========= ========= Total master_lag: 0.86 =========