builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1451828604.67 results: success (0) buildid: 20160103030302 builduid: 18edf61e6bf446079e860acb6e19026e revision: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.670568) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.671042) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.671387) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006437 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.700291) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.700605) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.755297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.755592) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.804881) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.805217) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.805628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.805938) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-03 05:43: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% 60.3M=0s 2016-01-03 05:43:25 (60.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162629 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.981322) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:24.981676) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016255 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.032366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.032774) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --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 d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 --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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-03 05:43:25,775 truncating revision to first 12 chars 2016-01-03 05:43:25,775 Setting DEBUG logging. 2016-01-03 05:43:25,775 attempt 1/10 2016-01-03 05:43:25,775 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d7a0ad85d9fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-03 05:43:26,123 unpacking tar archive at: mozilla-central-d7a0ad85d9fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644315 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.691379) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.691709) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.706762) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.707060) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-03 05:43:25.707509) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-03 05:43:25.707848) ========= /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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:43:26 INFO - MultiFileLogger online at 20160103 05:43:26 in /builds/slave/test-pgo 05:43: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:43:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:43:26 INFO - {'append_to_log': False, 05:43:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:43:26 INFO - 'blob_upload_branch': 'Firefox', 05:43:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:43:26 INFO - 'branch': 'Firefox', 05:43:26 INFO - 'buildbot_json_path': 'buildprops.json', 05:43:26 INFO - 'config_files': ('talos/linux_config.py',), 05:43:26 INFO - 'default_actions': ('clobber', 05:43:26 INFO - 'read-buildbot-config', 05:43:26 INFO - 'download-and-extract', 05:43:26 INFO - 'populate-webroot', 05:43:26 INFO - 'create-virtualenv', 05:43:26 INFO - 'install', 05:43:26 INFO - 'run-tests'), 05:43:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:43:26 INFO - 'download_minidump_stackwalk': True, 05:43:26 INFO - 'download_symbols': 'ondemand', 05:43:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:43:26 INFO - 'tooltool.py': '/tools/tooltool.py', 05:43:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:43:26 INFO - '/tools/misc-python/virtualenv.py')}, 05:43:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:43:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:43:26 INFO - 'installer_path': 'installer.exe', 05:43:26 INFO - 'log_level': 'info', 05:43:26 INFO - 'log_name': 'talos', 05:43:26 INFO - 'log_to_console': True, 05:43:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:43:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:43:26 INFO - 'opt_config_files': (), 05:43:26 INFO - 'pip_index': False, 05:43:26 INFO - 'sps_profile': False, 05:43:26 INFO - 'sps_profile_interval': 0, 05:43:26 INFO - 'suite': 'dromaeojs', 05:43:26 INFO - 'system_bits': '32', 05:43:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:43:26 INFO - 'title': 'talos-linux64-ix-042', 05:43:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:43:26 INFO - 'use_talos_json': True, 05:43:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:43:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:43:26 INFO - 'work_dir': 'build'} 05:43:26 INFO - ##### 05:43:26 INFO - ##### Running clobber step. 05:43:26 INFO - ##### 05:43:26 INFO - Running pre-action listener: _resource_record_pre_action 05:43:26 INFO - Running main action method: clobber 05:43:26 INFO - rmtree: /builds/slave/test-pgo/build 05:43:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:43:26 INFO - Running post-action listener: _resource_record_post_action 05:43:26 INFO - ##### 05:43:26 INFO - ##### Running read-buildbot-config step. 05:43:26 INFO - ##### 05:43:26 INFO - Running pre-action listener: _resource_record_pre_action 05:43:26 INFO - Running main action method: read_buildbot_config 05:43:26 INFO - Using buildbot properties: 05:43:26 INFO - { 05:43:26 INFO - "project": "", 05:43:26 INFO - "product": "firefox", 05:43:26 INFO - "script_repo_revision": "production", 05:43:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:43:26 INFO - "repository": "", 05:43:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:43:26 INFO - "buildid": "20160103030302", 05:43:26 INFO - "stage_platform": "linux64-pgo", 05:43:26 INFO - "basedir": "/builds/slave/test-pgo", 05:43:26 INFO - "nightly_build": "True", 05:43:26 INFO - "buildnumber": 630, 05:43:26 INFO - "slavename": "talos-linux64-ix-042", 05:43:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:43:26 INFO - "platform": "ubuntu64_hw", 05:43:26 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:43:26 INFO - "branch": "mozilla-central", 05:43:26 INFO - "repo_path": "mozilla-central", 05:43:26 INFO - "revision": "d7a0ad85d9fb77916f9d77d62697b852f3dc63e6", 05:43:26 INFO - "pgo_build": "True", 05:43:26 INFO - "builduid": "18edf61e6bf446079e860acb6e19026e", 05:43:26 INFO - "slavebuilddir": "test" 05:43:26 INFO - } 05:43:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:43:26 INFO - Running post-action listener: _resource_record_post_action 05:43:26 INFO - ##### 05:43:26 INFO - ##### Running download-and-extract step. 05:43:26 INFO - ##### 05:43:26 INFO - Running pre-action listener: _resource_record_pre_action 05:43:26 INFO - Running main action method: download_and_extract 05:43:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:43:26 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:43:26 INFO - https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:43:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/test_packages.json 05:43:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/test_packages.json 05:43:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:43:33 INFO - Downloaded 1302 bytes. 05:43:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:43:33 INFO - Using the following test package requirements: 05:43:33 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:43:33 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:43:33 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'jsshell-linux-x86_64.zip'], 05:43:33 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:43:33 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:43:33 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:43:33 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:43:33 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:43:33 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:43:33 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:43:33 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:43:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:33 INFO - https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:43:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:37 INFO - Downloaded 23198118 bytes. 05:43:37 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:43:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43:37 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:43:39 INFO - Return code: 0 05:43:39 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:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:39 INFO - https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:43:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:42 INFO - Downloaded 23198118 bytes. 05:43: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:43:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:43: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:43:43 INFO - Return code: 0 05:43: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:43:43 INFO - https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:43:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:43:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:43:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:44 INFO - Downloaded 11132285 bytes. 05:43:44 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:43:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:43:44 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:43:44 INFO - Return code: 0 05:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:44 INFO - https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:43:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:43:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:43:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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:43:46 INFO - Downloaded 54981630 bytes. 05:43:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:43:46 INFO - mkdir: /builds/slave/test-pgo/properties 05:43:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:43:46 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:43:46 INFO - Contents: 05:43:46 INFO - build_url:https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:43:46 INFO - Running post-action listener: _resource_record_post_action 05:43:46 INFO - Running post-action listener: set_extra_try_arguments 05:43:46 INFO - ##### 05:43:46 INFO - ##### Running populate-webroot step. 05:43:46 INFO - ##### 05:43:46 INFO - Running pre-action listener: _resource_record_pre_action 05:43:46 INFO - Running main action method: populate_webroot 05:43:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:43:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:43:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:43:46 INFO - '--tppagecycles', 05:43:46 INFO - '7'], 05:43:46 INFO - 'tests': ['tsvgm']}}, 05:43:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:43:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:43:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:43:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:43:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'tests': ['damp', 'tps']}, 05:43:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['damp', 'tps']}, 05:43:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:43:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:43:46 INFO - 'other': {'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'other_l64': {'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'other_nol64': {'tests': ['a11yr', 05:43:46 INFO - 'ts_paint', 05:43:46 INFO - 'tpaint', 05:43:46 INFO - 'sessionrestore', 05:43:46 INFO - 'sessionrestore_no_auto_restore']}, 05:43:46 INFO - 'svgr': {'tests': ['tsvgx', 05:43:46 INFO - 'tsvgr_opacity', 05:43:46 INFO - 'tart', 05:43:46 INFO - 'tscrollx', 05:43:46 INFO - 'cart']}, 05:43:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['tsvgx', 05:43:46 INFO - 'tsvgr_opacity', 05:43:46 INFO - 'tart', 05:43:46 INFO - 'tscrollx', 05:43:46 INFO - 'cart']}, 05:43:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'tests': ['tp5o']}, 05:43:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'talos_options': ['--e10s'], 05:43:46 INFO - 'tests': ['tp5o']}, 05:43:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'talos_options': ['--xperf_path', 05:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:46 INFO - 'tests': ['tp5n']}, 05:43:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:46 INFO - 'talos_options': ['--e10s', 05:43:46 INFO - '--xperf_path', 05:43:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:46 INFO - 'tests': ['tp5n']}}, 05:43:46 INFO - 'talos.zip': {'path': '', 05:43:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:43:46 INFO - Running post-action listener: _resource_record_post_action 05:43:46 INFO - ##### 05:43:46 INFO - ##### Running create-virtualenv step. 05:43:46 INFO - ##### 05:43:46 INFO - Running pre-action listener: _resource_record_pre_action 05:43:46 INFO - Running main action method: create_virtualenv 05:43:46 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:43: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:43:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:43:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:43:46 INFO - Using real prefix '/usr' 05:43:46 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:43:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:43:48 INFO - Installing pip.................done. 05:43:48 INFO - Return code: 0 05:43:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:48 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:43:48 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:43:48 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:43:48 INFO - Using env: {'DISPLAY': ':0', 05:43:48 INFO - 'HOME': '/home/cltbld', 05:43:48 INFO - 'LANG': 'en_US.UTF-8', 05:43:48 INFO - 'LANGUAGE': 'en_US:en', 05:43:48 INFO - 'LOGNAME': 'cltbld', 05:43:48 INFO - 'MAIL': '/var/mail/cltbld', 05:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:48 INFO - 'MOZ_NO_REMOTE': '1', 05:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:48 INFO - 'NO_EM_RESTART': '1', 05:43:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:48 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:48 INFO - 'SHELL': '/bin/bash', 05:43:48 INFO - 'SHLVL': '1', 05:43:48 INFO - 'TERM': 'linux', 05:43:48 INFO - 'TMOUT': '86400', 05:43:48 INFO - 'USER': 'cltbld', 05:43:48 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:48 INFO - '_': '/tools/buildbot/bin/python'} 05:43:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:48 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:43:48 INFO - Cleaning up... 05:43:48 INFO - Return code: 0 05:43:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:48 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:43:48 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:43:48 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:43:48 INFO - Using env: {'DISPLAY': ':0', 05:43:48 INFO - 'HOME': '/home/cltbld', 05:43:48 INFO - 'LANG': 'en_US.UTF-8', 05:43:48 INFO - 'LANGUAGE': 'en_US:en', 05:43:48 INFO - 'LOGNAME': 'cltbld', 05:43:48 INFO - 'MAIL': '/var/mail/cltbld', 05:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:48 INFO - 'MOZ_NO_REMOTE': '1', 05:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:48 INFO - 'NO_EM_RESTART': '1', 05:43:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:48 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:48 INFO - 'SHELL': '/bin/bash', 05:43:48 INFO - 'SHLVL': '1', 05:43:48 INFO - 'TERM': 'linux', 05:43:48 INFO - 'TMOUT': '86400', 05:43:48 INFO - 'USER': 'cltbld', 05:43:48 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:48 INFO - '_': '/tools/buildbot/bin/python'} 05:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:49 INFO - Downloading/unpacking psutil>=0.7.1 05:43: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:43: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:43: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:43: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:43: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:43: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:43:50 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:43:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:43:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:43:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:51 INFO - Installing collected packages: psutil 05:43:51 INFO - Running setup.py install for psutil 05:43:51 INFO - building 'psutil._psutil_linux' extension 05:43: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:43: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:43:51 INFO - building 'psutil._psutil_posix' extension 05:43: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:43: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:43:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:51 INFO - Successfully installed psutil 05:43:51 INFO - Cleaning up... 05:43:51 INFO - Return code: 0 05:43:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:43:51 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:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 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:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:43:51 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:43:51 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:43:51 INFO - Using env: {'DISPLAY': ':0', 05:43:51 INFO - 'HOME': '/home/cltbld', 05:43:51 INFO - 'LANG': 'en_US.UTF-8', 05:43:51 INFO - 'LANGUAGE': 'en_US:en', 05:43:51 INFO - 'LOGNAME': 'cltbld', 05:43:51 INFO - 'MAIL': '/var/mail/cltbld', 05:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:51 INFO - 'MOZ_NO_REMOTE': '1', 05:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:51 INFO - 'NO_EM_RESTART': '1', 05:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:51 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:51 INFO - 'SHELL': '/bin/bash', 05:43:51 INFO - 'SHLVL': '1', 05:43:51 INFO - 'TERM': 'linux', 05:43:51 INFO - 'TMOUT': '86400', 05:43:51 INFO - 'USER': 'cltbld', 05:43:51 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:51 INFO - '_': '/tools/buildbot/bin/python'} 05:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:43:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:43:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:43:53 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:43:53 INFO - Installing collected packages: mozsystemmonitor 05:43:53 INFO - Running setup.py install for mozsystemmonitor 05:43:53 INFO - Successfully installed mozsystemmonitor 05:43:53 INFO - Cleaning up... 05:43:53 INFO - Return code: 0 05:43:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:53 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:43:53 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:43:53 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:43:53 INFO - Using env: {'DISPLAY': ':0', 05:43:53 INFO - 'HOME': '/home/cltbld', 05:43:53 INFO - 'LANG': 'en_US.UTF-8', 05:43:53 INFO - 'LANGUAGE': 'en_US:en', 05:43:53 INFO - 'LOGNAME': 'cltbld', 05:43:53 INFO - 'MAIL': '/var/mail/cltbld', 05:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:53 INFO - 'MOZ_NO_REMOTE': '1', 05:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:53 INFO - 'NO_EM_RESTART': '1', 05:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:53 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:53 INFO - 'SHELL': '/bin/bash', 05:43:53 INFO - 'SHLVL': '1', 05:43:53 INFO - 'TERM': 'linux', 05:43:53 INFO - 'TMOUT': '86400', 05:43:53 INFO - 'USER': 'cltbld', 05:43:53 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:53 INFO - '_': '/tools/buildbot/bin/python'} 05:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:53 INFO - Downloading/unpacking blobuploader==1.2.4 05:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:55 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:43:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:55 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:43:55 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:43:55 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:43:55 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:43:55 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:43:55 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:43:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:43:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:43:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43: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:43: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:43: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:43: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:43: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:43: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:43:56 INFO - Downloading docopt-0.6.1.tar.gz 05:43: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:43:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:56 INFO - Running setup.py install for blobuploader 05:43:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:43:56 INFO - Running setup.py install for requests 05:43:56 INFO - Running setup.py install for docopt 05:43:56 INFO - Successfully installed blobuploader requests docopt 05:43:56 INFO - Cleaning up... 05:43:56 INFO - Return code: 0 05:43:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:43:56 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:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 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:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:43:56 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:43:56 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:43:56 INFO - Using env: {'DISPLAY': ':0', 05:43:56 INFO - 'HOME': '/home/cltbld', 05:43:56 INFO - 'LANG': 'en_US.UTF-8', 05:43:56 INFO - 'LANGUAGE': 'en_US:en', 05:43:56 INFO - 'LOGNAME': 'cltbld', 05:43:56 INFO - 'MAIL': '/var/mail/cltbld', 05:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:56 INFO - 'MOZ_NO_REMOTE': '1', 05:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:56 INFO - 'NO_EM_RESTART': '1', 05:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:56 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:56 INFO - 'SHELL': '/bin/bash', 05:43:56 INFO - 'SHLVL': '1', 05:43:56 INFO - 'TERM': 'linux', 05:43:56 INFO - 'TMOUT': '86400', 05:43:56 INFO - 'USER': 'cltbld', 05:43:56 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:56 INFO - '_': '/tools/buildbot/bin/python'} 05:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:57 INFO - Running setup.py (path:/tmp/pip-uitW39-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:57 INFO - Running setup.py (path:/tmp/pip-gUKSHU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:57 INFO - Running setup.py (path:/tmp/pip-7VTTpr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:57 INFO - Running setup.py (path:/tmp/pip-oByuOS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:57 INFO - Running setup.py (path:/tmp/pip-5jfqqd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:57 INFO - Running setup.py (path:/tmp/pip-6pbO2V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:57 INFO - Running setup.py (path:/tmp/pip-Lwe7Uk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:57 INFO - Running setup.py (path:/tmp/pip-Hdtajc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:57 INFO - Running setup.py (path:/tmp/pip-KugRlx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:57 INFO - Running setup.py (path:/tmp/pip-XGqiNI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:57 INFO - Running setup.py (path:/tmp/pip-IypHm8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:57 INFO - Running setup.py (path:/tmp/pip-E5hVA8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:57 INFO - Running setup.py (path:/tmp/pip-k0nbKv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:58 INFO - Running setup.py (path:/tmp/pip-9_BJ4z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:58 INFO - Running setup.py (path:/tmp/pip-dZfeov-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:58 INFO - Running setup.py (path:/tmp/pip-bsWlMQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:58 INFO - Running setup.py (path:/tmp/pip-bs9LwL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:58 INFO - Running setup.py install for manifestparser 05:43:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:43:58 INFO - Running setup.py install for mozcrash 05:43:58 INFO - Running setup.py install for mozdebug 05:43:58 INFO - Running setup.py install for mozdevice 05:43:58 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:43:58 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:43:58 INFO - Running setup.py install for mozfile 05:43:58 INFO - Running setup.py install for mozhttpd 05:43:58 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:43:58 INFO - Running setup.py install for mozinfo 05:43:59 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for mozInstall 05:43:59 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for mozleak 05:43:59 INFO - Running setup.py install for mozlog 05:43:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for moznetwork 05:43:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for mozprocess 05:43:59 INFO - Running setup.py install for mozprofile 05:43:59 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for mozrunner 05:43:59 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:43:59 INFO - Running setup.py install for mozscreenshot 05:43:59 INFO - Running setup.py install for moztest 05:44:00 INFO - Running setup.py install for mozversion 05:44:00 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:44:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:44:00 INFO - Cleaning up... 05:44:00 INFO - Return code: 0 05:44:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:44: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:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:44: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:44: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:44:00 INFO - Using env: {'DISPLAY': ':0', 05:44:00 INFO - 'HOME': '/home/cltbld', 05:44:00 INFO - 'LANG': 'en_US.UTF-8', 05:44:00 INFO - 'LANGUAGE': 'en_US:en', 05:44:00 INFO - 'LOGNAME': 'cltbld', 05:44:00 INFO - 'MAIL': '/var/mail/cltbld', 05:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:00 INFO - 'MOZ_NO_REMOTE': '1', 05:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:00 INFO - 'NO_EM_RESTART': '1', 05:44:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:44:00 INFO - 'PWD': '/builds/slave/test-pgo', 05:44:00 INFO - 'SHELL': '/bin/bash', 05:44:00 INFO - 'SHLVL': '1', 05:44:00 INFO - 'TERM': 'linux', 05:44:00 INFO - 'TMOUT': '86400', 05:44:00 INFO - 'USER': 'cltbld', 05:44:00 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:00 INFO - '_': '/tools/buildbot/bin/python'} 05:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:44:00 INFO - Running setup.py (path:/tmp/pip-5tKSbK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:44: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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:44:00 INFO - Running setup.py (path:/tmp/pip-IN1tQF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:44:00 INFO - Running setup.py (path:/tmp/pip-wYwHLS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:44:00 INFO - Running setup.py (path:/tmp/pip-25dPc9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:44:00 INFO - Running setup.py (path:/tmp/pip-3ufQ2j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:44:00 INFO - Running setup.py (path:/tmp/pip-jKXLKm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:44:00 INFO - Running setup.py (path:/tmp/pip-fY4D1s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:44:00 INFO - Running setup.py (path:/tmp/pip-6rzzRh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:44:00 INFO - Running setup.py (path:/tmp/pip-fi55Ji-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:44:00 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:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:44:01 INFO - Running setup.py (path:/tmp/pip-xEwT70-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:44: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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:44:01 INFO - Running setup.py (path:/tmp/pip-gwpxp8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:44: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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:44:01 INFO - Running setup.py (path:/tmp/pip-YhB90y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:44: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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:44:01 INFO - Running setup.py (path:/tmp/pip-Xc9CyF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:44: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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:44:01 INFO - Running setup.py (path:/tmp/pip-bmXA3G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:44: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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:44:01 INFO - Running setup.py (path:/tmp/pip-LzZxXH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:44:01 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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:44:01 INFO - Running setup.py (path:/tmp/pip-9uHlrp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:44:01 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:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:44:01 INFO - Running setup.py (path:/tmp/pip-R2LRDd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:44:01 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:44:01 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:44:01 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:44:01 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:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:44:01 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:44:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:44:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:03 INFO - Downloading blessings-1.5.1.tar.gz 05:44: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:44:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:44:03 INFO - Installing collected packages: blessings 05:44:03 INFO - Running setup.py install for blessings 05:44:03 INFO - Successfully installed blessings 05:44:03 INFO - Cleaning up... 05:44:03 INFO - Return code: 0 05:44:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:44:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:44:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:44:03 INFO - Reading from file tmpfile_stdout 05:44:03 INFO - Current package versions: 05:44:03 INFO - argparse == 1.2.1 05:44:03 INFO - blessings == 1.5.1 05:44:03 INFO - blobuploader == 1.2.4 05:44:03 INFO - docopt == 0.6.1 05:44:03 INFO - manifestparser == 1.1 05:44:03 INFO - mozInstall == 1.12 05:44:03 INFO - mozcrash == 0.16 05:44:03 INFO - mozdebug == 0.1 05:44:03 INFO - mozdevice == 0.47 05:44:03 INFO - mozfile == 1.2 05:44:03 INFO - mozhttpd == 0.7 05:44:03 INFO - mozinfo == 0.9 05:44:03 INFO - mozleak == 0.1 05:44:03 INFO - mozlog == 3.1 05:44:03 INFO - moznetwork == 0.27 05:44:03 INFO - mozprocess == 0.22 05:44:03 INFO - mozprofile == 0.28 05:44:03 INFO - mozrunner == 6.11 05:44:03 INFO - mozscreenshot == 0.1 05:44:03 INFO - mozsystemmonitor == 0.0 05:44:03 INFO - moztest == 0.7 05:44:03 INFO - mozversion == 1.4 05:44:03 INFO - psutil == 3.1.1 05:44:03 INFO - requests == 1.2.3 05:44:03 INFO - wsgiref == 0.1.2 05:44:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:03 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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:44:03 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:44:03 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:44:03 INFO - Using env: {'DISPLAY': ':0', 05:44:03 INFO - 'HOME': '/home/cltbld', 05:44:03 INFO - 'LANG': 'en_US.UTF-8', 05:44:03 INFO - 'LANGUAGE': 'en_US:en', 05:44:03 INFO - 'LOGNAME': 'cltbld', 05:44:03 INFO - 'MAIL': '/var/mail/cltbld', 05:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:03 INFO - 'MOZ_NO_REMOTE': '1', 05:44:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:03 INFO - 'NO_EM_RESTART': '1', 05:44:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:44:03 INFO - 'PWD': '/builds/slave/test-pgo', 05:44:03 INFO - 'SHELL': '/bin/bash', 05:44:03 INFO - 'SHLVL': '1', 05:44:03 INFO - 'TERM': 'linux', 05:44:03 INFO - 'TMOUT': '86400', 05:44:03 INFO - 'USER': 'cltbld', 05:44:03 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:03 INFO - '_': '/tools/buildbot/bin/python'} 05:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44: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:44: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:44: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:44: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:44: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:44: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:44: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:44: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:44: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:44: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:44: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:44:04 INFO - Cleaning up... 05:44:04 INFO - Return code: 0 05:44:04 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:44: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:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:44: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:44: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:44:04 INFO - Using env: {'DISPLAY': ':0', 05:44:04 INFO - 'HOME': '/home/cltbld', 05:44:04 INFO - 'LANG': 'en_US.UTF-8', 05:44:04 INFO - 'LANGUAGE': 'en_US:en', 05:44:04 INFO - 'LOGNAME': 'cltbld', 05:44:04 INFO - 'MAIL': '/var/mail/cltbld', 05:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:04 INFO - 'MOZ_NO_REMOTE': '1', 05:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:04 INFO - 'NO_EM_RESTART': '1', 05:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:44:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:44:04 INFO - 'SHELL': '/bin/bash', 05:44:04 INFO - 'SHLVL': '1', 05:44:04 INFO - 'TERM': 'linux', 05:44:04 INFO - 'TMOUT': '86400', 05:44:04 INFO - 'USER': 'cltbld', 05:44:04 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:04 INFO - '_': '/tools/buildbot/bin/python'} 05:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:04 INFO - Downloading/unpacking jsonschema 05:44: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:44: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:44: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:44: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:44: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:44: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:44:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:44: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:44:06 INFO - Downloading/unpacking functools32 (from jsonschema) 05:44: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:44: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:44: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:44: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:44: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:44: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:44:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:44: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:44:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:44:06 INFO - warning: no files found matching '*.txt' 05:44:06 INFO - no previously-included directories found matching 'build' 05:44:06 INFO - no previously-included directories found matching 'dist' 05:44:06 INFO - no previously-included directories found matching '.git*' 05:44:06 INFO - Installing collected packages: jsonschema, functools32 05:44:06 INFO - Running setup.py install for functools32 05:44:06 INFO - warning: no files found matching '*.txt' 05:44:06 INFO - no previously-included directories found matching 'build' 05:44:06 INFO - no previously-included directories found matching 'dist' 05:44:06 INFO - no previously-included directories found matching '.git*' 05:44:06 INFO - Successfully installed jsonschema functools32 05:44:06 INFO - Cleaning up... 05:44:06 INFO - Return code: 0 05:44:06 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:44: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:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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 0x7fcf6add6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x114f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x121f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, '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': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', '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:44: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:44: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:44:06 INFO - Using env: {'DISPLAY': ':0', 05:44:06 INFO - 'HOME': '/home/cltbld', 05:44:06 INFO - 'LANG': 'en_US.UTF-8', 05:44:06 INFO - 'LANGUAGE': 'en_US:en', 05:44:06 INFO - 'LOGNAME': 'cltbld', 05:44:06 INFO - 'MAIL': '/var/mail/cltbld', 05:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:06 INFO - 'MOZ_NO_REMOTE': '1', 05:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:06 INFO - 'NO_EM_RESTART': '1', 05:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:44:06 INFO - 'PWD': '/builds/slave/test-pgo', 05:44:06 INFO - 'SHELL': '/bin/bash', 05:44:06 INFO - 'SHLVL': '1', 05:44:06 INFO - 'TERM': 'linux', 05:44:06 INFO - 'TMOUT': '86400', 05:44:06 INFO - 'USER': 'cltbld', 05:44:06 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:06 INFO - '_': '/tools/buildbot/bin/python'} 05:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:06 INFO - Downloading/unpacking flake8 05:44: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:44: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:44: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:44: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:44: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:44: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:44:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:44: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:44:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:44: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:44: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:44: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:44: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:44: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:44: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:44:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:44: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:44:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:44: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:44: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:44: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:44: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:44: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:44: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:44:08 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:44:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:44: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:44: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:44: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:44: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:44: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:44: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:44:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:44:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:44:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:44:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:44:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:44:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:44:08 INFO - no previously-included directories found matching 'docs/_build' 05:44:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:44:09 INFO - Running setup.py install for pep8 05:44:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:44:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:44:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:44:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:44:09 INFO - no previously-included directories found matching 'docs/_build' 05:44:09 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:44:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:44:09 INFO - Cleaning up... 05:44:09 INFO - Return code: 0 05:44:09 INFO - Running post-action listener: _resource_record_post_action 05:44:09 INFO - Running post-action listener: _start_resource_monitoring 05:44:09 INFO - Starting resource monitoring. 05:44:09 INFO - ##### 05:44:09 INFO - ##### Running install step. 05:44:09 INFO - ##### 05:44:09 INFO - Running pre-action listener: _resource_record_pre_action 05:44:09 INFO - Running main action method: install 05:44:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:44:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:44:09 INFO - Reading from file tmpfile_stdout 05:44:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:44:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:44:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:44:09 INFO - Reading from file tmpfile_stdout 05:44:09 INFO - Output received: 05:44:09 INFO - Usage: mozinstall [options] installer 05:44:09 INFO - Options: 05:44:09 INFO - -h, --help show this help message and exit 05:44:09 INFO - -d DEST, --destination=DEST 05:44:09 INFO - Directory to install application into. [default: 05:44:09 INFO - "/builds/slave/test-pgo"] 05:44:09 INFO - --app=APP Application being installed. [default: firefox] 05:44:09 INFO - mkdir: /builds/slave/test-pgo/build/application 05:44: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:44: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:44:25 INFO - Reading from file tmpfile_stdout 05:44:25 INFO - Output received: 05:44:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:44:25 INFO - Running post-action listener: _resource_record_post_action 05:44:25 INFO - ##### 05:44:25 INFO - ##### Running run-tests step. 05:44:25 INFO - ##### 05:44:25 INFO - Running pre-action listener: _resource_record_pre_action 05:44:25 INFO - Running main action method: run_tests 05:44:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:44:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:44:25 INFO - Python 2.7.3 05:44:25 INFO - Return code: 0 05:44:25 INFO - grabbing minidump binary from tooltool 05:44: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:44: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 0x12b0840>, '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 0x121d6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x121db60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:44: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:44: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:44:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:44:25 INFO - Return code: 0 05:44:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:44:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:44:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:44:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:44:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:44:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:44:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:44:26 INFO - Return code: 0 05:44: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/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:44: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/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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:44:26 INFO - Using env: {'DISPLAY': ':0', 05:44:26 INFO - 'HOME': '/home/cltbld', 05:44:26 INFO - 'LANG': 'en_US.UTF-8', 05:44:26 INFO - 'LANGUAGE': 'en_US:en', 05:44:26 INFO - 'LOGNAME': 'cltbld', 05:44:26 INFO - 'MAIL': '/var/mail/cltbld', 05:44:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:44:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:26 INFO - 'MOZ_NO_REMOTE': '1', 05:44:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:44:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:26 INFO - 'NO_EM_RESTART': '1', 05:44: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:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:44:26 INFO - 'PWD': '/builds/slave/test-pgo', 05:44:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:44:26 INFO - 'SHELL': '/bin/bash', 05:44:26 INFO - 'SHLVL': '1', 05:44:26 INFO - 'TERM': 'linux', 05:44:26 INFO - 'TMOUT': '86400', 05:44:26 INFO - 'USER': 'cltbld', 05:44:26 INFO - 'XDG_SESSION_COOKIE': 'c402a24d74c70935940d60dd000001de-1451821794.159457-414925296', 05:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:26 INFO - '_': '/tools/buildbot/bin/python'} 05:44: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/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:44:26 INFO - mozversion application_buildid: 20160103030302 05:44:26 INFO - mozversion application_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 05:44:26 INFO - mozversion application_display_name: Nightly 05:44:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:44:26 INFO - mozversion application_name: Firefox 05:44:26 INFO - mozversion application_remotingname: firefox 05:44:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:44:26 INFO - mozversion application_vendor: Mozilla 05:44:26 INFO - mozversion application_version: 46.0a1 05:44:26 INFO - mozversion platform_buildid: 20160103030302 05:44:26 INFO - mozversion platform_changeset: d7a0ad85d9fb77916f9d77d62697b852f3dc63e6 05:44:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:44:26 INFO - mozversion platform_version: 46.0a1 05:44:26 INFO - using testdate: 1451828666 05:44:26 INFO - actual date: 1451828666 05:44:26 INFO - starting webserver on 'localhost:55597' 05:44:26 INFO - SUITE-START | Running 3 tests 05:44:26 INFO - TEST-START | dromaeo_css 05:44:26 INFO - operating with platform_type : linux_ 05:44:26 INFO - Initialising browser for dromaeo_css test... 05:44:26 INFO - TEST-INFO | started process 26135 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYHOJX8/profile http://localhost:55597/getInfo.html) 05:44:30 INFO - PROCESS | 26135 | __metrics Screen width/height:1600/1200 05:44:30 INFO - PROCESS | 26135 | colorDepth:24 05:44:30 INFO - PROCESS | 26135 | Browser inner width/height: 1024/697 05:44:30 INFO - PROCESS | 26135 | __metrics 05:44:30 INFO - PROCESS | 26135 | 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:44:30 INFO - PROCESS | 26135 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:44:32 INFO - TEST-INFO | 26135: exit 0 05:44:32 INFO - Browser initialized. 05:44:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:44:32 INFO - TEST-INFO | started process 26229 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYHOJX8/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:43 INFO - PROCESS | 26229 | RSS: Main: 149905408 05:44:43 INFO - PROCESS | 26229 | 05:45:35 INFO - PROCESS | 26229 | 1451828735337 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:47:44 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55597/tests/dromaeo/cssquery-ext.html) 05:47:45 INFO - PROCESS | 26229 | RSS: Main: 255877120 05:47:45 INFO - PROCESS | 26229 | 05:50:47 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-ext.html (next: http://localhost:55597/tests/dromaeo/cssquery-jquery.html) 05:50:48 INFO - PROCESS | 26229 | RSS: Main: 239198208 05:50:48 INFO - PROCESS | 26229 | 05:53:50 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55597/tests/dromaeo/cssquery-mootools.html) 05:53:50 INFO - PROCESS | 26229 | RSS: Main: 225693696 05:53:50 INFO - PROCESS | 26229 | 05:56:52 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55597/tests/dromaeo/cssquery-prototype.html) 05:56:53 INFO - PROCESS | 26229 | RSS: Main: 214994944 05:56:53 INFO - PROCESS | 26229 | 05:59:55 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55597/tests/dromaeo/cssquery-yui.html) 05:59:55 INFO - PROCESS | 26229 | RSS: Main: 223113216 05:59:55 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | Cycle 1(1): loaded http://localhost:55597/tests/dromaeo/cssquery-yui.html (next: http://localhost:55597/tests/dromaeo/cssquery-dojo.html) 06:02:58 INFO - PROCESS | 26229 | RSS: Main: 260427776 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | __start_tp_report 06:02:58 INFO - PROCESS | 26229 | _x_x_mozilla_page_load 06:02:58 INFO - PROCESS | 26229 | _x_x_mozilla_page_load_details 06:02:58 INFO - PROCESS | 26229 | |i|pagename|runs| 06:02:58 INFO - PROCESS | 26229 | |0;dojo.html;3039.650440199377;3088.197068762121;3135.9372812543734;3136.2159460134967;3157.778955473117;5016.598672106232;5134.871628209296;5191.19536471847;5210.478952104792;5246.554042906353;5049.419316778572;5134.743262836852;5139.922901156486;5142.7428628568605;5148.639595228336;4589.288660257649;4707.741074240916;4749.786259618317;4818.59041981432;4867.780949857247;4681.555252251031;4782.641301902354;4823.5176482351835;4827.300041493982;4934.333864928225;4751.85744427666;4831;4837.298591704183;4847.563719265283;4864.805407783684;4742.857714268577;4749.715017098951;4749.881252968669;4767.475577686451;4778.5699287064335;4646.930296045562;4721.26820342844;4752.07334569759;4770;4774.880627984328;5116.488351164891;5130.871728206787;5136.049830781318;5141.997310524449;5154.969006198738;5611.859703507404;5614.9438505615235;5616.52259557938;5624.184493248497;5654.717264136818;5517.2551705520045;5536.280283563111;5627.071533197018;5631.690257035862;5665.801696940587;5199.1161502544655;5199.350081239845;5203.791848326063;5205.245239440298;5234.42421333653;5672.914906276409;5677.971610142005;5680.062789639664;5683.232763576947;5687.857803554944;5336.466353364633;5439.673619582838;5441.809536666197;5443.537299329522;5445.646033007842;5550.250716153349;5550.944490555043;5655.802046928337;5656.8868622627315;5658.207850900877;5530.529904958124;5533.584981126432;5537.473939975695;5544.029794785896;5544.889102218014;5499;5542.057850165401;5552.222688823568;5553.888922221533;5586.804461843816;4296.462942132233;4311.9784401077795;4318.913621727548;4358.128374325084;4359.738415695068;3861.942070868939;3863.478430411915;3868.168343806095;3890.0663840678035;3913.099987003005;5219.2953951216095;5220.556252718561;5222.086134926372;5223.242629818655;5269.762860671202;5499.285092937893;5554;5575.888482230333;5625.46558076982;5629.943700563023;5210.244514545446;5276.234945932818;5276.577873770089;5276.788928442896;5313.1764576490705;4766.189747742892;4792.544708252711;4802.951970480251;4823.1800593898415;4865.9026819464125;5733.3406658234835;5814;5869.207656966255;5876.059830427197;5877.735501902424;5543.916841247304;5561.944380556142;5589.888202236014;5674.460926212003;5700.458456446631;5827.067669173081;5830.300363956183;5833.125031245348;5843.152742852178;5845.649261044351;5424.159255315432;5520.447955204608;5521.365043020104;5530.308711411074;5532.0595498766115;5580.35825880029;5586.804461843816;5614.578906581941;5685.857853553694;5730.312362516524;5443.292371991616;5484.890302194013;5488.067028605067;5490.5882058844945;5490.6431081979545;5317.680939143664;5486.451354864482;5489.094299440707;5491.972540137273;5492.780288788404;5319.840404787863;5323.281357016753;5363.651362661414;5448.128299472224;5489.39616642177;5425.701586412785;5438.537724293479;5445;5446.863828404163;5452.372977107683;5252.606054545848;5355.143177091647;5355.946440535545;5357.089294819812;5394.730263486731 06:02:58 INFO - PROCESS | 26229 | |1;ext.html;522.3366324767547;530.7876849260296;536.305484397705;547.824696097249;552.3040968379842;27706.86146569267;28392.14823555294;28430.85784571077;35682.464763028576;36183.63816361836;38854.41718374227;39243.803780981056;39339.21321573574;39366;39408.80295598525;8450.830983380329;8564.657413703444;8600.22597966183;8605.225529702326;8615.483071015758;7431.851362972764;7568.962155189216;9980.75048123799;10190.59237630494;10197.388156710622;9788.21694264457;9793.412395256308;9837.65568205113;9865.408075515492;9871.60513579456;7419.36935360495;7566.356859666935;9848.803023939518;9946.403215807039;9951;9933;10171.593136274614;10182.898171018269;10185.439800810953;10188;35667;35677;35850.46224306637;36116.63883361159;36119.27761444783;2949.690282520326;2980.1804503761423;2982.209714425679;2994.921828141867;2996.415698938708;27706.722932770615;27770.583441248396;28239;30007;30282.84858575693;3750.6999440044733;3767.811609419518;3804.7444343366624;3918.608139186087;3993.4409182714444;417752;422672;425901;426052;426636;57177.428225718046;58409.41590584125;58441.70779146077;58465.415345846;58466.41533584695;55888;56773.43226567764;64814.67592662101;65734.67132664306;65826.34173658298;14197.148171109766;14243.145411275356;14262.57212283633;14470.710585788436;14486.420543178156;11143.052840508648;11310.717232069264;11310.94344528272;11324.32054076758;11329.206955513035;19056.52358691044;19095.331663391713;19116.2353505861;19120.139593718315;19315.8068419314;7590.582517961565;7867.6459559319965;7948.483348582324;7979;8000.759977200693;853.7310747114639;925.5141050948306;1033.1270076785104;1046.2989796836139;1057.724991502215;26667.599986000227;26818.118577703714;27068.458630827274;27245.591316130274;27267.363958162176;925.1349987761464;1008.6973907827622;1022.9130523905402;1023.7798873242289;1045.4720366215013;13224.404901779442;13462.192268463923;13527.458901643824;13533.796993045113;13556.864431355758;374650;379162;379351.412971739;381490;382966;50652;51180.48819511832;51233;51240;51290;1313.6715821044738;1316.2365827819842;1332.726791007841;1360.775472047121;1364.7134101838526;25717.3570660735;25949.481010380063;26074.86962565175;26409.339766505993;26540.469190616466;34399.65600344064;34776.30447391089;40925.38611920883;41414.58585414107;41437;404952.7028378307;406589;407803;410931;416613;30605.846970765004;30934.226644333172;30985.535216972214;31012.53481197829;31031.84484077565;39010.80494597509;39465.60534394619;40025.59974400335;40098.59901401065;40145;34779.130521737156;34938;35018.649813501535;35080.82459587788;35235.64764352324;33735.662643374235;33960.490592641625;34074;34535.136621584665;34740.826295868355;33386.833065834515;33490.162745930575;34085.65914340825;34111.61218714666;34326;29586.852065739535;31139.37721245608;31754.36491270208;31895.68104318927;32194.356112877143;24017.759822401553;24254.87872560626;24274.63588046216;24587;24634.50730985406 06:02:58 INFO - PROCESS | 26229 | |2;jquery.html;354.8828886467466;359.31371081234846;359.62239648369206;362.93830048891687;368.03024031676523;14019.5093171739;14144.656257655532;16016.439424620126;16122.596935076614;16699.24853381598;15920.84079159208;16908.070056146906;17281.30874765011;17290.740638890427;17411.47765567035;5318.946810531903;5432.83701488954;5435.320584926884;5436.809711660093;5440.510354068133;3944.8224829882724;4076.408920706496;5401.729913504329;5441.129419292914;5724.7996320128805;5827.213326200951;5864.23764910561;5874.5006674432725;5885.764569417218;5894.351621321651;5668.801591944282;5811.15738217956;5811.738471768781;5812.680302583356;5825.912611310834;5093.312402825608;5097.388313402378;5110.872228194325;5111.054454925814;5113.565346945514;20054.699179512318;20569;20739.79260207409;20786;20882;2036.7124620785203;2051.579426217622;2054.578811343671;2062.9484262893397;2117.04732870209;12405.317707526083;12523.373831308489;12926.482940682361;12965.87034129656;13200;1741.4688520001393;1746.2229307957964;1753.3512600337956;1759.190772244769;1812.3747307179135;56295;59215;59528.40471595272;59800;60087.399126009055;9449.76375590602;9790.21678265737;9962.701118966443;9974.40153590787;9994.200463962865;12869.099163058496;12897.355132243538;12897.871021289855;12928.418221180069;12938.482460701467;7568.356689681329;7572.734954276574;7610.238976102345;7650.42621803367;7739.071311442663;7394.4454165936695;7395.149557800922;7452.627368631547;7464.589447580435;7474.7757567273065;5898.970505147447;5951.434613711777;5960.523158147338;5964.791232306935;6005.939940600538;1655.983440165607;1707.5731067233191;1708.6668099720434;1719.4669652407795;1770.097250402304;414.7905307819592;416.1593580966431;433.36944745395374;908.2416182487697;1016.7102375822855;10032.448215348226;10096.14182794456;10400.47997600117;10434.739131521772;10569.735756605993;392.6407337286351;430.39529461107196;442.5729171349663;447.17942575374553;456.1561111942879;5682.829515114553;5803.361630220672;5839.854003649943;5842.38654941225;5864.413558644187;562149;620449;624655;633640;640824;80594;81389.59305203435;82514;83383;83657.58171209105;1137.249415385858;1138.231693606812;1145.032447581792;1170.4791367841228;1171.1216587559331;15237;15496.84503154954;15525.378984840932;15560.455384061503;15814.209289535247;19340;19875;29194.124176274745;34751.13122171966;34835.651643484256;45316.77341613403;47886.76056619694;48667.5133248663;50259.49740502548;51130.48869511257;9333.113354231336;9583.137517623447;9709.126178643955;9888.85166722514;9983.400995940267;17335.82664173342;18009.819901800813;18171.273149073888;18267.725984110515;18608.534786630444;10501.579936802747;14025.929870350583;14242.28788560547;14372.568822935329;15389.538313850602;14434.350454229374;14442.205678687353;14726;14937.701245975237;15047.774283385977;11941.2835229883;12232.143749937775;14935;15180.772288415461;15289.77065344043;8697.26073283778;9063.320250981335;9147.817043658957;9157.450552966844;9281.118293762082;9913.256505762241;10004.799904002024;12523.56167534132;13487.865121349054;13519.391627376988 06:02:58 INFO - PROCESS | 26229 | |3;mootools.html;1237.0289322881529;1461.656510719981;1462.5027490653179;1608.8149862765779;1780.3768680961657;6961.373476387124;7440.96279518603;7573.242675732437;7574.545527268361;7584.203658615848;7463.36561392281;7643.732469363574;7657.846843063149;7670.539767613933;7677.0787505499275;1937.7674679038535;1965.7641083070052;2144.5603651251454;2171.359448962552;2189.124350259901;2245.741739699931;2252.3355610094977;2255.120503003822;2255.9436014099615;2256.8194544436487;2141.1114387529155;2188.5841690078914;2195.6157672407267;2196.5716685246384;2198.4064302638276;2171.0772921508296;2177.0638625391075;2192.6053310404122;2212.5574885022897;2215.4682876109778;2104.368689393176;2163.9783602163934;2171.1966572368165;2187.278198194592;2188.004457971619;7572.280633339824;7640.617969101579;7646.464747467679;7661.118971318314;7668.616569171519;4590;4654.790534425959;4668.85993420198;4670;4676.392069031038;2943.3230357018006;2990.820550766961;2997.1308320587004;3041.2244877556304;3069.8311592862383;965.6764983730459;973.956171972263;992.7815880506278;1009.0111690543308;1020.6172685243034;10135.645252416203;10220.795584088277;10224.897751022543;10231.079202871702;10236.181105511467;4069.5320038195396;4112.670986321087;4125.834966601363;4161.334186530141;4168.416421700964;8845.4250473719;8854.380193386402;8885.866711999326;8886.777830554287;8897.466152030898;4563.726176429424;4578.244589642738;4593.356930029799;4598.3792188054185;4606.930896036562;4145.606167414091;4152.792360381969;4161.167766446722;4164.770937598461;4165.416841642173;3239.7894136881027;3285.7042866141683;3483.965160348364;3525.0834782956103;3542.1498840278146;1110.6612483192703;1129.8870112988636;1133.484264659578;1134.2400591603646;1141.971450713722;1205.8311836342918;1282.8973682105595;1299.1295831792722;1303.5372442782866;1324.2187109605186;6837;6883.690233939485;6884.51808373419;6906.689198986058;6921.169459664872;1068.6206396729206;1078.4014871746256;1089.4280502736126;1094.0919037199105;1113.549012649878;5445.700486473203;5477.7261136943;5490.423505531941;5503.229547863302;5504.091824848935;8607.956960215284;9131.360804743737;9239.815203695889;9249;9254.907450925539;9138.17756401927;9163.450192988443;9273.397229180082;9436.575354109082;9459.527023648927;6963.61700106499;6966.303369662993;7180.605066721276;7181.820454488784;7187.89218161728;6867.965660171867;6924.4114250287275;6936.132983377078;6939.201991770809;6947.965260173667;7763.262490063435;7973.401994850526;8017.919820801717;8132.349412046904;8150.470219435718;8186.918130818854;8243.464174828618;8385.622646980779;8390.790230244293;8396.076431592397;6523.11937888398;6528.412442879973;6531.510136739668;6582.407583317333;6591.406773390418;6539.215294164731;6681.164854393201;6874.518783685094;7035.788926332219;7042;6653.567518111307;6745.628990405575;6778.966105169443;6827.590344579341;6860.108185935996;6635.966820165868;6671.766488172891;6672.899906501503;6771.898421523582;6772.322767723188;6658.667066646552;6673;6683.264840867598;6684.498662600227;6693.297203793726;6302.369763023844;6314.210723659543;6339.3343698912795;6386.201724784402;6397.104405383343;6224.439800417984;6249.1563638908165;6265.498760099273;6268.623882567061;6272.968635156795 06:02:58 INFO - PROCESS | 26229 | |4;prototype.html;338.09053645693086;340.77508844162855;346.1778276593091;346.507958698648;354.317937969409;5776.306843178819;5800.47795698387;5821.8835623287505;5823.1265310203435;5830.679312637803;5507.862303442416;5646.153077038446;5667.064934285838;5772.220750198722;5821.7380217890195;4505.594496495315;4509.616682581985;4520.254158063932;4522.4346956630425;4528.818847246106;4288.742675439484;4511.052678937427;4513.548645135493;4517.638588912879;4520.29935360018;4329.350597410382;4356.803943822536;4435.84474543391;4464.6428285737065;4495.3931219285305;4450.154470650566;4466.1067786442845;4475.619572336355;4479.686421950449;4486.125205584913;3967.6825853931905;3971.801409929492;3971.8808435746973;3974.1455587048636;3974.6224108709625;5425.593080518976;5517.724113794335;5520.089185284423;5529.142982837667;5531.1426728857095;5211.557017653504;5211.895762084775;5213.426523082458;5217.139172036609;5223.921641175385;6110.816675499697;6122.908156377658;6126.29547602027;6131.6934153292605;6135.9693201534155;4383.364412160251;4390.121975604892;4392.297232442794;4392.780360981938;4395.120975804852;6262.718177682015;6382.234131904202;6392.328805475451;6403.48772098231;6410.134631824691;4486.910261794746;4495.662825288116;4499.1451624191295;4501.459824821042;4503;4558.202314594999;4563.269876819693;4565.155446242456;4565.840195593205;4566.908661826745;5195.142801437796;5199.896002079937;5202.53177214045;5203.14148165556;5207.140821764367;6078.240219972504;6168.691565421801;6178.320384757673;6180.196574445294;6183.81448556544;1597.672477142183;1617.5713435939485;1617.8058632964119;1618.3769248839283;1618.8543031127256;1118.183725880112;1119.1885882734953;1122.8989390954853;1137.5790957345835;1145.971350716222;5879.617824841372;5888.381719919433;5890.764369425175;5892.351841297454;5892.558058145656;6340.175777148941;6342.858285508652;6347;6352.0789485525265;6357.554971151975;5677.943220567741;5733.627314224562;5840.328362238312;5863.70681465925;5866.941330586639;3371.4268574342423;3395.219099607104;3395.830208489566;3399.6600339965808;3400.6599340065795;6537.084808126866;6538.542302038907;6541.2150541934325;6545.967270163619;6546.541742078104;6346.11154438379;6438.484921206292;6444.806655800334;6508.8047358579315;6517.185351831021;6637.966810165918;6638.369354911372;6649.567778094505;6652.600843949379;6654.101696271038;6362.904556431565;6368.458681011981;6373.808785736435;6374.330695276929;6374.58565193261;6155.199824022849;6301.558890877594;6308.968455157695;6313.40022697843;6315.55791094619;6610.305917878553;6642.43539299165;6647.966760166169;6648.301928297459;6661.267260601233;4507.729536227836;4553.203189441768;4555.430571178603;4555.703879247971;4559.566841150086;6221.160143380766;6340.9048864268;6345.397187267202;6347.111404403295;6359;6172.444479996821;6184.041473571604;6184.134221208944;6186.288576813544;6190.7214175363115;6114.785982490592;6115;6115.266168059861;6116.4097334692415;6120;5953.970230148821;6050.394960503914;6059.121427392915;6062.969685151546;6067.848303792441;6108.755649774137;6168.814935551941;6169.5064394849205;6172.598781079216;6175.938240617536;6051.183090282755;6053;6055.969720151547;6057.545684073801;6064.211652485149 06:02:58 INFO - PROCESS | 26229 | |5;yui.html;689.6655122265702;800.6619333805671;814.771863878114;818.2349503214494;820.0937963550277;13389.330533473336;13535.390907409166;13560.660983475418;13563.59309220725;13633.113847599912;12711.300878451682;12871.163374380658;13095.279759613217;13210.867891321084;13220.669483262924;684.9726010959556;689.4036658290568;827.4745536584268;840.5503055865113;841.5161282262693;827.1769589258696;829.5603330234954;839.1985653700732;839.412411312081;839.9622017009249;873.5021038008338;878.0253918150847;878.4816957994782;879.5997820991465;880.1110878013188;719.4855678190092;725.6009194942814;725.6988349834838;859.9269062129727;873.4104479476359;638.651934695592;643.3855667837252;643.5752403413771;644.3298969072159;644.7968889799698;12434.067444941666;12449.688757781038;12531.373431328397;12539.31033793141;12551.560695375709;11692.590759323468;11790.705232369173;11930.135880130352;12274.38628068602;12307.507699692002;10237.334573252789;10359.430231337274;10373.585056597727;10587.205959552988;10631.681049568526;3405.3019131078067;3452.4648679454726;3452.9309413811834;3476.4263896457055;3540.3627347077518;42923.785381073205;43231;43444.348334775;43479.130417391476;43502;8761.518116503526;8836.955815220883;8934.28525717941;9023;9713.56288966984;17563.824361756473;17712.822871771376;17723.113844307733;17738.645227095385;17755.378561750276;7346.265373462611;7496.6373332936655;7610.961945190349;7677.078750549969;7738.380929525624;4194.706370554093;4209.074003719177;4233.724807887477;4295.098029413858;4302.548232435612;2868.784841136923;2923.736863682279;2933.0467598030727;2944.9411011779644;2949.88200471983;568.3095039526994;569.6952130610186;576.6770608459276;582.2634367525084;589.2251689028914;607.8966874107617;611.4110173282858;621.4965877639037;670.966451677424;676.7868121541798;8421;8769.166929141722;8840.778980525538;8868.201861832466;9035.186833185046;537.9731013449311;636.8695565371503;664.2427632498955;681.6932380428827;691.2820003476295;3884.514435695538;3915.47141135943;4073.124278280184;4478.731276123378;4552.908941821145;56025.15962260569;56448.43551564432;56499.43500565024;64515.35484645094;64758.676206618664;53988.730056349465;55195.44804551903;55447.44552554423;55459.72270138704;56114.1582876249;48727;49393.75303123461;49511;51030;51161.744191278805;8491.74524764258;8598.312135029308;8648.956755216184;8656.740297791077;8893.555322233733;33013.339733205685;33040.33919321552;33683.15792105119;34142.658573413944;34288.48567271443;38725.61274387333;39438.605613943495;41833;42571;42659.573404266805;11428;11489.138314626269;11531.8270225945;11943.880561194625;12111.697207569528;12022.819657705317;12100.878991209975;12179.147459677737;12686.492540298284;13122.934385328012;11601.5359385627;11889.227200231957;11903.82144267854;11947.462364193458;12298.139130260795;10517.211209159497;10618.73453163677;10864.511097000812;10878.728031799268;11195.44022798873;7580.164890961911;10982.560697572007;11592;11940.46267917963;11979.580714675294;9494.287928405536;9524.333296669167;9570.234381249344;9683.66107186245;9797.608095676092;8882.200601945857;9384.624615015597;9420.387674801115;9437.386569873212;9522.809543809224 06:02:58 INFO - PROCESS | 26229 | __end_tp_report 06:02:58 INFO - PROCESS | 26229 | __start_cc_report 06:02:58 INFO - PROCESS | 26229 | _x_x_mozilla_cycle_collect,895 06:02:58 INFO - PROCESS | 26229 | __end_cc_report 06:02:58 INFO - PROCESS | 26229 | __startTimestamp1451829778856__endTimestamp 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | ------- Summary: start ------- 06:02:58 INFO - PROCESS | 26229 | Number of tests: 6 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#0] dojo.html Cycles:165 Average:5189.97 Median:5357.09 stddev:571.10 (10.7%) stddev-sans-first:547.37 06:02:58 INFO - PROCESS | 26229 | Values: 3039.7 3088.2 3135.9 3136.2 3157.8 5016.6 5134.9 5191.2 5210.5 5246.6 5049.4 5134.7 5139.9 5142.7 5148.6 4589.3 4707.7 4749.8 4818.6 4867.8 4681.6 4782.6 4823.5 4827.3 4934.3 4751.9 4831.0 4837.3 4847.6 4864.8 4742.9 4749.7 4749.9 4767.5 4778.6 4646.9 4721.3 4752.1 4770.0 4774.9 5116.5 5130.9 5136.0 5142.0 5155.0 5611.9 5614.9 5616.5 5624.2 5654.7 5517.3 5536.3 5627.1 5631.7 5665.8 5199.1 5199.4 5203.8 5205.2 5234.4 5672.9 5678.0 5680.1 5683.2 5687.9 5336.5 5439.7 5441.8 5443.5 5445.6 5550.3 5550.9 5655.8 5656.9 5658.2 5530.5 5533.6 5537.5 5544.0 5544.9 5499.0 5542.1 5552.2 5553.9 5586.8 4296.5 4312.0 4318.9 4358.1 4359.7 3861.9 3863.5 3868.2 3890.1 3913.1 5219.3 5220.6 5222.1 5223.2 5269.8 5499.3 5554.0 5575.9 5625.5 5629.9 5210.2 5276.2 5276.6 5276.8 5313.2 4766.2 4792.5 4803.0 4823.2 4865.9 5733.3 5814.0 5869.2 5876.1 5877.7 5543.9 5561.9 5589.9 5674.5 5700.5 5827.1 5830.3 5833.1 5843.2 5845.6 5424.2 5520.4 5521.4 5530.3 5532.1 5580.4 5586.8 5614.6 5685.9 5730.3 5443.3 5484.9 5488.1 5490.6 5490.6 5317.7 5486.5 5489.1 5492.0 5492.8 5319.8 5323.3 5363.7 5448.1 5489.4 5425.7 5438.5 5445.0 5446.9 5452.4 5252.6 5355.1 5355.9 5357.1 5394.7 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#1] ext.html Cycles:180 Average:54542.84 Median:35049.74 stddev:107100.38 (305.6%) stddev-sans-first:107324.03 06:02:58 INFO - PROCESS | 26229 | Values: 522.3 530.8 536.3 547.8 552.3 27706.9 28392.1 28430.9 35682.5 36183.6 38854.4 39243.8 39339.2 39366.0 39408.8 8450.8 8564.7 8600.2 8605.2 8615.5 7431.9 7569.0 9980.8 10190.6 10197.4 9788.2 9793.4 9837.7 9865.4 9871.6 7419.4 7566.4 9848.8 9946.4 9951.0 9933.0 10171.6 10182.9 10185.4 10188.0 35667.0 35677.0 35850.5 36116.6 36119.3 2949.7 2980.2 2982.2 2994.9 2996.4 27706.7 27770.6 28239.0 30007.0 30282.8 3750.7 3767.8 3804.7 3918.6 3993.4 417752.0 422672.0 425901.0 426052.0 426636.0 57177.4 58409.4 58441.7 58465.4 58466.4 55888.0 56773.4 64814.7 65734.7 65826.3 14197.1 14243.1 14262.6 14470.7 14486.4 11143.1 11310.7 11310.9 11324.3 11329.2 19056.5 19095.3 19116.2 19120.1 19315.8 7590.6 7867.6 7948.5 7979.0 8000.8 853.7 925.5 1033.1 1046.3 1057.7 26667.6 26818.1 27068.5 27245.6 27267.4 925.1 1008.7 1022.9 1023.8 1045.5 13224.4 13462.2 13527.5 13533.8 13556.9 374650.0 379162.0 379351.4 381490.0 382966.0 50652.0 51180.5 51233.0 51240.0 51290.0 1313.7 1316.2 1332.7 1360.8 1364.7 25717.4 25949.5 26074.9 26409.3 26540.5 34399.7 34776.3 40925.4 41414.6 41437.0 404952.7 406589.0 407803.0 410931.0 416613.0 30605.8 30934.2 30985.5 31012.5 31031.8 39010.8 39465.6 40025.6 40098.6 40145.0 34779.1 34938.0 35018.6 35080.8 35235.6 33735.7 33960.5 34074.0 34535.1 34740.8 33386.8 33490.2 34085.7 34111.6 34326.0 29586.9 31139.4 31754.4 31895.7 32194.4 24017.8 24254.9 24274.6 24587.0 24634.5 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#2] jquery.html Cycles:180 Average:30593.20 Median:2168.73 stddev:100796.53 (4647.7%) stddev-sans-first:101053.71 06:02:58 INFO - PROCESS | 26229 | Values: 354.9 359.3 359.6 362.9 368.0 14019.5 14144.7 16016.4 16122.6 16699.2 15920.8 16908.1 17281.3 17290.7 17411.5 5318.9 5432.8 5435.3 5436.8 5440.5 3944.8 4076.4 5401.7 5441.1 5724.8 5827.2 5864.2 5874.5 5885.8 5894.4 5668.8 5811.2 5811.7 5812.7 5825.9 5093.3 5097.4 5110.9 5111.1 5113.6 20054.7 20569.0 20739.8 20786.0 20882.0 2036.7 2051.6 2054.6 2062.9 2117.0 12405.3 12523.4 12926.5 12965.9 13200.0 1741.5 1746.2 1753.4 1759.2 1812.4 56295.0 59215.0 59528.4 59800.0 60087.4 9449.8 9790.2 9962.7 9974.4 9994.2 12869.1 12897.4 12897.9 12928.4 12938.5 7568.4 7572.7 7610.2 7650.4 7739.1 7394.4 7395.1 7452.6 7464.6 7474.8 5899.0 5951.4 5960.5 5964.8 6005.9 1656.0 1707.6 1708.7 1719.5 1770.1 414.8 416.2 433.4 908.2 1016.7 10032.4 10096.1 10400.5 10434.7 10569.7 392.6 430.4 442.6 447.2 456.2 5682.8 5803.4 5839.9 5842.4 5864.4 562149.0 620449.0 624655.0 633640.0 640824.0 80594.0 81389.6 82514.0 83383.0 83657.6 1137.2 1138.2 1145.0 1170.5 1171.1 15237.0 15496.8 15525.4 15560.5 15814.2 19340.0 19875.0 29194.1 34751.1 34835.7 45316.8 47886.8 48667.5 50259.5 51130.5 9333.1 9583.1 9709.1 9888.9 9983.4 17335.8 18009.8 18171.3 18267.7 18608.5 10501.6 14025.9 14242.3 14372.6 15389.5 14434.4 14442.2 14726.0 14937.7 15047.8 11941.3 12232.1 14935.0 15180.8 15289.8 8697.3 9063.3 9147.8 9157.5 9281.1 9913.3 10004.8 12523.6 13487.9 13519.4 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#3] mootools.html Cycles:180 Average:5273.78 Median:6270.80 stddev:2721.62 (43.4%) stddev-sans-first:2712.34 06:02:58 INFO - PROCESS | 26229 | Values: 1237.0 1461.7 1462.5 1608.8 1780.4 6961.4 7441.0 7573.2 7574.5 7584.2 7463.4 7643.7 7657.8 7670.5 7677.1 1937.8 1965.8 2144.6 2171.4 2189.1 2245.7 2252.3 2255.1 2255.9 2256.8 2141.1 2188.6 2195.6 2196.6 2198.4 2171.1 2177.1 2192.6 2212.6 2215.5 2104.4 2164.0 2171.2 2187.3 2188.0 7572.3 7640.6 7646.5 7661.1 7668.6 4590.0 4654.8 4668.9 4670.0 4676.4 2943.3 2990.8 2997.1 3041.2 3069.8 965.7 974.0 992.8 1009.0 1020.6 10135.6 10220.8 10224.9 10231.1 10236.2 4069.5 4112.7 4125.8 4161.3 4168.4 8845.4 8854.4 8885.9 8886.8 8897.5 4563.7 4578.2 4593.4 4598.4 4606.9 4145.6 4152.8 4161.2 4164.8 4165.4 3239.8 3285.7 3484.0 3525.1 3542.1 1110.7 1129.9 1133.5 1134.2 1142.0 1205.8 1282.9 1299.1 1303.5 1324.2 6837.0 6883.7 6884.5 6906.7 6921.2 1068.6 1078.4 1089.4 1094.1 1113.5 5445.7 5477.7 5490.4 5503.2 5504.1 8608.0 9131.4 9239.8 9249.0 9254.9 9138.2 9163.5 9273.4 9436.6 9459.5 6963.6 6966.3 7180.6 7181.8 7187.9 6868.0 6924.4 6936.1 6939.2 6948.0 7763.3 7973.4 8017.9 8132.3 8150.5 8186.9 8243.5 8385.6 8390.8 8396.1 6523.1 6528.4 6531.5 6582.4 6591.4 6539.2 6681.2 6874.5 7035.8 7042.0 6653.6 6745.6 6779.0 6827.6 6860.1 6636.0 6671.8 6672.9 6771.9 6772.3 6658.7 6673.0 6683.3 6684.5 6693.3 6302.4 6314.2 6339.3 6386.2 6397.1 6224.4 6249.2 6265.5 6268.6 6273.0 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#4] prototype.html Cycles:180 Average:5173.24 Median:5826.90 stddev:1526.44 (26.2%) stddev-sans-first:1486.96 06:02:58 INFO - PROCESS | 26229 | Values: 338.1 340.8 346.2 346.5 354.3 5776.3 5800.5 5821.9 5823.1 5830.7 5507.9 5646.2 5667.1 5772.2 5821.7 4505.6 4509.6 4520.3 4522.4 4528.8 4288.7 4511.1 4513.5 4517.6 4520.3 4329.4 4356.8 4435.8 4464.6 4495.4 4450.2 4466.1 4475.6 4479.7 4486.1 3967.7 3971.8 3971.9 3974.1 3974.6 5425.6 5517.7 5520.1 5529.1 5531.1 5211.6 5211.9 5213.4 5217.1 5223.9 6110.8 6122.9 6126.3 6131.7 6136.0 4383.4 4390.1 4392.3 4392.8 4395.1 6262.7 6382.2 6392.3 6403.5 6410.1 4486.9 4495.7 4499.1 4501.5 4503.0 4558.2 4563.3 4565.2 4565.8 4566.9 5195.1 5199.9 5202.5 5203.1 5207.1 6078.2 6168.7 6178.3 6180.2 6183.8 1597.7 1617.6 1617.8 1618.4 1618.9 1118.2 1119.2 1122.9 1137.6 1146.0 5879.6 5888.4 5890.8 5892.4 5892.6 6340.2 6342.9 6347.0 6352.1 6357.6 5677.9 5733.6 5840.3 5863.7 5866.9 3371.4 3395.2 3395.8 3399.7 3400.7 6537.1 6538.5 6541.2 6546.0 6546.5 6346.1 6438.5 6444.8 6508.8 6517.2 6638.0 6638.4 6649.6 6652.6 6654.1 6362.9 6368.5 6373.8 6374.3 6374.6 6155.2 6301.6 6309.0 6313.4 6315.6 6610.3 6642.4 6648.0 6648.3 6661.3 4507.7 4553.2 4555.4 4555.7 4559.6 6221.2 6340.9 6345.4 6347.1 6359.0 6172.4 6184.0 6184.1 6186.3 6190.7 6114.8 6115.0 6115.3 6116.4 6120.0 5954.0 6050.4 6059.1 6063.0 6067.8 6108.8 6168.8 6169.5 6172.6 6175.9 6051.2 6053.0 6056.0 6057.5 6064.2 06:02:58 INFO - PROCESS | 26229 | 06:02:58 INFO - PROCESS | 26229 | [#5] yui.html Cycles:180 Average:13725.55 Median:49452.38 stddev:16131.04 (32.6%) stddev-sans-first:16146.59 06:02:58 INFO - PROCESS | 26229 | Values: 689.7 800.7 814.8 818.2 820.1 13389.3 13535.4 13560.7 13563.6 13633.1 12711.3 12871.2 13095.3 13210.9 13220.7 685.0 689.4 827.5 840.6 841.5 827.2 829.6 839.2 839.4 840.0 873.5 878.0 878.5 879.6 880.1 719.5 725.6 725.7 859.9 873.4 638.7 643.4 643.6 644.3 644.8 12434.1 12449.7 12531.4 12539.3 12551.6 11692.6 11790.7 11930.1 12274.4 12307.5 10237.3 10359.4 10373.6 10587.2 10631.7 3405.3 3452.5 3452.9 3476.4 3540.4 42923.8 43231.0 43444.3 43479.1 43502.0 8761.5 8837.0 8934.3 9023.0 9713.6 17563.8 17712.8 17723.1 17738.6 17755.4 7346.3 7496.6 7611.0 7677.1 7738.4 4194.7 4209.1 4233.7 4295.1 4302.5 2868.8 2923.7 2933.0 2944.9 2949.9 568.3 569.7 576.7 582.3 589.2 607.9 611.4 621.5 671.0 676.8 8421.0 8769.2 8840.8 8868.2 9035.2 538.0 636.9 664.2 681.7 691.3 3884.5 3915.5 4073.1 4478.7 4552.9 56025.2 56448.4 56499.4 64515.4 64758.7 53988.7 55195.4 55447.4 55459.7 56114.2 48727.0 49393.8 49511.0 51030.0 51161.7 8491.7 8598.3 8649.0 8656.7 8893.6 33013.3 33040.3 33683.2 34142.7 34288.5 38725.6 39438.6 41833.0 42571.0 42659.6 11428.0 11489.1 11531.8 11943.9 12111.7 12022.8 12100.9 12179.1 12686.5 13122.9 11601.5 11889.2 11903.8 11947.5 12298.1 10517.2 10618.7 10864.5 10878.7 11195.4 7580.2 10982.6 11592.0 11940.5 11979.6 9494.3 9524.3 9570.2 9683.7 9797.6 8882.2 9384.6 9420.4 9437.4 9522.8 06:02:58 INFO - PROCESS | 26229 | -------- Summary: end -------- 06:02:58 INFO - PROCESS | 26229 | 06:02:59 INFO - TEST-INFO | 26229: exit 0 06:02:59 INFO - TEST-OK | dromaeo_css | took 1112928ms 06:02:59 INFO - TEST-START | kraken 06:02:59 INFO - operating with platform_type : linux_ 06:02:59 INFO - Initialising browser for kraken test... 06:02:59 INFO - TEST-INFO | started process 26418 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqDDzV3/profile http://localhost:55597/getInfo.html) 06:03:03 INFO - PROCESS | 26418 | __metrics Screen width/height:1600/1200 06:03:03 INFO - PROCESS | 26418 | colorDepth:24 06:03:03 INFO - PROCESS | 26418 | Browser inner width/height: 1024/697 06:03:03 INFO - PROCESS | 26418 | __metrics 06:03:03 INFO - PROCESS | 26418 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 06:03:03 INFO - PROCESS | 26418 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:03:05 INFO - TEST-INFO | 26418: exit 0 06:03:05 INFO - Browser initialized. 06:03:05 INFO - Running cycle 1/1 for kraken test... 06:03:05 INFO - TEST-INFO | started process 26504 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqDDzV3/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:03:16 INFO - PROCESS | 26504 | RSS: Main: 149659648 06:03:16 INFO - PROCESS | 26504 | 06:03:19 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55597/tests/kraken/driver.html?testName=audio-beat-detection) 06:03:19 INFO - PROCESS | 26504 | RSS: Main: 184049664 06:03:19 INFO - PROCESS | 26504 | 06:03:22 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55597/tests/kraken/driver.html?testName=audio-dft) 06:03:23 INFO - PROCESS | 26504 | RSS: Main: 175161344 06:03:23 INFO - PROCESS | 26504 | 06:03:26 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55597/tests/kraken/driver.html?testName=audio-fft) 06:03:26 INFO - PROCESS | 26504 | RSS: Main: 158683136 06:03:26 INFO - PROCESS | 26504 | 06:03:28 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55597/tests/kraken/driver.html?testName=audio-oscillator) 06:03:29 INFO - PROCESS | 26504 | RSS: Main: 161443840 06:03:29 INFO - PROCESS | 26504 | 06:03:31 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55597/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:03:31 INFO - PROCESS | 26504 | RSS: Main: 163323904 06:03:31 INFO - PROCESS | 26504 | 06:03:34 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55597/tests/kraken/driver.html?testName=imaging-darkroom) 06:03:35 INFO - PROCESS | 26504 | RSS: Main: 164225024 06:03:35 INFO - PROCESS | 26504 | 06:03:38 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55597/tests/kraken/driver.html?testName=imaging-desaturate) 06:03:39 INFO - PROCESS | 26504 | RSS: Main: 165007360 06:03:39 INFO - PROCESS | 26504 | 06:03:42 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55597/tests/kraken/driver.html?testName=json-parse-financial) 06:03:42 INFO - PROCESS | 26504 | RSS: Main: 165273600 06:03:42 INFO - PROCESS | 26504 | 06:03:44 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55597/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:03:44 INFO - PROCESS | 26504 | RSS: Main: 174141440 06:03:44 INFO - PROCESS | 26504 | 06:03:46 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:03:47 INFO - PROCESS | 26504 | RSS: Main: 172298240 06:03:47 INFO - PROCESS | 26504 | 06:03:49 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:03:49 INFO - PROCESS | 26504 | RSS: Main: 183160832 06:03:49 INFO - PROCESS | 26504 | 06:03:52 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:03:52 INFO - PROCESS | 26504 | RSS: Main: 189231104 06:03:52 INFO - PROCESS | 26504 | 06:03:55 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:03:55 INFO - PROCESS | 26504 | RSS: Main: 185991168 06:03:55 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | Cycle 1(1): loaded http://localhost:55597/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55597/tests/kraken/driver.html?testName=ai-astar) 06:03:57 INFO - PROCESS | 26504 | RSS: Main: 244854784 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | __start_tp_report 06:03:57 INFO - PROCESS | 26504 | _x_x_mozilla_page_load 06:03:57 INFO - PROCESS | 26504 | _x_x_mozilla_page_load_details 06:03:57 INFO - PROCESS | 26504 | |i|pagename|runs| 06:03:57 INFO - PROCESS | 26504 | |0;ai-astar;110;110;104;112;111;111;101;111;110;111 06:03:57 INFO - PROCESS | 26504 | |1;audio-beat-detection;149;124;148;160;126;149;154;126;171;124 06:03:57 INFO - PROCESS | 26504 | |2;audio-dft;162;160;160;159;161;161;158;161;161;159 06:03:57 INFO - PROCESS | 26504 | |3;audio-fft;73;72;83;93;75;72;73;118;91;72 06:03:57 INFO - PROCESS | 26504 | |4;audio-oscillator;96;97;141;94;96;96;139;95;97;139 06:03:57 INFO - PROCESS | 26504 | |5;imaging-gaussian-blur;109;110;110;109;110;109;109;110;110;109 06:03:57 INFO - PROCESS | 26504 | |6;imaging-darkroom;146;147;146;146;145;146;146;146;146;146 06:03:57 INFO - PROCESS | 26504 | |7;imaging-desaturate;95;95;96;96;96;96;96;97;95;95 06:03:57 INFO - PROCESS | 26504 | |8;json-parse-financial;60;58;56;57;57;64;57;66;55;55 06:03:57 INFO - PROCESS | 26504 | |9;json-stringify-tinderbox;68;68;67;68;67;68;68;67;68;68 06:03:57 INFO - PROCESS | 26504 | |10;stanford-crypto-aes;84;85;85;85;93;85;87;85;85;84 06:03:57 INFO - PROCESS | 26504 | |11;stanford-crypto-ccm;135;118;114;119;123;120;122;123;122;118 06:03:57 INFO - PROCESS | 26504 | |12;stanford-crypto-pbkdf2;143;149;144;142;153;154;154;152;150;141 06:03:57 INFO - PROCESS | 26504 | |13;stanford-crypto-sha256-iterative;67;68;68;68;67;67;69;69;66;69 06:03:57 INFO - PROCESS | 26504 | __end_tp_report 06:03:57 INFO - PROCESS | 26504 | __start_cc_report 06:03:57 INFO - PROCESS | 26504 | _x_x_mozilla_cycle_collect,920 06:03:57 INFO - PROCESS | 26504 | __end_cc_report 06:03:57 INFO - PROCESS | 26504 | __startTimestamp1451829837786__endTimestamp 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | ------- Summary: start ------- 06:03:57 INFO - PROCESS | 26504 | Number of tests: 14 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#0] ai-astar Cycles:10 Average:109.10 Median:111.00 stddev:3.60 (3.2%) stddev-sans-first:3.81 06:03:57 INFO - PROCESS | 26504 | Values: 110.0 110.0 104.0 112.0 111.0 111.0 101.0 111.0 110.0 111.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:149.00 stddev:16.97 (11.4%) stddev-sans-first:17.86 06:03:57 INFO - PROCESS | 26504 | Values: 149.0 124.0 148.0 160.0 126.0 149.0 154.0 126.0 171.0 124.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:1.23 (0.8%) stddev-sans-first:1.12 06:03:57 INFO - PROCESS | 26504 | Values: 162.0 160.0 160.0 159.0 161.0 161.0 158.0 161.0 161.0 159.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#3] audio-fft Cycles:10 Average:82.20 Median:74.00 stddev:14.94 (20.2%) stddev-sans-first:15.47 06:03:57 INFO - PROCESS | 26504 | Values: 73.0 72.0 83.0 93.0 75.0 72.0 73.0 118.0 91.0 72.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:96.00 stddev:21.19 (22.1%) stddev-sans-first:21.94 06:03:57 INFO - PROCESS | 26504 | Values: 96.0 97.0 141.0 94.0 96.0 96.0 139.0 95.0 97.0 139.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:03:57 INFO - PROCESS | 26504 | Values: 109.0 110.0 110.0 109.0 110.0 109.0 109.0 110.0 110.0 109.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 06:03:57 INFO - PROCESS | 26504 | Values: 146.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 06:03:57 INFO - PROCESS | 26504 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 96.0 97.0 95.0 95.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:57.50 stddev:3.75 (6.5%) stddev-sans-first:3.94 06:03:57 INFO - PROCESS | 26504 | Values: 60.0 58.0 56.0 57.0 57.0 64.0 57.0 66.0 55.0 55.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 06:03:57 INFO - PROCESS | 26504 | Values: 68.0 68.0 67.0 68.0 67.0 68.0 68.0 67.0 68.0 68.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.00 stddev:2.66 (3.1%) stddev-sans-first:2.74 06:03:57 INFO - PROCESS | 26504 | Values: 84.0 85.0 85.0 85.0 93.0 85.0 87.0 85.0 85.0 84.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#11] stanford-crypto-ccm Cycles:10 Average:121.40 Median:122.00 stddev:5.54 (4.5%) stddev-sans-first:2.98 06:03:57 INFO - PROCESS | 26504 | Values: 135.0 118.0 114.0 119.0 123.0 120.0 122.0 123.0 122.0 118.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.20 Median:151.00 stddev:5.20 (3.4%) stddev-sans-first:5.17 06:03:57 INFO - PROCESS | 26504 | Values: 143.0 149.0 144.0 142.0 153.0 154.0 154.0 152.0 150.0 141.0 06:03:57 INFO - PROCESS | 26504 | 06:03:57 INFO - PROCESS | 26504 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 06:03:57 INFO - PROCESS | 26504 | Values: 67.0 68.0 68.0 68.0 67.0 67.0 69.0 69.0 66.0 69.0 06:03:57 INFO - PROCESS | 26504 | -------- Summary: end -------- 06:03:57 INFO - PROCESS | 26504 | 06:03:58 INFO - TEST-INFO | 26504: exit 0 06:03:58 INFO - TEST-OK | kraken | took 59152ms 06:03:58 INFO - TEST-START | v8_7 06:03:58 INFO - operating with platform_type : linux_ 06:03:58 INFO - Initialising browser for v8_7 test... 06:03:58 INFO - TEST-INFO | started process 26752 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgfZxXX/profile http://localhost:55597/getInfo.html) 06:04:03 INFO - PROCESS | 26752 | __metrics Screen width/height:1600/1200 06:04:03 INFO - PROCESS | 26752 | colorDepth:24 06:04:03 INFO - PROCESS | 26752 | Browser inner width/height: 1024/697 06:04:03 INFO - PROCESS | 26752 | __metrics 06:04:03 INFO - PROCESS | 26752 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 06:04:03 INFO - PROCESS | 26752 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:04:04 INFO - TEST-INFO | 26752: exit 0 06:04:04 INFO - Browser initialized. 06:04:04 INFO - Running cycle 1/1 for v8_7 test... 06:04:04 INFO - TEST-INFO | started process 26835 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgfZxXX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:15 INFO - PROCESS | 26835 | RSS: Main: 150282240 06:04:15 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | Cycle 1(1): loaded http://localhost:55597/tests/v8_7/run.html (next: http://localhost:55597/tests/v8_7/run.html) 06:04:37 INFO - PROCESS | 26835 | RSS: Main: 571105280 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | __start_tp_report 06:04:37 INFO - PROCESS | 26835 | _x_x_mozilla_page_load 06:04:37 INFO - PROCESS | 26835 | _x_x_mozilla_page_load_details 06:04:37 INFO - PROCESS | 26835 | |i|pagename|runs| 06:04:37 INFO - PROCESS | 26835 | |0;Richards;166.58337497917708 06:04:37 INFO - PROCESS | 26835 | |1;DeltaBlue;190.80328181644725 06:04:37 INFO - PROCESS | 26835 | |2;Encrypt;280.19052956010086 06:04:37 INFO - PROCESS | 26835 | |3;Decrypt;5164.948453608247 06:04:37 INFO - PROCESS | 26835 | |4;RayTrace;1138.9521640091116 06:04:37 INFO - PROCESS | 26835 | |5;Earley;789.8894154818325 06:04:37 INFO - PROCESS | 26835 | |6;Boyer;12910.25641025641 06:04:37 INFO - PROCESS | 26835 | |7;RegExp;25820.51282051282 06:04:37 INFO - PROCESS | 26835 | |8;Splay;625 06:04:37 INFO - PROCESS | 26835 | |9;NavierStokes;4826.923076923077 06:04:37 INFO - PROCESS | 26835 | __end_tp_report 06:04:37 INFO - PROCESS | 26835 | __startTimestamp1451829877448__endTimestamp 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | ------- Summary: start ------- 06:04:37 INFO - PROCESS | 26835 | Number of tests: 10 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#0] Richards Cycles:1 Average:166.58 Median:166.58 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 166.6 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#1] DeltaBlue Cycles:1 Average:190.80 Median:190.80 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 190.8 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#2] Encrypt Cycles:1 Average:280.19 Median:280.19 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 280.2 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 5164.9 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#4] RayTrace Cycles:1 Average:1138.95 Median:1138.95 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 1139.0 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#5] Earley Cycles:1 Average:789.89 Median:789.89 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 789.9 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#6] Boyer Cycles:1 Average:12910.26 Median:12910.26 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 12910.3 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#7] RegExp Cycles:1 Average:25820.51 Median:25820.51 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 25820.5 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#8] Splay Cycles:1 Average:625.00 Median:625.00 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 625.0 06:04:37 INFO - PROCESS | 26835 | 06:04:37 INFO - PROCESS | 26835 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 06:04:37 INFO - PROCESS | 26835 | Values: 4826.9 06:04:37 INFO - PROCESS | 26835 | -------- Summary: end -------- 06:04:37 INFO - PROCESS | 26835 | 06:04:38 INFO - TEST-INFO | 26835: exit 0 06:04:38 INFO - TEST-OK | v8_7 | took 39551ms 06:04:38 INFO - SUITE-END | took 1211s 06:04:38 INFO - Completed test suite (00:20:11) 06:04:38 INFO - Working with test: dromaeo_css 06:04:38 INFO - Generating results file: dromaeo_css 06:04:38 INFO - Working with test: kraken 06:04:38 INFO - Generating results file: kraken 06:04:38 INFO - javascript benchmark 06:04:38 INFO - Working with test: v8_7 06:04:38 INFO - Generating results file: v8_7 06:04:38 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:04:38 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 06:04:38 INFO - process_Request line: dromaeo_css 6826.13 graph.html#tests=[[72,1,35]] 06:04:38 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:04:38 INFO - process_Request line: kraken 1504.20 graph.html#tests=[[232,1,35]] 06:04:38 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:04:38 INFO - process_Request line: v8_7 20776.26 graph.html#tests=[[230,1,35]] 06:04:38 INFO - RETURN: dromaeo_css: 6826.13 06:04:38 INFO - RETURN: kraken: 1504.20 06:04:38 INFO - RETURN: v8_7: 20776.26 06:04:38 INFO - javascript benchmark 06:04:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3039.650440199377, 3088.197068762121, 3135.9372812543734, 3136.2159460134967, 3157.778955473117, 5016.598672106232, 5134.871628209296, 5191.19536471847, 5210.478952104792, 5246.554042906353, 5049.419316778572, 5134.743262836852, 5139.922901156486, 5142.7428628568605, 5148.639595228336, 4589.288660257649, 4707.741074240916, 4749.786259618317, 4818.59041981432, 4867.780949857247, 4681.555252251031, 4782.641301902354, 4823.5176482351835, 4827.300041493982, 4934.333864928225, 4751.85744427666, 4831.0, 4837.298591704183, 4847.563719265283, 4864.805407783684, 4742.857714268577, 4749.715017098951, 4749.881252968669, 4767.475577686451, 4778.5699287064335, 4646.930296045562, 4721.26820342844, 4752.07334569759, 4770.0, 4774.880627984328, 5116.488351164891, 5130.871728206787, 5136.049830781318, 5141.997310524449, 5154.969006198738, 5611.859703507404, 5614.9438505615235, 5616.52259557938, 5624.184493248497, 5654.717264136818, 5517.2551705520045, 5536.280283563111, 5627.071533197018, 5631.690257035862, 5665.801696940587, 5199.1161502544655, 5199.350081239845, 5203.791848326063, 5205.245239440298, 5234.42421333653, 5672.914906276409, 5677.971610142005, 5680.062789639664, 5683.232763576947, 5687.857803554944, 5336.466353364633, 5439.673619582838, 5441.809536666197, 5443.537299329522, 5445.646033007842, 5550.250716153349, 5550.944490555043, 5655.802046928337, 5656.8868622627315, 5658.207850900877, 5530.529904958124, 5533.584981126432, 5537.473939975695, 5544.029794785896, 5544.889102218014, 5499.0, 5542.057850165401, 5552.222688823568, 5553.888922221533, 5586.804461843816, 4296.462942132233, 4311.9784401077795, 4318.913621727548, 4358.128374325084, 4359.738415695068, 3861.942070868939, 3863.478430411915, 3868.168343806095, 3890.0663840678035, 3913.099987003005, 5219.2953951216095, 5220.556252718561, 5222.086134926372, 5223.242629818655, 5269.762860671202, 5499.285092937893, 5554.0, 5575.888482230333, 5625.46558076982, 5629.943700563023, 5210.244514545446, 5276.234945932818, 5276.577873770089, 5276.788928442896, 5313.1764576490705, 4766.189747742892, 4792.544708252711, 4802.951970480251, 4823.1800593898415, 4865.9026819464125, 5733.3406658234835, 5814.0, 5869.207656966255, 5876.059830427197, 5877.735501902424, 5543.916841247304, 5561.944380556142, 5589.888202236014, 5674.460926212003, 5700.458456446631, 5827.067669173081, 5830.300363956183, 5833.125031245348, 5843.152742852178, 5845.649261044351, 5424.159255315432, 5520.447955204608, 5521.365043020104, 5530.308711411074, 5532.0595498766115, 5580.35825880029, 5586.804461843816, 5614.578906581941, 5685.857853553694, 5730.312362516524, 5443.292371991616, 5484.890302194013, 5488.067028605067, 5490.5882058844945, 5490.6431081979545, 5317.680939143664, 5486.451354864482, 5489.094299440707, 5491.972540137273, 5492.780288788404, 5319.840404787863, 5323.281357016753, 5363.651362661414, 5448.128299472224, 5489.39616642177, 5425.701586412785, 5438.537724293479, 5445.0, 5446.863828404163, 5452.372977107683, 5252.606054545848, 5355.143177091647, 5355.946440535545, 5357.089294819812, 5394.730263486731], "unit": "score", "name": "dojo.html", "value": 5153.186126102934}, {"lowerIsBetter": false, "replicates": [522.3366324767547, 530.7876849260296, 536.305484397705, 547.824696097249, 552.3040968379842, 27706.86146569267, 28392.14823555294, 28430.85784571077, 35682.464763028576, 36183.63816361836, 38854.41718374227, 39243.803780981056, 39339.21321573574, 39366.0, 39408.80295598525, 8450.830983380329, 8564.657413703444, 8600.22597966183, 8605.225529702326, 8615.483071015758, 7431.851362972764, 7568.962155189216, 9980.75048123799, 10190.59237630494, 10197.388156710622, 9788.21694264457, 9793.412395256308, 9837.65568205113, 9865.408075515492, 9871.60513579456, 7419.36935360495, 7566.356859666935, 9848.803023939518, 9946.403215807039, 9951.0, 9933.0, 10171.593136274614, 10182.898171018269, 10185.439800810953, 10188.0, 35667.0, 35677.0, 35850.46224306637, 36116.63883361159, 36119.27761444783, 2949.690282520326, 2980.1804503761423, 2982.209714425679, 2994.921828141867, 2996.415698938708, 27706.722932770615, 27770.583441248396, 28239.0, 30007.0, 30282.84858575693, 3750.6999440044733, 3767.811609419518, 3804.7444343366624, 3918.608139186087, 3993.4409182714444, 417752.0, 422672.0, 425901.0, 426052.0, 426636.0, 57177.428225718046, 58409.41590584125, 58441.70779146077, 58465.415345846, 58466.41533584695, 55888.0, 56773.43226567764, 64814.67592662101, 65734.67132664306, 65826.34173658298, 14197.148171109766, 14243.145411275356, 14262.57212283633, 14470.710585788436, 14486.420543178156, 11143.052840508648, 11310.717232069264, 11310.94344528272, 11324.32054076758, 11329.206955513035, 19056.52358691044, 19095.331663391713, 19116.2353505861, 19120.139593718315, 19315.8068419314, 7590.582517961565, 7867.6459559319965, 7948.483348582324, 7979.0, 8000.759977200693, 853.7310747114639, 925.5141050948306, 1033.1270076785104, 1046.2989796836139, 1057.724991502215, 26667.599986000227, 26818.118577703714, 27068.458630827274, 27245.591316130274, 27267.363958162176, 925.1349987761464, 1008.6973907827622, 1022.9130523905402, 1023.7798873242289, 1045.4720366215013, 13224.404901779442, 13462.192268463923, 13527.458901643824, 13533.796993045113, 13556.864431355758, 374650.0, 379162.0, 379351.412971739, 381490.0, 382966.0, 50652.0, 51180.48819511832, 51233.0, 51240.0, 51290.0, 1313.6715821044738, 1316.2365827819842, 1332.726791007841, 1360.775472047121, 1364.7134101838526, 25717.3570660735, 25949.481010380063, 26074.86962565175, 26409.339766505993, 26540.469190616466, 34399.65600344064, 34776.30447391089, 40925.38611920883, 41414.58585414107, 41437.0, 404952.7028378307, 406589.0, 407803.0, 410931.0, 416613.0, 30605.846970765004, 30934.226644333172, 30985.535216972214, 31012.53481197829, 31031.84484077565, 39010.80494597509, 39465.60534394619, 40025.59974400335, 40098.59901401065, 40145.0, 34779.130521737156, 34938.0, 35018.649813501535, 35080.82459587788, 35235.64764352324, 33735.662643374235, 33960.490592641625, 34074.0, 34535.136621584665, 34740.826295868355, 33386.833065834515, 33490.162745930575, 34085.65914340825, 34111.61218714666, 34326.0, 29586.852065739535, 31139.37721245608, 31754.36491270208, 31895.68104318927, 32194.356112877143, 24017.759822401553, 24254.87872560626, 24274.63588046216, 24587.0, 24634.50730985406], "unit": "score", "name": "ext.html", "value": 18327.834644368606}, {"lowerIsBetter": false, "replicates": [354.8828886467466, 359.31371081234846, 359.62239648369206, 362.93830048891687, 368.03024031676523, 14019.5093171739, 14144.656257655532, 16016.439424620126, 16122.596935076614, 16699.24853381598, 15920.84079159208, 16908.070056146906, 17281.30874765011, 17290.740638890427, 17411.47765567035, 5318.946810531903, 5432.83701488954, 5435.320584926884, 5436.809711660093, 5440.510354068133, 3944.8224829882724, 4076.408920706496, 5401.729913504329, 5441.129419292914, 5724.7996320128805, 5827.213326200951, 5864.23764910561, 5874.5006674432725, 5885.764569417218, 5894.351621321651, 5668.801591944282, 5811.15738217956, 5811.738471768781, 5812.680302583356, 5825.912611310834, 5093.312402825608, 5097.388313402378, 5110.872228194325, 5111.054454925814, 5113.565346945514, 20054.699179512318, 20569.0, 20739.79260207409, 20786.0, 20882.0, 2036.7124620785203, 2051.579426217622, 2054.578811343671, 2062.9484262893397, 2117.04732870209, 12405.317707526083, 12523.373831308489, 12926.482940682361, 12965.87034129656, 13200.0, 1741.4688520001393, 1746.2229307957964, 1753.3512600337956, 1759.190772244769, 1812.3747307179135, 56295.0, 59215.0, 59528.40471595272, 59800.0, 60087.399126009055, 9449.76375590602, 9790.21678265737, 9962.701118966443, 9974.40153590787, 9994.200463962865, 12869.099163058496, 12897.355132243538, 12897.871021289855, 12928.418221180069, 12938.482460701467, 7568.356689681329, 7572.734954276574, 7610.238976102345, 7650.42621803367, 7739.071311442663, 7394.4454165936695, 7395.149557800922, 7452.627368631547, 7464.589447580435, 7474.7757567273065, 5898.970505147447, 5951.434613711777, 5960.523158147338, 5964.791232306935, 6005.939940600538, 1655.983440165607, 1707.5731067233191, 1708.6668099720434, 1719.4669652407795, 1770.097250402304, 414.7905307819592, 416.1593580966431, 433.36944745395374, 908.2416182487697, 1016.7102375822855, 10032.448215348226, 10096.14182794456, 10400.47997600117, 10434.739131521772, 10569.735756605993, 392.6407337286351, 430.39529461107196, 442.5729171349663, 447.17942575374553, 456.1561111942879, 5682.829515114553, 5803.361630220672, 5839.854003649943, 5842.38654941225, 5864.413558644187, 562149.0, 620449.0, 624655.0, 633640.0, 640824.0, 80594.0, 81389.59305203435, 82514.0, 83383.0, 83657.58171209105, 1137.249415385858, 1138.231693606812, 1145.032447581792, 1170.4791367841228, 1171.1216587559331, 15237.0, 15496.84503154954, 15525.378984840932, 15560.455384061503, 15814.209289535247, 19340.0, 19875.0, 29194.124176274745, 34751.13122171966, 34835.651643484256, 45316.77341613403, 47886.76056619694, 48667.5133248663, 50259.49740502548, 51130.48869511257, 9333.113354231336, 9583.137517623447, 9709.126178643955, 9888.85166722514, 9983.400995940267, 17335.82664173342, 18009.819901800813, 18171.273149073888, 18267.725984110515, 18608.534786630444, 10501.579936802747, 14025.929870350583, 14242.28788560547, 14372.568822935329, 15389.538313850602, 14434.350454229374, 14442.205678687353, 14726.0, 14937.701245975237, 15047.774283385977, 11941.2835229883, 12232.143749937775, 14935.0, 15180.772288415461, 15289.77065344043, 8697.26073283778, 9063.320250981335, 9147.817043658957, 9157.450552966844, 9281.118293762082, 9913.256505762241, 10004.799904002024, 12523.56167534132, 13487.865121349054, 13519.391627376988], "unit": "score", "name": "jquery.html", "value": 8409.031689305008}, {"lowerIsBetter": false, "replicates": [1237.0289322881529, 1461.656510719981, 1462.5027490653179, 1608.8149862765779, 1780.3768680961657, 6961.373476387124, 7440.96279518603, 7573.242675732437, 7574.545527268361, 7584.203658615848, 7463.36561392281, 7643.732469363574, 7657.846843063149, 7670.539767613933, 7677.0787505499275, 1937.7674679038535, 1965.7641083070052, 2144.5603651251454, 2171.359448962552, 2189.124350259901, 2245.741739699931, 2252.3355610094977, 2255.120503003822, 2255.9436014099615, 2256.8194544436487, 2141.1114387529155, 2188.5841690078914, 2195.6157672407267, 2196.5716685246384, 2198.4064302638276, 2171.0772921508296, 2177.0638625391075, 2192.6053310404122, 2212.5574885022897, 2215.4682876109778, 2104.368689393176, 2163.9783602163934, 2171.1966572368165, 2187.278198194592, 2188.004457971619, 7572.280633339824, 7640.617969101579, 7646.464747467679, 7661.118971318314, 7668.616569171519, 4590.0, 4654.790534425959, 4668.85993420198, 4670.0, 4676.392069031038, 2943.3230357018006, 2990.820550766961, 2997.1308320587004, 3041.2244877556304, 3069.8311592862383, 965.6764983730459, 973.956171972263, 992.7815880506278, 1009.0111690543308, 1020.6172685243034, 10135.645252416203, 10220.795584088277, 10224.897751022543, 10231.079202871702, 10236.181105511467, 4069.5320038195396, 4112.670986321087, 4125.834966601363, 4161.334186530141, 4168.416421700964, 8845.4250473719, 8854.380193386402, 8885.866711999326, 8886.777830554287, 8897.466152030898, 4563.726176429424, 4578.244589642738, 4593.356930029799, 4598.3792188054185, 4606.930896036562, 4145.606167414091, 4152.792360381969, 4161.167766446722, 4164.770937598461, 4165.416841642173, 3239.7894136881027, 3285.7042866141683, 3483.965160348364, 3525.0834782956103, 3542.1498840278146, 1110.6612483192703, 1129.8870112988636, 1133.484264659578, 1134.2400591603646, 1141.971450713722, 1205.8311836342918, 1282.8973682105595, 1299.1295831792722, 1303.5372442782866, 1324.2187109605186, 6837.0, 6883.690233939485, 6884.51808373419, 6906.689198986058, 6921.169459664872, 1068.6206396729206, 1078.4014871746256, 1089.4280502736126, 1094.0919037199105, 1113.549012649878, 5445.700486473203, 5477.7261136943, 5490.423505531941, 5503.229547863302, 5504.091824848935, 8607.956960215284, 9131.360804743737, 9239.815203695889, 9249.0, 9254.907450925539, 9138.17756401927, 9163.450192988443, 9273.397229180082, 9436.575354109082, 9459.527023648927, 6963.61700106499, 6966.303369662993, 7180.605066721276, 7181.820454488784, 7187.89218161728, 6867.965660171867, 6924.4114250287275, 6936.132983377078, 6939.201991770809, 6947.965260173667, 7763.262490063435, 7973.401994850526, 8017.919820801717, 8132.349412046904, 8150.470219435718, 8186.918130818854, 8243.464174828618, 8385.622646980779, 8390.790230244293, 8396.076431592397, 6523.11937888398, 6528.412442879973, 6531.510136739668, 6582.407583317333, 6591.406773390418, 6539.215294164731, 6681.164854393201, 6874.518783685094, 7035.788926332219, 7042.0, 6653.567518111307, 6745.628990405575, 6778.966105169443, 6827.590344579341, 6860.108185935996, 6635.966820165868, 6671.766488172891, 6672.899906501503, 6771.898421523582, 6772.322767723188, 6658.667066646552, 6673.0, 6683.264840867598, 6684.498662600227, 6693.297203793726, 6302.369763023844, 6314.210723659543, 6339.3343698912795, 6386.201724784402, 6397.104405383343, 6224.439800417984, 6249.1563638908165, 6265.498760099273, 6268.623882567061, 6272.968635156795], "unit": "score", "name": "mootools.html", "value": 4348.856727381372}, {"lowerIsBetter": false, "replicates": [338.09053645693086, 340.77508844162855, 346.1778276593091, 346.507958698648, 354.317937969409, 5776.306843178819, 5800.47795698387, 5821.8835623287505, 5823.1265310203435, 5830.679312637803, 5507.862303442416, 5646.153077038446, 5667.064934285838, 5772.220750198722, 5821.7380217890195, 4505.594496495315, 4509.616682581985, 4520.254158063932, 4522.4346956630425, 4528.818847246106, 4288.742675439484, 4511.052678937427, 4513.548645135493, 4517.638588912879, 4520.29935360018, 4329.350597410382, 4356.803943822536, 4435.84474543391, 4464.6428285737065, 4495.3931219285305, 4450.154470650566, 4466.1067786442845, 4475.619572336355, 4479.686421950449, 4486.125205584913, 3967.6825853931905, 3971.801409929492, 3971.8808435746973, 3974.1455587048636, 3974.6224108709625, 5425.593080518976, 5517.724113794335, 5520.089185284423, 5529.142982837667, 5531.1426728857095, 5211.557017653504, 5211.895762084775, 5213.426523082458, 5217.139172036609, 5223.921641175385, 6110.816675499697, 6122.908156377658, 6126.29547602027, 6131.6934153292605, 6135.9693201534155, 4383.364412160251, 4390.121975604892, 4392.297232442794, 4392.780360981938, 4395.120975804852, 6262.718177682015, 6382.234131904202, 6392.328805475451, 6403.48772098231, 6410.134631824691, 4486.910261794746, 4495.662825288116, 4499.1451624191295, 4501.459824821042, 4503.0, 4558.202314594999, 4563.269876819693, 4565.155446242456, 4565.840195593205, 4566.908661826745, 5195.142801437796, 5199.896002079937, 5202.53177214045, 5203.14148165556, 5207.140821764367, 6078.240219972504, 6168.691565421801, 6178.320384757673, 6180.196574445294, 6183.81448556544, 1597.672477142183, 1617.5713435939485, 1617.8058632964119, 1618.3769248839283, 1618.8543031127256, 1118.183725880112, 1119.1885882734953, 1122.8989390954853, 1137.5790957345835, 1145.971350716222, 5879.617824841372, 5888.381719919433, 5890.764369425175, 5892.351841297454, 5892.558058145656, 6340.175777148941, 6342.858285508652, 6347.0, 6352.0789485525265, 6357.554971151975, 5677.943220567741, 5733.627314224562, 5840.328362238312, 5863.70681465925, 5866.941330586639, 3371.4268574342423, 3395.219099607104, 3395.830208489566, 3399.6600339965808, 3400.6599340065795, 6537.084808126866, 6538.542302038907, 6541.2150541934325, 6545.967270163619, 6546.541742078104, 6346.11154438379, 6438.484921206292, 6444.806655800334, 6508.8047358579315, 6517.185351831021, 6637.966810165918, 6638.369354911372, 6649.567778094505, 6652.600843949379, 6654.101696271038, 6362.904556431565, 6368.458681011981, 6373.808785736435, 6374.330695276929, 6374.58565193261, 6155.199824022849, 6301.558890877594, 6308.968455157695, 6313.40022697843, 6315.55791094619, 6610.305917878553, 6642.43539299165, 6647.966760166169, 6648.301928297459, 6661.267260601233, 4507.729536227836, 4553.203189441768, 4555.430571178603, 4555.703879247971, 4559.566841150086, 6221.160143380766, 6340.9048864268, 6345.397187267202, 6347.111404403295, 6359.0, 6172.444479996821, 6184.041473571604, 6184.134221208944, 6186.288576813544, 6190.7214175363115, 6114.785982490592, 6115.0, 6115.266168059861, 6116.4097334692415, 6120.0, 5953.970230148821, 6050.394960503914, 6059.121427392915, 6062.969685151546, 6067.848303792441, 6108.755649774137, 6168.814935551941, 6169.5064394849205, 6172.598781079216, 6175.938240617536, 6051.183090282755, 6053.0, 6055.969720151547, 6057.545684073801, 6064.211652485149], "unit": "score", "name": "prototype.html", "value": 4688.04230010854}, {"lowerIsBetter": false, "replicates": [689.6655122265702, 800.6619333805671, 814.771863878114, 818.2349503214494, 820.0937963550277, 13389.330533473336, 13535.390907409166, 13560.660983475418, 13563.59309220725, 13633.113847599912, 12711.300878451682, 12871.163374380658, 13095.279759613217, 13210.867891321084, 13220.669483262924, 684.9726010959556, 689.4036658290568, 827.4745536584268, 840.5503055865113, 841.5161282262693, 827.1769589258696, 829.5603330234954, 839.1985653700732, 839.412411312081, 839.9622017009249, 873.5021038008338, 878.0253918150847, 878.4816957994782, 879.5997820991465, 880.1110878013188, 719.4855678190092, 725.6009194942814, 725.6988349834838, 859.9269062129727, 873.4104479476359, 638.651934695592, 643.3855667837252, 643.5752403413771, 644.3298969072159, 644.7968889799698, 12434.067444941666, 12449.688757781038, 12531.373431328397, 12539.31033793141, 12551.560695375709, 11692.590759323468, 11790.705232369173, 11930.135880130352, 12274.38628068602, 12307.507699692002, 10237.334573252789, 10359.430231337274, 10373.585056597727, 10587.205959552988, 10631.681049568526, 3405.3019131078067, 3452.4648679454726, 3452.9309413811834, 3476.4263896457055, 3540.3627347077518, 42923.785381073205, 43231.0, 43444.348334775, 43479.130417391476, 43502.0, 8761.518116503526, 8836.955815220883, 8934.28525717941, 9023.0, 9713.56288966984, 17563.824361756473, 17712.822871771376, 17723.113844307733, 17738.645227095385, 17755.378561750276, 7346.265373462611, 7496.6373332936655, 7610.961945190349, 7677.078750549969, 7738.380929525624, 4194.706370554093, 4209.074003719177, 4233.724807887477, 4295.098029413858, 4302.548232435612, 2868.784841136923, 2923.736863682279, 2933.0467598030727, 2944.9411011779644, 2949.88200471983, 568.3095039526994, 569.6952130610186, 576.6770608459276, 582.2634367525084, 589.2251689028914, 607.8966874107617, 611.4110173282858, 621.4965877639037, 670.966451677424, 676.7868121541798, 8421.0, 8769.166929141722, 8840.778980525538, 8868.201861832466, 9035.186833185046, 537.9731013449311, 636.8695565371503, 664.2427632498955, 681.6932380428827, 691.2820003476295, 3884.514435695538, 3915.47141135943, 4073.124278280184, 4478.731276123378, 4552.908941821145, 56025.15962260569, 56448.43551564432, 56499.43500565024, 64515.35484645094, 64758.676206618664, 53988.730056349465, 55195.44804551903, 55447.44552554423, 55459.72270138704, 56114.1582876249, 48727.0, 49393.75303123461, 49511.0, 51030.0, 51161.744191278805, 8491.74524764258, 8598.312135029308, 8648.956755216184, 8656.740297791077, 8893.555322233733, 33013.339733205685, 33040.33919321552, 33683.15792105119, 34142.658573413944, 34288.48567271443, 38725.61274387333, 39438.605613943495, 41833.0, 42571.0, 42659.573404266805, 11428.0, 11489.138314626269, 11531.8270225945, 11943.880561194625, 12111.697207569528, 12022.819657705317, 12100.878991209975, 12179.147459677737, 12686.492540298284, 13122.934385328012, 11601.5359385627, 11889.227200231957, 11903.82144267854, 11947.462364193458, 12298.139130260795, 10517.211209159497, 10618.73453163677, 10864.511097000812, 10878.728031799268, 11195.44022798873, 7580.164890961911, 10982.560697572007, 11592.0, 11940.46267917963, 11979.580714675294, 9494.287928405536, 9524.333296669167, 9570.234381249344, 9683.66107186245, 9797.608095676092, 8882.200601945857, 9384.624615015597, 9420.387674801115, 9437.386569873212, 9522.809543809224], "unit": "score", "name": "yui.html", "value": 6247.514132133546}], "name": "dromaeo_css", "value": 6826.128446095999}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 112.0, 111.0, 111.0, 101.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 109.1}, {"lowerIsBetter": true, "replicates": [149.0, 124.0, 148.0, 160.0, 126.0, 149.0, 154.0, 126.0, 171.0, 124.0], "unit": "score", "name": "audio-beat-detection", "value": 143.1}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 160.0, 159.0, 161.0, 161.0, 158.0, 161.0, 161.0, 159.0], "unit": "score", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 83.0, 93.0, 75.0, 72.0, 73.0, 118.0, 91.0, 72.0], "unit": "score", "name": "audio-fft", "value": 82.2}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 141.0, 94.0, 96.0, 96.0, 139.0, 95.0, 97.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 109.0}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [60.0, 58.0, 56.0, 57.0, 57.0, 64.0, 57.0, 66.0, 55.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 58.5}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 85.0, 85.0, 93.0, 85.0, 87.0, 85.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.8}, {"lowerIsBetter": true, "replicates": [135.0, 118.0, 114.0, 119.0, 123.0, 120.0, 122.0, 123.0, 122.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.4}, {"lowerIsBetter": true, "replicates": [143.0, 149.0, 144.0, 142.0, 153.0, 154.0, 154.0, 152.0, 150.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.2}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 67.0, 69.0, 69.0, 66.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken", "value": 1504.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.58337497917708], "unit": "score", "name": "Richards", "value": 211.91790600000007}, {"lowerIsBetter": false, "replicates": [190.80328181644725], "unit": "score", "name": "DeltaBlue", "value": 346.5244380000001}, {"lowerIsBetter": false, "replicates": [280.19052956010086], "unit": "score", "name": "Encrypt", "value": 949.9999890000003}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [1138.9521640091116], "unit": "score", "name": "RayTrace", "value": 649.7103420000002}, {"lowerIsBetter": false, "replicates": [789.8894154818325], "unit": "score", "name": "Earley", "value": 843.7421580000001}, {"lowerIsBetter": false, "replicates": [12910.25641025641], "unit": "score", "name": "Boyer", "value": 51.62275471698113}, {"lowerIsBetter": false, "replicates": [25820.51282051282], "unit": "score", "name": "RegExp", "value": 35.28144488579943}, {"lowerIsBetter": false, "replicates": [625.0], "unit": "score", "name": "Splay", "value": 130.3856}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 20776.260301909206}]} 06:04:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "20776.26"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1504.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6826.13"}}} 06:04:39 INFO - Return code: 0 06:04:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:04:39 INFO - # TBPL SUCCESS # 06:04:39 INFO - Running post-action listener: _resource_record_post_action 06:04:39 INFO - Running post-run listener: _resource_record_post_run 06:04:40 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555380736; Read time: 32; Write time: 523804 06:04:40 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 235511808; Read time: 0; Write time: 280492 06:04:40 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 319868928; Read time: 32; Write time: 243312 06:04:40 INFO - Running post-run listener: _upload_blobber_files 06:04:40 INFO - Blob upload gear active. 06:04:40 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 06:04: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 06:04: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'] 06:04: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 06:04:40 INFO - (blobuploader) - INFO - Open directory for files ... 06:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:41 INFO - (blobuploader) - INFO - Done attempting. 06:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 06:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:42 INFO - (blobuploader) - INFO - Done attempting. 06:04:42 INFO - (blobuploader) - INFO - Iteration through files over. 06:04:42 INFO - Return code: 0 06:04:42 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 06:04:42 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 06:04:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de0e6ba01bc67cd6d33a4b5249a5a343aebd9c5402f4141eec0f042a2e64a2567f5a880845a8e981d5c18fe9fdac4ccf8a33b42744d05d9b30acabf93e3732b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f7f85d5edea2f0d7b5a4d117b41b88cdcc5ad9ac890c1b36b1ea28bf5816858ffe51ba81d9427ab950c03b97c8d2ef7e66865f6d3703ff5d9a376c074150ff"} 06:04:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 06:04:42 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 06:04:42 INFO - Contents: 06:04:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7de0e6ba01bc67cd6d33a4b5249a5a343aebd9c5402f4141eec0f042a2e64a2567f5a880845a8e981d5c18fe9fdac4ccf8a33b42744d05d9b30acabf93e3732b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f7f85d5edea2f0d7b5a4d117b41b88cdcc5ad9ac890c1b36b1ea28bf5816858ffe51ba81d9427ab950c03b97c8d2ef7e66865f6d3703ff5d9a376c074150ff"} 06:04:42 INFO - Copying logs to upload dir... 06:04:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1276.419528 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-03 06:04:42.141205) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-03 06:04:42.144546) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 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/7de0e6ba01bc67cd6d33a4b5249a5a343aebd9c5402f4141eec0f042a2e64a2567f5a880845a8e981d5c18fe9fdac4ccf8a33b42744d05d9b30acabf93e3732b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f7f85d5edea2f0d7b5a4d117b41b88cdcc5ad9ac890c1b36b1ea28bf5816858ffe51ba81d9427ab950c03b97c8d2ef7e66865f6d3703ff5d9a376c074150ff"} build_url:https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011546 build_url: 'https://queue.taskcluster.net/v1/task/AJU-n2ZlThmC9_TiOF1uHQ/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/7de0e6ba01bc67cd6d33a4b5249a5a343aebd9c5402f4141eec0f042a2e64a2567f5a880845a8e981d5c18fe9fdac4ccf8a33b42744d05d9b30acabf93e3732b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95f7f85d5edea2f0d7b5a4d117b41b88cdcc5ad9ac890c1b36b1ea28bf5816858ffe51ba81d9427ab950c03b97c8d2ef7e66865f6d3703ff5d9a376c074150ff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-03 06:04:42.172051) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 06:04:42.172650) ========= 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=c402a24d74c70935940d60dd000001de-1451821794.159457-414925296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-03 06:04:42.255712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-03 06:04:42.256265) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-03 06:04:42.256776) ========= ========= Total master_lag: 0.24 =========