builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1451696798.39 results: success (0) buildid: 20160101143004 builduid: 674b665b11194570a671950a79c750e4 revision: 8079161123840602784e73dfaa61d1946abfc07a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.392088) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.392598) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.392903) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006283 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.415836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.416144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.429552) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.429945) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004521 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.481009) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.481551) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.482146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.482636) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-01 17:06:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-01-01 17:06:38 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161133 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.657712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.658110) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022071 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.708770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:38.709157) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8079161123840602784e73dfaa61d1946abfc07a --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8079161123840602784e73dfaa61d1946abfc07a --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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-01 17:06:39,068 truncating revision to first 12 chars 2016-01-01 17:06:39,068 Setting DEBUG logging. 2016-01-01 17:06:39,068 attempt 1/10 2016-01-01 17:06:39,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/807916112384?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-01 17:06:39,432 unpacking tar archive at: fx-team-807916112384/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655684 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:39.427838) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:39.428148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:39.438604) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:39.438998) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-01 17:06:39.439500) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-01 17:06:39.439795) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:06:39 INFO - MultiFileLogger online at 20160101 17:06:39 in /builds/slave/test-pgo 17:06:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:06:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:06:39 INFO - {'append_to_log': False, 17:06:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:06:39 INFO - 'blob_upload_branch': 'Fx-Team', 17:06:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:06:39 INFO - 'branch': 'Fx-Team', 17:06:39 INFO - 'buildbot_json_path': 'buildprops.json', 17:06:39 INFO - 'config_files': ('talos/linux_config.py',), 17:06:39 INFO - 'default_actions': ('clobber', 17:06:39 INFO - 'read-buildbot-config', 17:06:39 INFO - 'download-and-extract', 17:06:39 INFO - 'populate-webroot', 17:06:39 INFO - 'create-virtualenv', 17:06:39 INFO - 'install', 17:06:39 INFO - 'run-tests'), 17:06:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:06:39 INFO - 'download_minidump_stackwalk': True, 17:06:39 INFO - 'download_symbols': 'ondemand', 17:06:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:06:39 INFO - 'tooltool.py': '/tools/tooltool.py', 17:06:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:06:39 INFO - '/tools/misc-python/virtualenv.py')}, 17:06:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:06:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:06:39 INFO - 'installer_path': 'installer.exe', 17:06:39 INFO - 'log_level': 'info', 17:06:39 INFO - 'log_name': 'talos', 17:06:39 INFO - 'log_to_console': True, 17:06:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:06:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:06:39 INFO - 'opt_config_files': (), 17:06:39 INFO - 'pip_index': False, 17:06:39 INFO - 'sps_profile': False, 17:06:39 INFO - 'sps_profile_interval': 0, 17:06:39 INFO - 'suite': 'dromaeojs', 17:06:39 INFO - 'system_bits': '32', 17:06:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:06:39 INFO - 'title': 'talos-linux64-ix-007', 17:06:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:06:39 INFO - 'use_talos_json': True, 17:06:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:06:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:06:39 INFO - 'work_dir': 'build'} 17:06:39 INFO - ##### 17:06:39 INFO - ##### Running clobber step. 17:06:39 INFO - ##### 17:06:39 INFO - Running pre-action listener: _resource_record_pre_action 17:06:39 INFO - Running main action method: clobber 17:06:39 INFO - rmtree: /builds/slave/test-pgo/build 17:06:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:06:40 INFO - Running post-action listener: _resource_record_post_action 17:06:40 INFO - ##### 17:06:40 INFO - ##### Running read-buildbot-config step. 17:06:40 INFO - ##### 17:06:40 INFO - Running pre-action listener: _resource_record_pre_action 17:06:40 INFO - Running main action method: read_buildbot_config 17:06:40 INFO - Using buildbot properties: 17:06:40 INFO - { 17:06:40 INFO - "project": "", 17:06:40 INFO - "product": "firefox", 17:06:40 INFO - "script_repo_revision": "production", 17:06:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:06:40 INFO - "repository": "", 17:06:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:06:40 INFO - "buildid": "20160101143004", 17:06:40 INFO - "pgo_build": "True", 17:06:40 INFO - "basedir": "/builds/slave/test-pgo", 17:06:40 INFO - "buildnumber": 396, 17:06:40 INFO - "slavename": "talos-linux64-ix-007", 17:06:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:06:40 INFO - "platform": "ubuntu64_hw", 17:06:40 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:06:40 INFO - "branch": "fx-team", 17:06:40 INFO - "repo_path": "integration/fx-team", 17:06:40 INFO - "revision": "8079161123840602784e73dfaa61d1946abfc07a", 17:06:40 INFO - "stage_platform": "linux64-pgo", 17:06:40 INFO - "builduid": "674b665b11194570a671950a79c750e4", 17:06:40 INFO - "slavebuilddir": "test" 17:06:40 INFO - } 17:06:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 17:06:40 INFO - Running post-action listener: _resource_record_post_action 17:06:40 INFO - ##### 17:06:40 INFO - ##### Running download-and-extract step. 17:06:40 INFO - ##### 17:06:40 INFO - Running pre-action listener: _resource_record_pre_action 17:06:40 INFO - Running main action method: download_and_extract 17:06:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:40 INFO - https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:06:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/test_packages.json 17:06:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/test_packages.json 17:06:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:06:40 INFO - Downloaded 1302 bytes. 17:06:40 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:06:40 INFO - Using the following test package requirements: 17:06:40 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:40 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:06:40 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'jsshell-linux-x86_64.zip'], 17:06:40 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:06:40 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:40 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:06:40 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:06:40 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:06:40 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:40 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:40 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:06:40 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:40 INFO - https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:06:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:42 INFO - Downloaded 23195377 bytes. 17:06: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 17:06:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06: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 17:06:43 INFO - Return code: 0 17:06:43 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 17:06: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')]} 17:06:43 INFO - https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:06:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:44 INFO - Downloaded 23195377 bytes. 17:06:44 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 17:06:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:06:44 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 17:06:45 INFO - Return code: 0 17:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:45 INFO - https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:06:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:46 INFO - Downloaded 11132285 bytes. 17:06:46 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 17:06:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:46 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 17:06:46 INFO - Return code: 0 17:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:06:46 INFO - https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:06:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:06:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:06:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtiUzhATTKiVHDiysc_sCA/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 17:06:48 INFO - Downloaded 54931543 bytes. 17:06:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:06:48 INFO - mkdir: /builds/slave/test-pgo/properties 17:06:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:06:48 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:06:48 INFO - Contents: 17:06:48 INFO - build_url:https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:06:48 INFO - Running post-action listener: _resource_record_post_action 17:06:48 INFO - Running post-action listener: set_extra_try_arguments 17:06:48 INFO - ##### 17:06:48 INFO - ##### Running populate-webroot step. 17:06:48 INFO - ##### 17:06:48 INFO - Running pre-action listener: _resource_record_pre_action 17:06:48 INFO - Running main action method: populate_webroot 17:06:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:48 INFO - '--tppagecycles', 17:06:48 INFO - '7'], 17:06:48 INFO - 'tests': ['tsvgm']}}, 17:06:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:06:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:06:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:06:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'tests': ['damp', 'tps']}, 17:06:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['damp', 'tps']}, 17:06:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:06:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:48 INFO - 'other': {'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'other_l64': {'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'other_nol64': {'tests': ['a11yr', 17:06:48 INFO - 'ts_paint', 17:06:48 INFO - 'tpaint', 17:06:48 INFO - 'sessionrestore', 17:06:48 INFO - 'sessionrestore_no_auto_restore']}, 17:06:48 INFO - 'svgr': {'tests': ['tsvgx', 17:06:48 INFO - 'tsvgr_opacity', 17:06:48 INFO - 'tart', 17:06:48 INFO - 'tscrollx', 17:06:48 INFO - 'cart']}, 17:06:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['tsvgx', 17:06:48 INFO - 'tsvgr_opacity', 17:06:48 INFO - 'tart', 17:06:48 INFO - 'tscrollx', 17:06:48 INFO - 'cart']}, 17:06:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'tests': ['tp5o']}, 17:06:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'talos_options': ['--e10s'], 17:06:48 INFO - 'tests': ['tp5o']}, 17:06:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'talos_options': ['--xperf_path', 17:06:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:48 INFO - 'tests': ['tp5n']}, 17:06:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:06:48 INFO - 'talos_options': ['--e10s', 17:06:48 INFO - '--xperf_path', 17:06:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:48 INFO - 'tests': ['tp5n']}}, 17:06:48 INFO - 'talos.zip': {'path': '', 17:06:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:48 INFO - Running post-action listener: _resource_record_post_action 17:06:48 INFO - ##### 17:06:48 INFO - ##### Running create-virtualenv step. 17:06:48 INFO - ##### 17:06:48 INFO - Running pre-action listener: _resource_record_pre_action 17:06:48 INFO - Running main action method: create_virtualenv 17:06:48 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:06:48 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 17:06:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:06:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:48 INFO - Using real prefix '/usr' 17:06:48 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:06:48 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:50 INFO - Installing pip.................done. 17:06:50 INFO - Return code: 0 17:06:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:06:50 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')]} 17:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:50 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')]} 17:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:50 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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:06:50 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 17:06:50 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 17:06:50 INFO - Using env: {'DISPLAY': ':0', 17:06:50 INFO - 'HOME': '/home/cltbld', 17:06:50 INFO - 'LANG': 'en_US.UTF-8', 17:06:50 INFO - 'LANGUAGE': 'en_US:en', 17:06:50 INFO - 'LOGNAME': 'cltbld', 17:06:50 INFO - 'MAIL': '/var/mail/cltbld', 17:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:50 INFO - 'MOZ_NO_REMOTE': '1', 17:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:50 INFO - 'NO_EM_RESTART': '1', 17:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:06:50 INFO - 'PWD': '/builds/slave/test-pgo', 17:06:50 INFO - 'SHELL': '/bin/bash', 17:06:50 INFO - 'SHLVL': '1', 17:06:50 INFO - 'TERM': 'linux', 17:06:50 INFO - 'TMOUT': '86400', 17:06:50 INFO - 'USER': 'cltbld', 17:06:50 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:50 INFO - '_': '/tools/buildbot/bin/python'} 17:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:51 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 17:06:51 INFO - Cleaning up... 17:06:51 INFO - Return code: 0 17:06:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:06: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')]} 17:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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', '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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:06: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 17:06: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 psutil>=0.7.1 17:06:51 INFO - Using env: {'DISPLAY': ':0', 17:06:51 INFO - 'HOME': '/home/cltbld', 17:06:51 INFO - 'LANG': 'en_US.UTF-8', 17:06:51 INFO - 'LANGUAGE': 'en_US:en', 17:06:51 INFO - 'LOGNAME': 'cltbld', 17:06:51 INFO - 'MAIL': '/var/mail/cltbld', 17:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:51 INFO - 'MOZ_NO_REMOTE': '1', 17:06:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:51 INFO - 'NO_EM_RESTART': '1', 17:06:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:06:51 INFO - 'PWD': '/builds/slave/test-pgo', 17:06:51 INFO - 'SHELL': '/bin/bash', 17:06:51 INFO - 'SHLVL': '1', 17:06:51 INFO - 'TERM': 'linux', 17:06:51 INFO - 'TMOUT': '86400', 17:06:51 INFO - 'USER': 'cltbld', 17:06:51 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:51 INFO - '_': '/tools/buildbot/bin/python'} 17:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:51 INFO - Downloading/unpacking psutil>=0.7.1 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:52 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 17:06:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:06:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:53 INFO - Installing collected packages: psutil 17:06:53 INFO - Running setup.py install for psutil 17:06:53 INFO - building 'psutil._psutil_linux' extension 17:06:53 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 17:06:53 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 17:06:53 INFO - building 'psutil._psutil_posix' extension 17:06:53 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 17:06:53 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 17:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:53 INFO - Successfully installed psutil 17:06:53 INFO - Cleaning up... 17:06:53 INFO - Return code: 0 17:06:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:06: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')]} 17:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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', '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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:06: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 17:06: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 mozsystemmonitor==0.0.0 17:06:53 INFO - Using env: {'DISPLAY': ':0', 17:06:53 INFO - 'HOME': '/home/cltbld', 17:06:53 INFO - 'LANG': 'en_US.UTF-8', 17:06:53 INFO - 'LANGUAGE': 'en_US:en', 17:06:53 INFO - 'LOGNAME': 'cltbld', 17:06:53 INFO - 'MAIL': '/var/mail/cltbld', 17:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:53 INFO - 'MOZ_NO_REMOTE': '1', 17:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:53 INFO - 'NO_EM_RESTART': '1', 17:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:06:53 INFO - 'PWD': '/builds/slave/test-pgo', 17:06:53 INFO - 'SHELL': '/bin/bash', 17:06:53 INFO - 'SHLVL': '1', 17:06:53 INFO - 'TERM': 'linux', 17:06:53 INFO - 'TMOUT': '86400', 17:06:53 INFO - 'USER': 'cltbld', 17:06:53 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:53 INFO - '_': '/tools/buildbot/bin/python'} 17:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:06:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:06:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:06:55 INFO - Installing collected packages: mozsystemmonitor 17:06:55 INFO - Running setup.py install for mozsystemmonitor 17:06:55 INFO - Successfully installed mozsystemmonitor 17:06:55 INFO - Cleaning up... 17:06:55 INFO - Return code: 0 17:06:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:06:55 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')]} 17:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:55 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')]} 17:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:55 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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:06:55 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 17:06:55 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 17:06:55 INFO - Using env: {'DISPLAY': ':0', 17:06:55 INFO - 'HOME': '/home/cltbld', 17:06:55 INFO - 'LANG': 'en_US.UTF-8', 17:06:55 INFO - 'LANGUAGE': 'en_US:en', 17:06:55 INFO - 'LOGNAME': 'cltbld', 17:06:55 INFO - 'MAIL': '/var/mail/cltbld', 17:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:55 INFO - 'MOZ_NO_REMOTE': '1', 17:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:55 INFO - 'NO_EM_RESTART': '1', 17:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:06:55 INFO - 'PWD': '/builds/slave/test-pgo', 17:06:55 INFO - 'SHELL': '/bin/bash', 17:06:55 INFO - 'SHLVL': '1', 17:06:55 INFO - 'TERM': 'linux', 17:06:55 INFO - 'TMOUT': '86400', 17:06:55 INFO - 'USER': 'cltbld', 17:06:55 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:55 INFO - '_': '/tools/buildbot/bin/python'} 17:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:55 INFO - Downloading/unpacking blobuploader==1.2.4 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06: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 17:06:57 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:06:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:06:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:06:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:58 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 17:06:58 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 17:06:58 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 17:06:58 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 17:06:58 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 17:06:58 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 17:06:58 INFO - Downloading docopt-0.6.1.tar.gz 17:06:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:06:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:58 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:58 INFO - Running setup.py install for blobuploader 17:06:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:58 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:06:58 INFO - Running setup.py install for requests 17:06:58 INFO - Running setup.py install for docopt 17:06:58 INFO - Successfully installed blobuploader requests docopt 17:06:58 INFO - Cleaning up... 17:06:58 INFO - Return code: 0 17:06:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:06:58 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')]} 17:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:58 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')]} 17:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:58 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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:06:58 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 17:06:58 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 17:06:58 INFO - Using env: {'DISPLAY': ':0', 17:06:58 INFO - 'HOME': '/home/cltbld', 17:06:58 INFO - 'LANG': 'en_US.UTF-8', 17:06:58 INFO - 'LANGUAGE': 'en_US:en', 17:06:58 INFO - 'LOGNAME': 'cltbld', 17:06:58 INFO - 'MAIL': '/var/mail/cltbld', 17:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:58 INFO - 'MOZ_NO_REMOTE': '1', 17:06:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:58 INFO - 'NO_EM_RESTART': '1', 17:06:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:06:58 INFO - 'PWD': '/builds/slave/test-pgo', 17:06:58 INFO - 'SHELL': '/bin/bash', 17:06:58 INFO - 'SHLVL': '1', 17:06:58 INFO - 'TERM': 'linux', 17:06:58 INFO - 'TMOUT': '86400', 17:06:58 INFO - 'USER': 'cltbld', 17:06:58 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:58 INFO - '_': '/tools/buildbot/bin/python'} 17:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:06:59 INFO - Running setup.py (path:/tmp/pip-AaYWdB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:06:59 INFO - Running setup.py (path:/tmp/pip-LmLVwG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:06:59 INFO - Running setup.py (path:/tmp/pip-TPC6jr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:06:59 INFO - Running setup.py (path:/tmp/pip-E8UPMA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:06:59 INFO - Running setup.py (path:/tmp/pip-W9UL5t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:06:59 INFO - Running setup.py (path:/tmp/pip-xAh4rS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:06:59 INFO - Running setup.py (path:/tmp/pip-ylMjlc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:06:59 INFO - Running setup.py (path:/tmp/pip-dlbaUj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:06:59 INFO - Running setup.py (path:/tmp/pip-UzL1mu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:06:59 INFO - Running setup.py (path:/tmp/pip-L25b8J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:06:59 INFO - Running setup.py (path:/tmp/pip-2n3YXP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:06:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:06:59 INFO - Running setup.py (path:/tmp/pip-zz2Jz2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:07:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:07:00 INFO - Running setup.py (path:/tmp/pip-Mon1J5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:07:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:07:00 INFO - Running setup.py (path:/tmp/pip-Mk1GuP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:07:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:07:00 INFO - Running setup.py (path:/tmp/pip-pIRWtr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:07:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:07:00 INFO - Running setup.py (path:/tmp/pip-beFGLs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:07:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:07:00 INFO - Running setup.py (path:/tmp/pip-WwpV3h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:07:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:07:00 INFO - Running setup.py install for manifestparser 17:07:00 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:07:00 INFO - Running setup.py install for mozcrash 17:07:00 INFO - Running setup.py install for mozdebug 17:07:00 INFO - Running setup.py install for mozdevice 17:07:00 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:07:00 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:07:00 INFO - Running setup.py install for mozfile 17:07:00 INFO - Running setup.py install for mozhttpd 17:07:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozinfo 17:07:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozInstall 17:07:01 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozleak 17:07:01 INFO - Running setup.py install for mozlog 17:07:01 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for moznetwork 17:07:01 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozprocess 17:07:01 INFO - Running setup.py install for mozprofile 17:07:01 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozrunner 17:07:01 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:07:01 INFO - Running setup.py install for mozscreenshot 17:07:01 INFO - Running setup.py install for moztest 17:07:02 INFO - Running setup.py install for mozversion 17:07:02 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:07:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:07:02 INFO - Cleaning up... 17:07:02 INFO - Return code: 0 17:07:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:02 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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:07:02 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 17:07:02 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 17:07:02 INFO - Using env: {'DISPLAY': ':0', 17:07:02 INFO - 'HOME': '/home/cltbld', 17:07:02 INFO - 'LANG': 'en_US.UTF-8', 17:07:02 INFO - 'LANGUAGE': 'en_US:en', 17:07:02 INFO - 'LOGNAME': 'cltbld', 17:07:02 INFO - 'MAIL': '/var/mail/cltbld', 17:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:02 INFO - 'MOZ_NO_REMOTE': '1', 17:07:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:02 INFO - 'NO_EM_RESTART': '1', 17:07:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:07:02 INFO - 'PWD': '/builds/slave/test-pgo', 17:07:02 INFO - 'SHELL': '/bin/bash', 17:07:02 INFO - 'SHLVL': '1', 17:07:02 INFO - 'TERM': 'linux', 17:07:02 INFO - 'TMOUT': '86400', 17:07:02 INFO - 'USER': 'cltbld', 17:07:02 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:02 INFO - '_': '/tools/buildbot/bin/python'} 17:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:07:02 INFO - Running setup.py (path:/tmp/pip-fY6cer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:07:02 INFO - Running setup.py (path:/tmp/pip-Lsi41p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:07:02 INFO - Running setup.py (path:/tmp/pip-XXxDdK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:07:02 INFO - Running setup.py (path:/tmp/pip-NcYMoD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:07:02 INFO - Running setup.py (path:/tmp/pip-TFdNmW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:07:02 INFO - Running setup.py (path:/tmp/pip-ORyVu3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:07:02 INFO - Running setup.py (path:/tmp/pip-ie1rN_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:07:02 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)) 17:07:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:07:02 INFO - Running setup.py (path:/tmp/pip-bEREvx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:07:03 INFO - Running setup.py (path:/tmp/pip-Bpljus-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:07:03 INFO - Running setup.py (path:/tmp/pip-Mnc5ht-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:07:03 INFO - Running setup.py (path:/tmp/pip-XJtYyc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:07:03 INFO - Running setup.py (path:/tmp/pip-OQnFoM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:07:03 INFO - Running setup.py (path:/tmp/pip-squnYS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:07:03 INFO - Running setup.py (path:/tmp/pip-c3FpXY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:07:03 INFO - Running setup.py (path:/tmp/pip-UrGlT3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:07:03 INFO - Running setup.py (path:/tmp/pip-N1L59R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:07:03 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)) 17:07:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:07:03 INFO - Running setup.py (path:/tmp/pip-zl6EQG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:07:03 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)) 17:07:03 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)) 17:07:03 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)) 17:07:03 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)) 17:07:03 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)) 17:07:03 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)) 17:07:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:07:03 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 17:07:03 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 17:07:03 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 17:07:03 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 17:07:03 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 17:07:03 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 17:07:05 INFO - Downloading blessings-1.5.1.tar.gz 17:07:05 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 17:07:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:07:05 INFO - Installing collected packages: blessings 17:07:05 INFO - Running setup.py install for blessings 17:07:05 INFO - Successfully installed blessings 17:07:05 INFO - Cleaning up... 17:07:05 INFO - Return code: 0 17:07:05 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:07:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:07:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:07:05 INFO - Reading from file tmpfile_stdout 17:07:05 INFO - Current package versions: 17:07:05 INFO - argparse == 1.2.1 17:07:05 INFO - blessings == 1.5.1 17:07:05 INFO - blobuploader == 1.2.4 17:07:05 INFO - docopt == 0.6.1 17:07:05 INFO - manifestparser == 1.1 17:07:05 INFO - mozInstall == 1.12 17:07:05 INFO - mozcrash == 0.16 17:07:05 INFO - mozdebug == 0.1 17:07:05 INFO - mozdevice == 0.47 17:07:05 INFO - mozfile == 1.2 17:07:05 INFO - mozhttpd == 0.7 17:07:05 INFO - mozinfo == 0.9 17:07:05 INFO - mozleak == 0.1 17:07:05 INFO - mozlog == 3.1 17:07:05 INFO - moznetwork == 0.27 17:07:05 INFO - mozprocess == 0.22 17:07:05 INFO - mozprofile == 0.28 17:07:05 INFO - mozrunner == 6.11 17:07:05 INFO - mozscreenshot == 0.1 17:07:05 INFO - mozsystemmonitor == 0.0 17:07:05 INFO - moztest == 0.7 17:07:05 INFO - mozversion == 1.4 17:07:05 INFO - psutil == 3.1.1 17:07:05 INFO - requests == 1.2.3 17:07:05 INFO - wsgiref == 0.1.2 17:07:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:07:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 17:07:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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 17:07:05 INFO - Using env: {'DISPLAY': ':0', 17:07:05 INFO - 'HOME': '/home/cltbld', 17:07:05 INFO - 'LANG': 'en_US.UTF-8', 17:07:05 INFO - 'LANGUAGE': 'en_US:en', 17:07:05 INFO - 'LOGNAME': 'cltbld', 17:07:05 INFO - 'MAIL': '/var/mail/cltbld', 17:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:05 INFO - 'MOZ_NO_REMOTE': '1', 17:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:05 INFO - 'NO_EM_RESTART': '1', 17:07:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:07:05 INFO - 'PWD': '/builds/slave/test-pgo', 17:07:05 INFO - 'SHELL': '/bin/bash', 17:07:05 INFO - 'SHLVL': '1', 17:07:05 INFO - 'TERM': 'linux', 17:07:05 INFO - 'TMOUT': '86400', 17:07:05 INFO - 'USER': 'cltbld', 17:07:05 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:05 INFO - '_': '/tools/buildbot/bin/python'} 17:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 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)) 17:07:06 INFO - Cleaning up... 17:07:06 INFO - Return code: 0 17:07:06 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:07: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')]} 17:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07: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')]} 17:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:07: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', 'jsonschema'] in /builds/slave/test-pgo/build 17:07: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 jsonschema 17:07:06 INFO - Using env: {'DISPLAY': ':0', 17:07:06 INFO - 'HOME': '/home/cltbld', 17:07:06 INFO - 'LANG': 'en_US.UTF-8', 17:07:06 INFO - 'LANGUAGE': 'en_US:en', 17:07:06 INFO - 'LOGNAME': 'cltbld', 17:07:06 INFO - 'MAIL': '/var/mail/cltbld', 17:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:06 INFO - 'MOZ_NO_REMOTE': '1', 17:07:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:06 INFO - 'NO_EM_RESTART': '1', 17:07:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:07:06 INFO - 'PWD': '/builds/slave/test-pgo', 17:07:06 INFO - 'SHELL': '/bin/bash', 17:07:06 INFO - 'SHLVL': '1', 17:07:06 INFO - 'TERM': 'linux', 17:07:06 INFO - 'TMOUT': '86400', 17:07:06 INFO - 'USER': 'cltbld', 17:07:06 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:06 INFO - '_': '/tools/buildbot/bin/python'} 17:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:06 INFO - Downloading/unpacking jsonschema 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:07:07 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 17:07:07 INFO - Downloading/unpacking functools32 (from jsonschema) 17:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:07:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:07:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:07:08 INFO - warning: no files found matching '*.txt' 17:07:08 INFO - no previously-included directories found matching 'build' 17:07:08 INFO - no previously-included directories found matching 'dist' 17:07:08 INFO - no previously-included directories found matching '.git*' 17:07:08 INFO - Installing collected packages: jsonschema, functools32 17:07:08 INFO - Running setup.py install for functools32 17:07:08 INFO - warning: no files found matching '*.txt' 17:07:08 INFO - no previously-included directories found matching 'build' 17:07:08 INFO - no previously-included directories found matching 'dist' 17:07:08 INFO - no previously-included directories found matching '.git*' 17:07:08 INFO - Successfully installed jsonschema functools32 17:07:08 INFO - Cleaning up... 17:07:08 INFO - Return code: 0 17:07:08 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:07:08 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')]} 17:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:08 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')]} 17:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:08 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 0x7fa688ba8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, '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': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', '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 17:07:08 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 17:07:08 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 17:07:08 INFO - Using env: {'DISPLAY': ':0', 17:07:08 INFO - 'HOME': '/home/cltbld', 17:07:08 INFO - 'LANG': 'en_US.UTF-8', 17:07:08 INFO - 'LANGUAGE': 'en_US:en', 17:07:08 INFO - 'LOGNAME': 'cltbld', 17:07:08 INFO - 'MAIL': '/var/mail/cltbld', 17:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:08 INFO - 'MOZ_NO_REMOTE': '1', 17:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:08 INFO - 'NO_EM_RESTART': '1', 17:07:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:07:08 INFO - 'PWD': '/builds/slave/test-pgo', 17:07:08 INFO - 'SHELL': '/bin/bash', 17:07:08 INFO - 'SHLVL': '1', 17:07:08 INFO - 'TERM': 'linux', 17:07:08 INFO - 'TMOUT': '86400', 17:07:08 INFO - 'USER': 'cltbld', 17:07:08 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:08 INFO - '_': '/tools/buildbot/bin/python'} 17:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:08 INFO - Downloading/unpacking flake8 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07: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 17:07:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:07:10 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 17:07:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:07:10 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 17:07:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 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 17:07:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:07:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:07:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:07:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:07:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:07:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:07:10 INFO - no previously-included directories found matching 'docs/_build' 17:07:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:07:10 INFO - Running setup.py install for pep8 17:07:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:07:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:07:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:07:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:07:11 INFO - no previously-included directories found matching 'docs/_build' 17:07:11 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:07:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:07:11 INFO - Cleaning up... 17:07:11 INFO - Return code: 0 17:07:11 INFO - Running post-action listener: _resource_record_post_action 17:07:11 INFO - Running post-action listener: _start_resource_monitoring 17:07:11 INFO - Starting resource monitoring. 17:07:11 INFO - ##### 17:07:11 INFO - ##### Running install step. 17:07:11 INFO - ##### 17:07:11 INFO - Running pre-action listener: _resource_record_pre_action 17:07:11 INFO - Running main action method: install 17:07:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:07:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:07:11 INFO - Reading from file tmpfile_stdout 17:07:11 INFO - Detecting whether we're running mozinstall >=1.0... 17:07:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:07:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:07:11 INFO - Reading from file tmpfile_stdout 17:07:11 INFO - Output received: 17:07:11 INFO - Usage: mozinstall [options] installer 17:07:11 INFO - Options: 17:07:11 INFO - -h, --help show this help message and exit 17:07:11 INFO - -d DEST, --destination=DEST 17:07:11 INFO - Directory to install application into. [default: 17:07:11 INFO - "/builds/slave/test-pgo"] 17:07:11 INFO - --app=APP Application being installed. [default: firefox] 17:07:11 INFO - mkdir: /builds/slave/test-pgo/build/application 17:07:11 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'] 17:07:11 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 17:07:27 INFO - Reading from file tmpfile_stdout 17:07:27 INFO - Output received: 17:07:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:07:27 INFO - Running post-action listener: _resource_record_post_action 17:07:27 INFO - ##### 17:07:27 INFO - ##### Running run-tests step. 17:07:27 INFO - ##### 17:07:27 INFO - Running pre-action listener: _resource_record_pre_action 17:07:27 INFO - Running main action method: run_tests 17:07:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:07:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:07:27 INFO - Python 2.7.3 17:07:27 INFO - Return code: 0 17:07:27 INFO - grabbing minidump binary from tooltool 17:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:07:27 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 0x2d12840>, '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 0x2c7f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:07:27 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 17:07:27 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 17:07:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:07:27 INFO - Return code: 0 17:07:27 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:07:27 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:07:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:07:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:07:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:07:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:07:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:07:28 INFO - Return code: 0 17:07:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 17:07:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 17:07:28 INFO - Using env: {'DISPLAY': ':0', 17:07:28 INFO - 'HOME': '/home/cltbld', 17:07:28 INFO - 'LANG': 'en_US.UTF-8', 17:07:28 INFO - 'LANGUAGE': 'en_US:en', 17:07:28 INFO - 'LOGNAME': 'cltbld', 17:07:28 INFO - 'MAIL': '/var/mail/cltbld', 17:07:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:07:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:28 INFO - 'MOZ_NO_REMOTE': '1', 17:07:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:28 INFO - 'NO_EM_RESTART': '1', 17:07:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:07:28 INFO - 'PWD': '/builds/slave/test-pgo', 17:07:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:07:28 INFO - 'SHELL': '/bin/bash', 17:07:28 INFO - 'SHLVL': '1', 17:07:28 INFO - 'TERM': 'linux', 17:07:28 INFO - 'TMOUT': '86400', 17:07:28 INFO - 'USER': 'cltbld', 17:07:28 INFO - 'XDG_SESSION_COOKIE': '6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798', 17:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:28 INFO - '_': '/tools/buildbot/bin/python'} 17:07:28 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 17:07:28 INFO - mozversion application_buildid: 20160101143004 17:07:28 INFO - mozversion application_changeset: 8079161123840602784e73dfaa61d1946abfc07a 17:07:28 INFO - mozversion application_display_name: Nightly 17:07:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:07:28 INFO - mozversion application_name: Firefox 17:07:28 INFO - mozversion application_remotingname: firefox 17:07:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:07:28 INFO - mozversion application_vendor: Mozilla 17:07:28 INFO - mozversion application_version: 46.0a1 17:07:28 INFO - mozversion platform_buildid: 20160101143004 17:07:28 INFO - mozversion platform_changeset: 8079161123840602784e73dfaa61d1946abfc07a 17:07:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:07:28 INFO - mozversion platform_version: 46.0a1 17:07:28 INFO - using testdate: 1451696848 17:07:28 INFO - actual date: 1451696848 17:07:28 INFO - starting webserver on 'localhost:59915' 17:07:28 INFO - SUITE-START | Running 3 tests 17:07:28 INFO - TEST-START | dromaeo_css 17:07:28 INFO - operating with platform_type : linux_ 17:07:28 INFO - Initialising browser for dromaeo_css test... 17:07:28 INFO - TEST-INFO | started process 7391 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvT1FEK/profile http://localhost:59915/getInfo.html) 17:07:32 INFO - PROCESS | 7391 | __metrics Screen width/height:1600/1200 17:07:32 INFO - PROCESS | 7391 | colorDepth:24 17:07:32 INFO - PROCESS | 7391 | Browser inner width/height: 1024/697 17:07:32 INFO - PROCESS | 7391 | __metrics 17:07:32 INFO - PROCESS | 7391 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:07:32 INFO - PROCESS | 7391 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:07:33 INFO - TEST-INFO | 7391: exit 0 17:07:33 INFO - Browser initialized. 17:07:33 INFO - Running cycle 1/1 for dromaeo_css test... 17:07:33 INFO - TEST-INFO | started process 7484 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvT1FEK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:45 INFO - PROCESS | 7484 | RSS: Main: 150081536 17:07:45 INFO - PROCESS | 7484 | 17:08:37 INFO - PROCESS | 7484 | 1451696917127 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] 17:10:45 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59915/tests/dromaeo/cssquery-ext.html) 17:10:46 INFO - PROCESS | 7484 | RSS: Main: 273252352 17:10:46 INFO - PROCESS | 7484 | 17:13:49 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-ext.html (next: http://localhost:59915/tests/dromaeo/cssquery-jquery.html) 17:13:49 INFO - PROCESS | 7484 | RSS: Main: 244604928 17:13:49 INFO - PROCESS | 7484 | 17:16:51 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59915/tests/dromaeo/cssquery-mootools.html) 17:16:52 INFO - PROCESS | 7484 | RSS: Main: 234217472 17:16:52 INFO - PROCESS | 7484 | 17:19:54 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59915/tests/dromaeo/cssquery-prototype.html) 17:19:54 INFO - PROCESS | 7484 | RSS: Main: 216293376 17:19:54 INFO - PROCESS | 7484 | 17:22:57 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59915/tests/dromaeo/cssquery-yui.html) 17:22:57 INFO - PROCESS | 7484 | RSS: Main: 218664960 17:22:57 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | Cycle 1(1): loaded http://localhost:59915/tests/dromaeo/cssquery-yui.html (next: http://localhost:59915/tests/dromaeo/cssquery-dojo.html) 17:26:00 INFO - PROCESS | 7484 | RSS: Main: 282034176 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | __start_tp_report 17:26:00 INFO - PROCESS | 7484 | _x_x_mozilla_page_load 17:26:00 INFO - PROCESS | 7484 | _x_x_mozilla_page_load_details 17:26:00 INFO - PROCESS | 7484 | |i|pagename|runs| 17:26:00 INFO - PROCESS | 7484 | |0;dojo.html;3092.8298943558125;3098.4422803895295;3138.937221255575;3144.4025635129337;3172.8413579321027;5017.698938063719;5125.077486052513;5125.410577783556;5153.046686363021;5180.792768289269;5136.563392111666;5238.292830467885;5277.1556550951855;5278.155495120782;5284.894302113965;4655.045715628288;4703.341532185488;4789.880252993686;4889.535494128052;4892.633052521057;4822.951770482303;4840.443349014856;4898.559129678329;4903.877403064934;4915.901681966358;4660.557247061523;4675.088357770236;4744.5967092797155;4771.069641419925;4775.474697783241;4669;4679.60223381013;4684.648651351163;4696.060787842445;4717.410323709536;4651.395318608593;4681.461631912305;4697.201475749131;4698.953010469886;4713.151632706112;4972.055309491221;5023;5062.11413002723;5117.283580298761;5151.253068305112;5539.944600554023;5593.720313984325;5597.80407685729;5605.607607467479;5606.635568688022;5485.369182544018;5604.3835178130375;5613.46672066153;5617.410171931929;5626.887462250732;5236.764345604457;5263.289455923439;5264.210368444726;5299.814506492255;5331.226972089025;5685.090385538294;5696.515796157373;5709.571782116358;5718.971405143031;5757.712114394263;5380.435054319319;5529.364123125812;5531.225628412026;5538.501534861882;5541.0580201365865;5475.589330800206;5534.7786088556;5581.49766521015;5609.27079479665;5637.8590535236945;5567.415421380697;5567.554595632339;5567.916481252784;5569.359523654751;5582.190582365534;5425.83722488326;5535.640183388067;5609.382967873531;5610.270664813548;5647.774089036392;4263.957360426418;4333.220020396359;4340.65274778017;4365.497967733688;4371.409859668967;3876.4379165020914;3881.5536213335827;3890.591487893787;3893.04620368006;3911.8630847920185;5176.1200595898035;5198.350206224222;5207.19288510274;5212.4266330703595;5213.3222681051975;5423.918641220306;5525.088360420566;5528.977139229255;5580.581456390787;5655.1517272409465;5159.638825282269;5161.638685292069;5163.66436181647;5164.638475306692;5174.689518628894;4848.12733707936;4848.684835485682;4855.077535268288;4857.101436234308;4899.314096026559;5581.386047534768;5582.441755824466;5689.999999999917;5698.2592263005545;5701.059325211294;5571.331440227199;5608.242887210175;5609.663420194802;5613.88772224545;5617.719114044363;5618.550515958796;5626.099824028136;5666.744996475251;5667.4332566743;5670.4329567044615;5407.215953686772;5504.091824848855;5505.504504594605;5531.9723401382735;5646.2942132233475;5543.77824887;5571.359293681278;5572.749226284908;5593.356763972195;5598.972005139948;5435.10320797064;5459.7270136492225;5460.3720572133125;5462.890742185214;5510.476504732044;5322.520973112439;5421.810236641699;5425.430329815313;5425.647332923348;5553.805616803393;5374.946250537601;5380.650257733235;5381.542568881689;5387.595930305164;5388.461153884737;5480.6437581557075;5486.149646804752;5491.560675146058;5497.835064948058;5553.222548843084;5279.577633789211;5330.120530112488;5340.49265319794;5398.568114550748;5446.1558458439 17:26:00 INFO - PROCESS | 7484 | |1;ext.html;499.69518593657875;508.14631419215726;512.6565201315118;523.7800123947942;536.0699186910707;27940.720592794067;28590.85704571477;35856.2828743425;36403.817980910055;36408.27183456329;39467.407988880186;39701.60298397015;39983.200335993264;40021.39967900477;40041;7351.70593176275;8348.29039531639;8516.74449766508;8534.57327133644;8535.914640853574;7464.178940316561;7575.204603516634;7577.204393538682;7581.3555847753005;10036.799264014751;9967;10002.299839011272;10050.798984020315;10071.899281007207;10087.344322619043;9917.900820991807;9971.202303815677;9999.400035997827;10017.198624110053;10039.598416063349;7484.700611975514;7638.236176382317;9869.111779939769;10052.648157314532;10242.539085741086;35573.822130889435;35940.46089308662;35945.820270898475;35998.82000590007;36035.27929441423;2844.8293102413922;2929.5166297560877;2944.926376840575;3115.704008119225;3360.2271477560053;27848.443031139468;27949.860250698617;28041.57937630937;28272.85863570669;28412.147635570967;3815.4276858471176;3955.9011024724323;4001;4025.8993525162105;4069.694772892045;265239;268069;268207.6589617059;268826;269068;51701.741491292305;52943.20585191148;53003;58607;59620;64681;65527;65646.34353656498;65774;66058.66970665116;14331.355089021019;14350.354234059492;14355.712885742227;14467.710645787025;14485.420583176765;11904.166708330335;11906.761864762657;11975.46110425033;11982;11995.160338776202;19225.898094296863;20133.798662013487;20243.493912652302;20303.796962030192;20377.694334584994;6936;7255.52839065459;7339.669714862844;7364.33720965105;7372.96313518429;928.967486137982;955.9378640388353;1033.2921948465325;1036.8133735927606;1043.1446214104512;28141.5929203539;28352.291192720346;28789;28837.855810720812;28869.27826804347;983.8376667849868;1007.7581380468781;1018.8777346718293;1038.9948050259852;1060.3796778884284;13521.526746563823;13603.523876664269;13632.727345453233;13827.755502004868;13856;253492;256908;256999;257100;257290;46621;46647.30029049567;46725.76637116793;46769;46807;1078.2613909472036;1223.9510419583116;1235.950561977511;1321.73565286943;1337.7190789934223;27459.725402745717;28590.42819143564;28777.46300295161;29193.124206273846;30352.84823575868;45972.31041534313;46323.53676463327;46368;46387.76806115948;46425;239880;242215;389114;401016;417980;30293.69706302909;30307.242318942204;30433.39133217279;30716.232094197825;30740.23149421193;42113.57886421097;42740.572594274905;42774;43756.78121609372;43779.78110109429;35660.821695891354;35705.46441803323;35922.8203858979;36306.636933630325;36559.45160822537;36607;36773.32578927331;36808.81595592005;37130.25739485141;37155.442668360534;35255.82372088123;35435.46846797249;36161.45757813688;36178.4573231407;36206.27587448289;30509.69490305157;31196.220094497818;33233;33322.333553329285;33368.49947250744;24602.101165373624;24812.751872481043;24847.75152248527;25305.240842774747;25337.366565836 17:26:00 INFO - PROCESS | 7484 | |2;jquery.html;353.4486201525619;355.69054922217674;357.1339501708358;360.87549795320615;362.2411048852654;13997.230152341617;14031.579052628413;16576.4198253061;17280.48158555242;18150.455486335413;15399.153046582433;16053.438129665465;16244.269007894643;16309.673806523862;16457.588560285967;5177.042247184274;5307.628466007381;5322.654027488201;5325.733713314338;5329.120695085326;3939.0546268895546;4050.51393832739;5223.947760522403;5296.523312901838;5661.2357331760095;5721.656700597957;5727.3699893011735;5766.942330576682;5774.682392468412;5788.247527821377;5611.8597035074245;5751.4536119068625;5767.192593036978;5767.826965191051;5770.567207459415;5091.541761241469;5092.159793634046;5101.438841727407;5106.795728170869;5108.2848401223855;19232.807671923383;20316.5936681267;20561.588768224552;20824.895875520524;20864.895675521526;2180.062573093569;2256.368216899271;2318.0869556555017;2441.8779061047053;2507.2728908616477;12808.615741527676;12970.546030888874;13059;13114.737868595228;13383.531576394877;1769.6637638848576;1789.4005508154785;1812.1573468337288;1854.1841589700489;1875.1468082022648;56531.43468565344;61218.999999999556;61527;61819.07271390888;61966.69016654888;9487.66793162236;9683.176929960891;9882.901170988342;10022.3485473444;10090.899091008996;12993.675158121123;13010.284434356201;13015.544455943997;13045.543405980934;13048.412821423059;7769.7669069928;7782.571958542335;7805.921940780633;7812.84374312522;7903.920960790433;7620.695172193161;7625.23747625233;7672.80817979555;7684.807879802938;7714.498557593738;5853.385394533598;5876.529877609781;5918.7928422505;5974.790882319098;6002.639841609517;1667.4914151184005;1703.8892471989282;1750;1763.4974032400705;1778.644271145776;951.3103000324709;961.4663861556903;984.9310548261697;1005.1657124586576;1020.1430798129607;16818.495445136665;18197.636047278982;18214.908925455555;18458.538536536693;18461.907690461463;899.2985471332371;916.6654171227455;927.8376284150247;940.5532372123323;985.522021819417;9649.276304277208;9800.950995244979;9843.969798040653;9848.901510984942;9860.654877079267;591134;591392;598316;620733;621732;76277;80072.19927800648;80209.59895200486;80904;82879.5856020716;1138.4137169357787;1141.2182654881433;1145.9427028648367;1147.6614398752388;1150.9942450287695;15162.393504259706;15178.848211517743;15521.922390388427;15571.922140389226;15825.52523424299;36044.63955360518;36151.45772813357;36590.45114323234;37239.25521489609;37791;49438.25842612292;49785.50214497954;51593;52004;52810.471895280556;9300.209482193819;9568.95215523918;9583.808323833624;9584.37701549397;9591.760205994906;11219.60731374398;11313;16207.513774586781;17044.23300951485;17116.82883171202;11686.474108664961;12197.878021220029;14549.19979401143;14813.407463701331;14814.185219812583;13868;14151.504697335544;14266.928665356607;14629.268536573341;14746;14466.782998255245;14714.337854796773;14759.114453132846;14862.851371486147;14880.330385132911;9097.727068187965;9482.810343792948;9495.620175192915;9518;9679;11989.520419183134;14354.569362919128;14486.38632356033;14575.781363279772;14657.340419681354 17:26:00 INFO - PROCESS | 7484 | |3;mootools.html;1304.0741073837576;1414.9787753183705;1418.4042702065133;1536.5159974607998;1767.805541390446;6673;7319.451041171907;7455.813604659873;7455.962720186399;7471.1408188058385;7282;7490.4756667033325;7492.700291988328;7494.250574942503;7494.250574942503;1951.3950675290705;1985.0471773548704;1988.9900550497227;2214.645656694933;2266.3880752196897;2223.321886824518;2266.9886650566723;2268.920587779428;2273.8408311418207;2278.897449614763;2223.0552015393473;2263.6151854184745;2272.704548408713;2286.9885650571805;2290.347251033456;2209.591225623265;2248.786365295294;2249.224017713886;2253.7182852143483;2280.9201677941273;2235.765244649305;2251.8761468119246;2267.319804058792;2269.3645779181857;2277.2485079923586;7484.513506622106;7563.886541701879;7572.772816815449;7573.734919277799;7619.466637335388;4549.909001819979;4623.722576645412;4663.694165633628;4667.253239481701;4676.462206846189;2982.0904624089862;3052.3437460946;3083.398737246238;3087.706667866549;3113.2995076107923;968.1141755293897;970.0396607358734;975.0201047946769;992.2111921022732;1022.8516865054526;10218.693439196835;10316.845247321371;10363.585456581728;10375.636852710195;10382.480875956171;4075.083106301118;4087.713860029829;4203.306454434984;4227.281362168439;4248.915021699549;8574.22831945128;8577.356698247657;8588.914110858812;8592.8711069334;8640.30877529796;4657.6273898088075;4665.580097791215;4674.555917187861;4676.298555216745;4692.694974826625;4293.785310734451;4295.162443323522;4295.539516564383;4295.72077814941;4295.96896744779;3119.064280715776;3159.873605055772;3334.1664583854035;3389.356022355794;3446.3968805458944;1042.8904964978567;1066.461436974323;1074.9355038697547;1076.2573824061374;1087.9456027198767;1020.2807021050141;1026.6560702164709;1087.8912108789216;1164.9941750291193;1329.9867001330056;6837.931620683729;6886.10480637524;6908.965455172692;6944.965275173592;6956.79129626112;600.5945986459122;618.2550027217193;623.0747340199799;623.9251289845248;761.1466080094987;5225.166024473077;5328.067588172132;5444;5451.373092094381;5452.863678407992;8923.107689231025;9084.636614535477;9205.907940920504;9217.953910230406;9219.492927888896;9044.321675874337;9088.318376121817;9145.862812057825;9163.541822908961;9165.954170229239;6969.337912898267;7182.96408517954;7183.281671832775;7203.711851525985;7256.818579535448;6769.729210831709;6798.7280508779095;6802.489813263926;6815.556988795713;6822.3859852613505;7380.483366164317;7550.2072282411755;7676.6161691916295;7694.769156925301;7697.846043078995;7977.800554986172;8101.959490202511;8257.091719910924;8265.95867020661;8268.669253229804;6136.877262454636;6336.239651241881;6346.809595712136;6353.68231588428;6354.491640668828;6143;6285.905711414424;6909.827254318682;7224.241454647186;7287.744928927462;6598.736050557924;6669.83325416849;6822.897656535255;6865.759698410531;7011.614361210182;6653.933460665525;6677.232118306457;6677.599344039373;6808.38724514796;6827.249002609609;6701.8324541884895;6750.29121942189;6755.831104222434;6757.560758550679;6761.22245941723;6304.432601065926;6334.3665633436285;6360.459360954371;6369.14016607752;6371.139896113966;6189.412005859436;6323.873522529616;6389.393007664265;6402.743890244525;6405.551611387158 17:26:00 INFO - PROCESS | 7484 | |4;prototype.html;319.7122589669297;327.07274875727313;331.05153734550515;337.2378424760042;350.0496152944755;5876;5891.057430811065;5900.173975643408;5932.525397968163;5977.163197152402;5569.220309156722;5702.657840529571;5742.339630942442;5840.649561026341;5867.295924489056;4534.97732511337;4538.432695913011;4547.272436410175;4551.544845515438;4557.475890272612;4346.217680817452;4549.567791059844;4552.8861778455475;4561.635069194456;4570.360149579045;4383;4410.580994805488;4508.77456127194;4516.029053753442;4523.660725445605;4494.303382975644;4505.3917721107555;4510.0979804039325;4510.345999830022;4513.729176249419;4027.939580906289;4044.69664775143;4044.8786536403954;4044.8988775280854;4049.5545489996075;5522.447755224485;5620.57845661577;5623.634463759883;5624.746886390122;5641.68970706611;5319.308489896327;5325.866853328659;5325.920111198335;5329.307190065306;5330.893382132335;6213.502919766408;6222.782202622887;6226;6228.5017198624;6231.189945307126;4485.1029794041;4488.932666010012;4489.73061616304;4492.337835001038;4498.775061246957;6270.184875966095;6473.579217350904;6490.091387205795;6498.252700939405;6505.0892874997535;4576.359309696645;4579.771011449414;4583.579090481961;4604.30935359692;4611.469680986729;4668.4631267404;4670.719756814534;4672.299155126758;4681.250999840009;4685.8594242172785;5314.335708036496;5318.149096144675;5318.840434786963;5324.707141107277;5330.760115794798;6109.6639684817765;6237.126802247689;6244.063390491463;6250.343713910065;6356.491480681534;1567.0910871694389;1567.9451219207274;1567.9843201567837;1568.2080549322518;1568.9529314120596;1045.257866914484;1056.6671498477954;1056.6829951014665;1062.1555863088863;1077.902988731018;5916.970415147896;5920.230370051866;5923.703814809329;5928.466438020512;5938.435848594377;6457.257415397289;6466.6766661666725;6466.902996455057;6469.935300647027;6490.383413575702;5721.141828725725;5884.705764711747;5886.58793884424;5889.823305300848;5896.233489646319;3308.1894935740897;3448.2413868948793;3475.461303497962;3508.7894726316504;3508.807015614115;6560.475161987029;6563.7702680405955;6646.335366463315;6658.633775142317;6662.633555154416;6694.397504224643;6695.531312808154;6704.296048914891;6712.597244165366;6714.630695311707;6479.708413121421;6558;6608.570442921195;6614.0740296358545;6615.669216539154;6485.643289619116;6505.349465053457;6510.31641677636;6513.381228783258;6513.7068831903625;6304.148939893055;6399.424051835357;6426.775062872777;6427.357264273535;6436.871262574816;6359.3640635936035;6539.901901471387;6553.967230163818;6555.311692272434;6599.637019963948;4399.472063352418;4427.756473393994;4433.490148632948;4435.689501734848;4438.60052595268;6435.485161187;6440.774572889926;6511.251206111168;6515.73937042513;6517.641529715911;6204.81385558434;6326.398992095744;6326.652034137983;6358.0780786784735;6359.713812878525;6337.207849018872;6341.682915854097;6345.302016778243;6346.650934198663;6350.206224221973;6236.875262494634;6239.906401403891;6242.219722534684;6248.187735594343;6248.4376406123665;6256.749730010749;6362.459190968634;6378.521610879296;6379.74481020754;6383.776567820104;6209.379062093935;6217.96891015542;6222.191115155001;6224.47500362396;6225.875482490415 17:26:00 INFO - PROCESS | 7484 | |5;yui.html;691.3812138136368;833.7457075591944;845.8646616541355;852.2329903087225;859.5702148925537;13220.933895330521;13442.126261792977;13444.865551344483;13457.865421345783;13469.865301346983;11941;12000.759984800321;12008.699782505442;12012.399380031007;12028.097892658065;669.4577392312231;677.8847595908683;806.4677312973439;819.5533434278303;821.2116368286422;817.0522194254665;817.5707753429438;824.3940703582862;824.8474032304013;825.0099880143821;858.3133493205411;862.2843040276556;863.2144748279068;863.8833757442727;864.658459908339;689.9172099348085;696.2271878215178;836.180543067797;858.0132847225678;858.3733874271754;631.3497097989078;632.137132813706;632.2192092765431;632.4497687012283;632.6330728177692;12299.569515066929;12367.257964522158;12386.256824590553;12495.125341226118;12563.623091307183;11661.358625275607;11693.239939403997;11829.526818927232;12029.578964736193;12035.338056406894;10656.946715266451;10677.626283080133;10677.83983240252;10730.731731706692;10758.408287544182;3597.784132952031;3671;3682.852685892588;3724.1434470071763;3757.1734218472166;45959;46133.30800038002;46256.3061554077;46420.30369544459;46601.30098048532;10141.847872281922;10176.236782241362;10212.540435680412;10275.280730348954;10302.793944121226;18984.240630374905;19048.42854714361;19107.80892191088;19109.713354299696;19123.90438047801;7154.856902861914;7277.3086556777025;7320.267973202637;7387.9261207387235;7558.093028836574;4251.744895306291;4302.720323178984;4338.501072376654;4361;4385.868423947223;2885.9033567244314;2929.6484421869513;2930.8094973826633;2931.5016447204025;2936.0898121582377;588.6203398807745;596.8388535095587;598.7245866901275;609.9085137229363;619.5632079384023;571.9256496655491;586.4428792647002;587.5974957154363;612.5895649914568;631.36232405271;7532.300193276774;7789.182135875765;7802.219778022266;7948.841023179504;7974.800629984296;482.1273494974108;519.6232731269799;551.9503244707915;579.3164066401603;641.9215717594393;3400.285939952638;3484.337975784593;3492.877749278763;3535.611082780892;3662.249238906018;53544;53727.46272537224;54184.18723719147;54308.728456357465;55520.44479555153;49323.50676493261;51750;51933;52150.739246304285;52359.214611780095;43990.56009439928;44052.55947440484;44104.558954410684;44685.55314446814;45074.54925450769;7308.415326773952;7308.648866870511;7376.114866216089;7458.589777562069;7517.285857843496;32404.67595324111;32929.341413171125;33477.497837532945;33642;34179.14552136217;38617;38661.03347416337;45436.091278174914;45503.31745023894;48443.757781210865;10212.387256764618;10327.638532651321;10500.267455870002;10668.35989840581;10675.252732308663;11456.312621242752;11527.654170374903;11882.881171188523;11957.820632690691;12018;10548.314359566604;10648.414337211529;10681.092107170978;11076.169287303646;11105.222634415513;10548.84176737365;11021.559137634405;11032;11062.280951738434;11153.442327883411;9725.221982241545;9981.351212171186;10646.148308135176;10658.680239592824;10704.09015233714;8672.436291641023;8844.646214151362;9011.324150688593;9501.097395747393;9697.951510242403;8419.95790021046;8485.193906578865;8776.297896168164;9154.40496367734;9195.172434480928 17:26:00 INFO - PROCESS | 7484 | __end_tp_report 17:26:00 INFO - PROCESS | 7484 | __start_cc_report 17:26:00 INFO - PROCESS | 7484 | _x_x_mozilla_cycle_collect,931 17:26:00 INFO - PROCESS | 7484 | __end_cc_report 17:26:00 INFO - PROCESS | 7484 | __startTimestamp1451697960750__endTimestamp 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | ------- Summary: start ------- 17:26:00 INFO - PROCESS | 7484 | Number of tests: 6 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#0] dojo.html Cycles:165 Average:5176.92 Median:5381.54 stddev:558.85 (10.4%) stddev-sans-first:536.12 17:26:00 INFO - PROCESS | 7484 | Values: 3092.8 3098.4 3138.9 3144.4 3172.8 5017.7 5125.1 5125.4 5153.0 5180.8 5136.6 5238.3 5277.2 5278.2 5284.9 4655.0 4703.3 4789.9 4889.5 4892.6 4823.0 4840.4 4898.6 4903.9 4915.9 4660.6 4675.1 4744.6 4771.1 4775.5 4669.0 4679.6 4684.6 4696.1 4717.4 4651.4 4681.5 4697.2 4699.0 4713.2 4972.1 5023.0 5062.1 5117.3 5151.3 5539.9 5593.7 5597.8 5605.6 5606.6 5485.4 5604.4 5613.5 5617.4 5626.9 5236.8 5263.3 5264.2 5299.8 5331.2 5685.1 5696.5 5709.6 5719.0 5757.7 5380.4 5529.4 5531.2 5538.5 5541.1 5475.6 5534.8 5581.5 5609.3 5637.9 5567.4 5567.6 5567.9 5569.4 5582.2 5425.8 5535.6 5609.4 5610.3 5647.8 4264.0 4333.2 4340.7 4365.5 4371.4 3876.4 3881.6 3890.6 3893.0 3911.9 5176.1 5198.4 5207.2 5212.4 5213.3 5423.9 5525.1 5529.0 5580.6 5655.2 5159.6 5161.6 5163.7 5164.6 5174.7 4848.1 4848.7 4855.1 4857.1 4899.3 5581.4 5582.4 5690.0 5698.3 5701.1 5571.3 5608.2 5609.7 5613.9 5617.7 5618.6 5626.1 5666.7 5667.4 5670.4 5407.2 5504.1 5505.5 5532.0 5646.3 5543.8 5571.4 5572.7 5593.4 5599.0 5435.1 5459.7 5460.4 5462.9 5510.5 5322.5 5421.8 5425.4 5425.6 5553.8 5374.9 5380.7 5381.5 5387.6 5388.5 5480.6 5486.1 5491.6 5497.8 5553.2 5279.6 5330.1 5340.5 5398.6 5446.2 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#1] ext.html Cycles:180 Average:45375.00 Median:35345.65 stddev:76940.69 (217.7%) stddev-sans-first:77082.76 17:26:00 INFO - PROCESS | 7484 | Values: 499.7 508.1 512.7 523.8 536.1 27940.7 28590.9 35856.3 36403.8 36408.3 39467.4 39701.6 39983.2 40021.4 40041.0 7351.7 8348.3 8516.7 8534.6 8535.9 7464.2 7575.2 7577.2 7581.4 10036.8 9967.0 10002.3 10050.8 10071.9 10087.3 9917.9 9971.2 9999.4 10017.2 10039.6 7484.7 7638.2 9869.1 10052.6 10242.5 35573.8 35940.5 35945.8 35998.8 36035.3 2844.8 2929.5 2944.9 3115.7 3360.2 27848.4 27949.9 28041.6 28272.9 28412.1 3815.4 3955.9 4001.0 4025.9 4069.7 265239.0 268069.0 268207.7 268826.0 269068.0 51701.7 52943.2 53003.0 58607.0 59620.0 64681.0 65527.0 65646.3 65774.0 66058.7 14331.4 14350.4 14355.7 14467.7 14485.4 11904.2 11906.8 11975.5 11982.0 11995.2 19225.9 20133.8 20243.5 20303.8 20377.7 6936.0 7255.5 7339.7 7364.3 7373.0 929.0 955.9 1033.3 1036.8 1043.1 28141.6 28352.3 28789.0 28837.9 28869.3 983.8 1007.8 1018.9 1039.0 1060.4 13521.5 13603.5 13632.7 13827.8 13856.0 253492.0 256908.0 256999.0 257100.0 257290.0 46621.0 46647.3 46725.8 46769.0 46807.0 1078.3 1224.0 1236.0 1321.7 1337.7 27459.7 28590.4 28777.5 29193.1 30352.8 45972.3 46323.5 46368.0 46387.8 46425.0 239880.0 242215.0 389114.0 401016.0 417980.0 30293.7 30307.2 30433.4 30716.2 30740.2 42113.6 42740.6 42774.0 43756.8 43779.8 35660.8 35705.5 35922.8 36306.6 36559.5 36607.0 36773.3 36808.8 37130.3 37155.4 35255.8 35435.5 36161.5 36178.5 36206.3 30509.7 31196.2 33233.0 33322.3 33368.5 24602.1 24812.8 24847.8 25305.2 25337.4 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#2] jquery.html Cycles:180 Average:30905.24 Median:18476.35 stddev:98739.78 (534.4%) stddev-sans-first:98990.12 17:26:00 INFO - PROCESS | 7484 | Values: 353.4 355.7 357.1 360.9 362.2 13997.2 14031.6 16576.4 17280.5 18150.5 15399.2 16053.4 16244.3 16309.7 16457.6 5177.0 5307.6 5322.7 5325.7 5329.1 3939.1 4050.5 5223.9 5296.5 5661.2 5721.7 5727.4 5766.9 5774.7 5788.2 5611.9 5751.5 5767.2 5767.8 5770.6 5091.5 5092.2 5101.4 5106.8 5108.3 19232.8 20316.6 20561.6 20824.9 20864.9 2180.1 2256.4 2318.1 2441.9 2507.3 12808.6 12970.5 13059.0 13114.7 13383.5 1769.7 1789.4 1812.2 1854.2 1875.1 56531.4 61219.0 61527.0 61819.1 61966.7 9487.7 9683.2 9882.9 10022.3 10090.9 12993.7 13010.3 13015.5 13045.5 13048.4 7769.8 7782.6 7805.9 7812.8 7903.9 7620.7 7625.2 7672.8 7684.8 7714.5 5853.4 5876.5 5918.8 5974.8 6002.6 1667.5 1703.9 1750.0 1763.5 1778.6 951.3 961.5 984.9 1005.2 1020.1 16818.5 18197.6 18214.9 18458.5 18461.9 899.3 916.7 927.8 940.6 985.5 9649.3 9801.0 9844.0 9848.9 9860.7 591134.0 591392.0 598316.0 620733.0 621732.0 76277.0 80072.2 80209.6 80904.0 82879.6 1138.4 1141.2 1145.9 1147.7 1151.0 15162.4 15178.8 15521.9 15571.9 15825.5 36044.6 36151.5 36590.5 37239.3 37791.0 49438.3 49785.5 51593.0 52004.0 52810.5 9300.2 9569.0 9583.8 9584.4 9591.8 11219.6 11313.0 16207.5 17044.2 17116.8 11686.5 12197.9 14549.2 14813.4 14814.2 13868.0 14151.5 14266.9 14629.3 14746.0 14466.8 14714.3 14759.1 14862.9 14880.3 9097.7 9482.8 9495.6 9518.0 9679.0 11989.5 14354.6 14486.4 14575.8 14657.3 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#3] mootools.html Cycles:180 Average:5224.58 Median:6329.12 stddev:2708.41 (42.8%) stddev-sans-first:2699.98 17:26:00 INFO - PROCESS | 7484 | Values: 1304.1 1415.0 1418.4 1536.5 1767.8 6673.0 7319.5 7455.8 7456.0 7471.1 7282.0 7490.5 7492.7 7494.3 7494.3 1951.4 1985.0 1989.0 2214.6 2266.4 2223.3 2267.0 2268.9 2273.8 2278.9 2223.1 2263.6 2272.7 2287.0 2290.3 2209.6 2248.8 2249.2 2253.7 2280.9 2235.8 2251.9 2267.3 2269.4 2277.2 7484.5 7563.9 7572.8 7573.7 7619.5 4549.9 4623.7 4663.7 4667.3 4676.5 2982.1 3052.3 3083.4 3087.7 3113.3 968.1 970.0 975.0 992.2 1022.9 10218.7 10316.8 10363.6 10375.6 10382.5 4075.1 4087.7 4203.3 4227.3 4248.9 8574.2 8577.4 8588.9 8592.9 8640.3 4657.6 4665.6 4674.6 4676.3 4692.7 4293.8 4295.2 4295.5 4295.7 4296.0 3119.1 3159.9 3334.2 3389.4 3446.4 1042.9 1066.5 1074.9 1076.3 1087.9 1020.3 1026.7 1087.9 1165.0 1330.0 6837.9 6886.1 6909.0 6945.0 6956.8 600.6 618.3 623.1 623.9 761.1 5225.2 5328.1 5444.0 5451.4 5452.9 8923.1 9084.6 9205.9 9218.0 9219.5 9044.3 9088.3 9145.9 9163.5 9166.0 6969.3 7183.0 7183.3 7203.7 7256.8 6769.7 6798.7 6802.5 6815.6 6822.4 7380.5 7550.2 7676.6 7694.8 7697.8 7977.8 8102.0 8257.1 8266.0 8268.7 6136.9 6336.2 6346.8 6353.7 6354.5 6143.0 6285.9 6909.8 7224.2 7287.7 6598.7 6669.8 6822.9 6865.8 7011.6 6653.9 6677.2 6677.6 6808.4 6827.2 6701.8 6750.3 6755.8 6757.6 6761.2 6304.4 6334.4 6360.5 6369.1 6371.1 6189.4 6323.9 6389.4 6402.7 6405.6 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#4] prototype.html Cycles:180 Average:5250.97 Median:5893.65 stddev:1568.12 (26.6%) stddev-sans-first:1528.21 17:26:00 INFO - PROCESS | 7484 | Values: 319.7 327.1 331.1 337.2 350.0 5876.0 5891.1 5900.2 5932.5 5977.2 5569.2 5702.7 5742.3 5840.6 5867.3 4535.0 4538.4 4547.3 4551.5 4557.5 4346.2 4549.6 4552.9 4561.6 4570.4 4383.0 4410.6 4508.8 4516.0 4523.7 4494.3 4505.4 4510.1 4510.3 4513.7 4027.9 4044.7 4044.9 4044.9 4049.6 5522.4 5620.6 5623.6 5624.7 5641.7 5319.3 5325.9 5325.9 5329.3 5330.9 6213.5 6222.8 6226.0 6228.5 6231.2 4485.1 4488.9 4489.7 4492.3 4498.8 6270.2 6473.6 6490.1 6498.3 6505.1 4576.4 4579.8 4583.6 4604.3 4611.5 4668.5 4670.7 4672.3 4681.3 4685.9 5314.3 5318.1 5318.8 5324.7 5330.8 6109.7 6237.1 6244.1 6250.3 6356.5 1567.1 1567.9 1568.0 1568.2 1569.0 1045.3 1056.7 1056.7 1062.2 1077.9 5917.0 5920.2 5923.7 5928.5 5938.4 6457.3 6466.7 6466.9 6469.9 6490.4 5721.1 5884.7 5886.6 5889.8 5896.2 3308.2 3448.2 3475.5 3508.8 3508.8 6560.5 6563.8 6646.3 6658.6 6662.6 6694.4 6695.5 6704.3 6712.6 6714.6 6479.7 6558.0 6608.6 6614.1 6615.7 6485.6 6505.3 6510.3 6513.4 6513.7 6304.1 6399.4 6426.8 6427.4 6436.9 6359.4 6539.9 6554.0 6555.3 6599.6 4399.5 4427.8 4433.5 4435.7 4438.6 6435.5 6440.8 6511.3 6515.7 6517.6 6204.8 6326.4 6326.7 6358.1 6359.7 6337.2 6341.7 6345.3 6346.7 6350.2 6236.9 6239.9 6242.2 6248.2 6248.4 6256.7 6362.5 6378.5 6379.7 6383.8 6209.4 6218.0 6222.2 6224.5 6225.9 17:26:00 INFO - PROCESS | 7484 | 17:26:00 INFO - PROCESS | 7484 | [#5] yui.html Cycles:180 Average:13291.54 Median:45469.70 stddev:15442.27 (34.0%) stddev-sans-first:15456.60 17:26:00 INFO - PROCESS | 7484 | Values: 691.4 833.7 845.9 852.2 859.6 13220.9 13442.1 13444.9 13457.9 13469.9 11941.0 12000.8 12008.7 12012.4 12028.1 669.5 677.9 806.5 819.6 821.2 817.1 817.6 824.4 824.8 825.0 858.3 862.3 863.2 863.9 864.7 689.9 696.2 836.2 858.0 858.4 631.3 632.1 632.2 632.4 632.6 12299.6 12367.3 12386.3 12495.1 12563.6 11661.4 11693.2 11829.5 12029.6 12035.3 10656.9 10677.6 10677.8 10730.7 10758.4 3597.8 3671.0 3682.9 3724.1 3757.2 45959.0 46133.3 46256.3 46420.3 46601.3 10141.8 10176.2 10212.5 10275.3 10302.8 18984.2 19048.4 19107.8 19109.7 19123.9 7154.9 7277.3 7320.3 7387.9 7558.1 4251.7 4302.7 4338.5 4361.0 4385.9 2885.9 2929.6 2930.8 2931.5 2936.1 588.6 596.8 598.7 609.9 619.6 571.9 586.4 587.6 612.6 631.4 7532.3 7789.2 7802.2 7948.8 7974.8 482.1 519.6 552.0 579.3 641.9 3400.3 3484.3 3492.9 3535.6 3662.2 53544.0 53727.5 54184.2 54308.7 55520.4 49323.5 51750.0 51933.0 52150.7 52359.2 43990.6 44052.6 44104.6 44685.6 45074.5 7308.4 7308.6 7376.1 7458.6 7517.3 32404.7 32929.3 33477.5 33642.0 34179.1 38617.0 38661.0 45436.1 45503.3 48443.8 10212.4 10327.6 10500.3 10668.4 10675.3 11456.3 11527.7 11882.9 11957.8 12018.0 10548.3 10648.4 10681.1 11076.2 11105.2 10548.8 11021.6 11032.0 11062.3 11153.4 9725.2 9981.4 10646.1 10658.7 10704.1 8672.4 8844.6 9011.3 9501.1 9698.0 8420.0 8485.2 8776.3 9154.4 9195.2 17:26:00 INFO - PROCESS | 7484 | -------- Summary: end -------- 17:26:00 INFO - PROCESS | 7484 | 17:26:01 INFO - TEST-INFO | 7484: exit 0 17:26:01 INFO - TEST-OK | dromaeo_css | took 1112953ms 17:26:01 INFO - TEST-START | kraken 17:26:01 INFO - operating with platform_type : linux_ 17:26:01 INFO - Initialising browser for kraken test... 17:26:01 INFO - TEST-INFO | started process 7676 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXzx0rT/profile http://localhost:59915/getInfo.html) 17:26:05 INFO - PROCESS | 7676 | __metrics Screen width/height:1600/1200 17:26:05 INFO - PROCESS | 7676 | colorDepth:24 17:26:05 INFO - PROCESS | 7676 | Browser inner width/height: 1024/697 17:26:05 INFO - PROCESS | 7676 | __metrics 17:26:06 INFO - PROCESS | 7676 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:26:06 INFO - PROCESS | 7676 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:26:06 INFO - TEST-INFO | 7676: exit 0 17:26:06 INFO - Browser initialized. 17:26:06 INFO - Running cycle 1/1 for kraken test... 17:26:06 INFO - TEST-INFO | started process 7758 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXzx0rT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:18 INFO - PROCESS | 7758 | RSS: Main: 150409216 17:26:18 INFO - PROCESS | 7758 | 17:26:21 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59915/tests/kraken/driver.html?testName=audio-beat-detection) 17:26:21 INFO - PROCESS | 7758 | RSS: Main: 184786944 17:26:21 INFO - PROCESS | 7758 | 17:26:24 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59915/tests/kraken/driver.html?testName=audio-dft) 17:26:24 INFO - PROCESS | 7758 | RSS: Main: 173912064 17:26:24 INFO - PROCESS | 7758 | 17:26:27 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59915/tests/kraken/driver.html?testName=audio-fft) 17:26:28 INFO - PROCESS | 7758 | RSS: Main: 160960512 17:26:28 INFO - PROCESS | 7758 | 17:26:30 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59915/tests/kraken/driver.html?testName=audio-oscillator) 17:26:31 INFO - PROCESS | 7758 | RSS: Main: 162934784 17:26:31 INFO - PROCESS | 7758 | 17:26:33 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:26:33 INFO - PROCESS | 7758 | RSS: Main: 165826560 17:26:33 INFO - PROCESS | 7758 | 17:26:36 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59915/tests/kraken/driver.html?testName=imaging-darkroom) 17:26:37 INFO - PROCESS | 7758 | RSS: Main: 164732928 17:26:37 INFO - PROCESS | 7758 | 17:26:40 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59915/tests/kraken/driver.html?testName=imaging-desaturate) 17:26:41 INFO - PROCESS | 7758 | RSS: Main: 165142528 17:26:41 INFO - PROCESS | 7758 | 17:26:43 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59915/tests/kraken/driver.html?testName=json-parse-financial) 17:26:44 INFO - PROCESS | 7758 | RSS: Main: 164810752 17:26:44 INFO - PROCESS | 7758 | 17:26:46 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:26:46 INFO - PROCESS | 7758 | RSS: Main: 176889856 17:26:46 INFO - PROCESS | 7758 | 17:26:48 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:26:49 INFO - PROCESS | 7758 | RSS: Main: 175427584 17:26:49 INFO - PROCESS | 7758 | 17:26:51 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:26:51 INFO - PROCESS | 7758 | RSS: Main: 183943168 17:26:51 INFO - PROCESS | 7758 | 17:26:54 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:26:54 INFO - PROCESS | 7758 | RSS: Main: 192765952 17:26:54 INFO - PROCESS | 7758 | 17:26:57 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:26:57 INFO - PROCESS | 7758 | RSS: Main: 191741952 17:26:57 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | Cycle 1(1): loaded http://localhost:59915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59915/tests/kraken/driver.html?testName=ai-astar) 17:26:59 INFO - PROCESS | 7758 | RSS: Main: 248958976 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | __start_tp_report 17:26:59 INFO - PROCESS | 7758 | _x_x_mozilla_page_load 17:26:59 INFO - PROCESS | 7758 | _x_x_mozilla_page_load_details 17:26:59 INFO - PROCESS | 7758 | |i|pagename|runs| 17:26:59 INFO - PROCESS | 7758 | |0;ai-astar;109;111;104;111;111;110;99;111;110;104 17:26:59 INFO - PROCESS | 7758 | |1;audio-beat-detection;145;126;156;175;126;151;149;126;165;133 17:26:59 INFO - PROCESS | 7758 | |2;audio-dft;161;162;162;161;164;163;159;159;161;160 17:26:59 INFO - PROCESS | 7758 | |3;audio-fft;72;73;80;95;74;73;72;98;88;83 17:26:59 INFO - PROCESS | 7758 | |4;audio-oscillator;96;97;150;96;96;96;142;96;96;136 17:26:59 INFO - PROCESS | 7758 | |5;imaging-gaussian-blur;109;109;109;109;109;109;109;110;109;109 17:26:59 INFO - PROCESS | 7758 | |6;imaging-darkroom;146;146;146;145;145;145;145;145;145;145 17:26:59 INFO - PROCESS | 7758 | |7;imaging-desaturate;96;95;95;96;95;95;96;96;96;96 17:26:59 INFO - PROCESS | 7758 | |8;json-parse-financial;58;57;58;56;59;57;62;67;54;56 17:26:59 INFO - PROCESS | 7758 | |9;json-stringify-tinderbox;67;68;67;68;68;67;68;68;68;68 17:26:59 INFO - PROCESS | 7758 | |10;stanford-crypto-aes;94;86;86;94;92;86;85;85;84;86 17:26:59 INFO - PROCESS | 7758 | |11;stanford-crypto-ccm;119;122;117;124;125;115;136;121;115;130 17:26:59 INFO - PROCESS | 7758 | |12;stanford-crypto-pbkdf2;141;155;150;151;145;143;150;149;150;140 17:26:59 INFO - PROCESS | 7758 | |13;stanford-crypto-sha256-iterative;67;67;67;70;69;67;67;68;72;69 17:26:59 INFO - PROCESS | 7758 | __end_tp_report 17:26:59 INFO - PROCESS | 7758 | __start_cc_report 17:26:59 INFO - PROCESS | 7758 | _x_x_mozilla_cycle_collect,936 17:26:59 INFO - PROCESS | 7758 | __end_cc_report 17:26:59 INFO - PROCESS | 7758 | __startTimestamp1451698019751__endTimestamp 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | ------- Summary: start ------- 17:26:59 INFO - PROCESS | 7758 | Number of tests: 14 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#0] ai-astar Cycles:10 Average:108.00 Median:111.00 stddev:4.19 (3.8%) stddev-sans-first:4.43 17:26:59 INFO - PROCESS | 7758 | Values: 109.0 111.0 104.0 111.0 111.0 110.0 99.0 111.0 110.0 104.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:150.00 stddev:17.32 (11.5%) stddev-sans-first:18.37 17:26:59 INFO - PROCESS | 7758 | Values: 145.0 126.0 156.0 175.0 126.0 151.0 149.0 126.0 165.0 133.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.62 (1.0%) stddev-sans-first:1.72 17:26:59 INFO - PROCESS | 7758 | Values: 161.0 162.0 162.0 161.0 164.0 163.0 159.0 159.0 161.0 160.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#3] audio-fft Cycles:10 Average:80.80 Median:81.50 stddev:9.87 (12.1%) stddev-sans-first:9.95 17:26:59 INFO - PROCESS | 7758 | Values: 72.0 73.0 80.0 95.0 74.0 73.0 72.0 98.0 88.0 83.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#4] audio-oscillator Cycles:10 Average:110.10 Median:96.00 stddev:22.72 (23.7%) stddev-sans-first:23.52 17:26:59 INFO - PROCESS | 7758 | Values: 96.0 97.0 150.0 96.0 96.0 96.0 142.0 96.0 96.0 136.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:26:59 INFO - PROCESS | 7758 | Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 17:26:59 INFO - PROCESS | 7758 | Values: 146.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:26:59 INFO - PROCESS | 7758 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.00 stddev:3.69 (6.4%) stddev-sans-first:3.91 17:26:59 INFO - PROCESS | 7758 | Values: 58.0 57.0 58.0 56.0 59.0 57.0 62.0 67.0 54.0 56.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 17:26:59 INFO - PROCESS | 7758 | Values: 67.0 68.0 67.0 68.0 68.0 67.0 68.0 68.0 68.0 68.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.00 stddev:3.91 (4.5%) stddev-sans-first:3.44 17:26:59 INFO - PROCESS | 7758 | Values: 94.0 86.0 86.0 94.0 92.0 86.0 85.0 85.0 84.0 86.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:123.00 stddev:6.70 (5.4%) stddev-sans-first:7.00 17:26:59 INFO - PROCESS | 7758 | Values: 119.0 122.0 117.0 124.0 125.0 115.0 136.0 121.0 115.0 130.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.40 Median:150.00 stddev:4.88 (3.3%) stddev-sans-first:4.59 17:26:59 INFO - PROCESS | 7758 | Values: 141.0 155.0 150.0 151.0 145.0 143.0 150.0 149.0 150.0 140.0 17:26:59 INFO - PROCESS | 7758 | 17:26:59 INFO - PROCESS | 7758 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.70 (2.5%) stddev-sans-first:1.74 17:26:59 INFO - PROCESS | 7758 | Values: 67.0 67.0 67.0 70.0 69.0 67.0 67.0 68.0 72.0 69.0 17:26:59 INFO - PROCESS | 7758 | -------- Summary: end -------- 17:26:59 INFO - PROCESS | 7758 | 17:27:00 INFO - TEST-INFO | 7758: exit 0 17:27:00 INFO - TEST-OK | kraken | took 59106ms 17:27:00 INFO - TEST-START | v8_7 17:27:00 INFO - operating with platform_type : linux_ 17:27:00 INFO - Initialising browser for v8_7 test... 17:27:00 INFO - TEST-INFO | started process 8007 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTopzCL/profile http://localhost:59915/getInfo.html) 17:27:04 INFO - PROCESS | 8007 | __metrics Screen width/height:1600/1200 17:27:04 INFO - PROCESS | 8007 | colorDepth:24 17:27:04 INFO - PROCESS | 8007 | Browser inner width/height: 1024/697 17:27:04 INFO - PROCESS | 8007 | __metrics 17:27:04 INFO - PROCESS | 8007 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:27:04 INFO - PROCESS | 8007 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:27:06 INFO - TEST-INFO | 8007: exit 0 17:27:06 INFO - Browser initialized. 17:27:06 INFO - Running cycle 1/1 for v8_7 test... 17:27:06 INFO - TEST-INFO | started process 8090 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTopzCL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:27:17 INFO - PROCESS | 8090 | RSS: Main: 150081536 17:27:17 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | Cycle 1(1): loaded http://localhost:59915/tests/v8_7/run.html (next: http://localhost:59915/tests/v8_7/run.html) 17:27:39 INFO - PROCESS | 8090 | RSS: Main: 550207488 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | __start_tp_report 17:27:39 INFO - PROCESS | 8090 | _x_x_mozilla_page_load 17:27:39 INFO - PROCESS | 8090 | _x_x_mozilla_page_load_details 17:27:39 INFO - PROCESS | 8090 | |i|pagename|runs| 17:27:39 INFO - PROCESS | 8090 | |0;Richards;167.897918065816 17:27:39 INFO - PROCESS | 8090 | |1;DeltaBlue;197.27756954034325 17:27:39 INFO - PROCESS | 8090 | |2;Encrypt;285.46959748786753 17:27:39 INFO - PROCESS | 8090 | |3;Decrypt;5175.257731958763 17:27:39 INFO - PROCESS | 8090 | |4;RayTrace;1108.6474501108648 17:27:39 INFO - PROCESS | 8090 | |5;Earley;798.0845969672786 17:27:39 INFO - PROCESS | 8090 | |6;Boyer;12537.5 17:27:39 INFO - PROCESS | 8090 | |7;RegExp;26868.42105263158 17:27:39 INFO - PROCESS | 8090 | |8;Splay;599.8807394156231 17:27:39 INFO - PROCESS | 8090 | |9;NavierStokes;4830.917874396136 17:27:39 INFO - PROCESS | 8090 | __end_tp_report 17:27:39 INFO - PROCESS | 8090 | __startTimestamp1451698059478__endTimestamp 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | ------- Summary: start ------- 17:27:39 INFO - PROCESS | 8090 | Number of tests: 10 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#0] Richards Cycles:1 Average:167.90 Median:167.90 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 167.9 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#1] DeltaBlue Cycles:1 Average:197.28 Median:197.28 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 197.3 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#2] Encrypt Cycles:1 Average:285.47 Median:285.47 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 285.5 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 5175.3 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#4] RayTrace Cycles:1 Average:1108.65 Median:1108.65 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 1108.6 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#5] Earley Cycles:1 Average:798.08 Median:798.08 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 798.1 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#6] Boyer Cycles:1 Average:12537.50 Median:12537.50 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 12537.5 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#7] RegExp Cycles:1 Average:26868.42 Median:26868.42 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 26868.4 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#8] Splay Cycles:1 Average:599.88 Median:599.88 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 599.9 17:27:39 INFO - PROCESS | 8090 | 17:27:39 INFO - PROCESS | 8090 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 17:27:39 INFO - PROCESS | 8090 | Values: 4830.9 17:27:39 INFO - PROCESS | 8090 | -------- Summary: end -------- 17:27:39 INFO - PROCESS | 8090 | 17:27:40 INFO - TEST-INFO | 8090: exit 0 17:27:40 INFO - TEST-OK | v8_7 | took 39708ms 17:27:40 INFO - SUITE-END | took 1211s 17:27:40 INFO - Completed test suite (00:20:11) 17:27:40 INFO - Working with test: dromaeo_css 17:27:40 INFO - Generating results file: dromaeo_css 17:27:40 INFO - Working with test: kraken 17:27:40 INFO - Generating results file: kraken 17:27:40 INFO - javascript benchmark 17:27:40 INFO - Working with test: v8_7 17:27:40 INFO - Generating results file: v8_7 17:27:40 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:27:40 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 17:27:40 INFO - process_Request line: dromaeo_css 6845.20 graph.html#tests=[[72,64,35]] 17:27:40 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:27:40 INFO - process_Request line: kraken 1507.30 graph.html#tests=[[232,64,35]] 17:27:40 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:27:40 INFO - process_Request line: v8_7 20744.75 graph.html#tests=[[230,64,35]] 17:27:40 INFO - RETURN: dromaeo_css: 6845.20 17:27:40 INFO - RETURN: kraken: 1507.30 17:27:40 INFO - RETURN: v8_7: 20744.75 17:27:40 INFO - javascript benchmark 17:27:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3092.8298943558125, 3098.4422803895295, 3138.937221255575, 3144.4025635129337, 3172.8413579321027, 5017.698938063719, 5125.077486052513, 5125.410577783556, 5153.046686363021, 5180.792768289269, 5136.563392111666, 5238.292830467885, 5277.1556550951855, 5278.155495120782, 5284.894302113965, 4655.045715628288, 4703.341532185488, 4789.880252993686, 4889.535494128052, 4892.633052521057, 4822.951770482303, 4840.443349014856, 4898.559129678329, 4903.877403064934, 4915.901681966358, 4660.557247061523, 4675.088357770236, 4744.5967092797155, 4771.069641419925, 4775.474697783241, 4669.0, 4679.60223381013, 4684.648651351163, 4696.060787842445, 4717.410323709536, 4651.395318608593, 4681.461631912305, 4697.201475749131, 4698.953010469886, 4713.151632706112, 4972.055309491221, 5023.0, 5062.11413002723, 5117.283580298761, 5151.253068305112, 5539.944600554023, 5593.720313984325, 5597.80407685729, 5605.607607467479, 5606.635568688022, 5485.369182544018, 5604.3835178130375, 5613.46672066153, 5617.410171931929, 5626.887462250732, 5236.764345604457, 5263.289455923439, 5264.210368444726, 5299.814506492255, 5331.226972089025, 5685.090385538294, 5696.515796157373, 5709.571782116358, 5718.971405143031, 5757.712114394263, 5380.435054319319, 5529.364123125812, 5531.225628412026, 5538.501534861882, 5541.0580201365865, 5475.589330800206, 5534.7786088556, 5581.49766521015, 5609.27079479665, 5637.8590535236945, 5567.415421380697, 5567.554595632339, 5567.916481252784, 5569.359523654751, 5582.190582365534, 5425.83722488326, 5535.640183388067, 5609.382967873531, 5610.270664813548, 5647.774089036392, 4263.957360426418, 4333.220020396359, 4340.65274778017, 4365.497967733688, 4371.409859668967, 3876.4379165020914, 3881.5536213335827, 3890.591487893787, 3893.04620368006, 3911.8630847920185, 5176.1200595898035, 5198.350206224222, 5207.19288510274, 5212.4266330703595, 5213.3222681051975, 5423.918641220306, 5525.088360420566, 5528.977139229255, 5580.581456390787, 5655.1517272409465, 5159.638825282269, 5161.638685292069, 5163.66436181647, 5164.638475306692, 5174.689518628894, 4848.12733707936, 4848.684835485682, 4855.077535268288, 4857.101436234308, 4899.314096026559, 5581.386047534768, 5582.441755824466, 5689.999999999917, 5698.2592263005545, 5701.059325211294, 5571.331440227199, 5608.242887210175, 5609.663420194802, 5613.88772224545, 5617.719114044363, 5618.550515958796, 5626.099824028136, 5666.744996475251, 5667.4332566743, 5670.4329567044615, 5407.215953686772, 5504.091824848855, 5505.504504594605, 5531.9723401382735, 5646.2942132233475, 5543.77824887, 5571.359293681278, 5572.749226284908, 5593.356763972195, 5598.972005139948, 5435.10320797064, 5459.7270136492225, 5460.3720572133125, 5462.890742185214, 5510.476504732044, 5322.520973112439, 5421.810236641699, 5425.430329815313, 5425.647332923348, 5553.805616803393, 5374.946250537601, 5380.650257733235, 5381.542568881689, 5387.595930305164, 5388.461153884737, 5480.6437581557075, 5486.149646804752, 5491.560675146058, 5497.835064948058, 5553.222548843084, 5279.577633789211, 5330.120530112488, 5340.49265319794, 5398.568114550748, 5446.1558458439], "unit": "score", "name": "dojo.html", "value": 5141.569625483189}, {"lowerIsBetter": false, "replicates": [499.69518593657875, 508.14631419215726, 512.6565201315118, 523.7800123947942, 536.0699186910707, 27940.720592794067, 28590.85704571477, 35856.2828743425, 36403.817980910055, 36408.27183456329, 39467.407988880186, 39701.60298397015, 39983.200335993264, 40021.39967900477, 40041.0, 7351.70593176275, 8348.29039531639, 8516.74449766508, 8534.57327133644, 8535.914640853574, 7464.178940316561, 7575.204603516634, 7577.204393538682, 7581.3555847753005, 10036.799264014751, 9967.0, 10002.299839011272, 10050.798984020315, 10071.899281007207, 10087.344322619043, 9917.900820991807, 9971.202303815677, 9999.400035997827, 10017.198624110053, 10039.598416063349, 7484.700611975514, 7638.236176382317, 9869.111779939769, 10052.648157314532, 10242.539085741086, 35573.822130889435, 35940.46089308662, 35945.820270898475, 35998.82000590007, 36035.27929441423, 2844.8293102413922, 2929.5166297560877, 2944.926376840575, 3115.704008119225, 3360.2271477560053, 27848.443031139468, 27949.860250698617, 28041.57937630937, 28272.85863570669, 28412.147635570967, 3815.4276858471176, 3955.9011024724323, 4001.0, 4025.8993525162105, 4069.694772892045, 265239.0, 268069.0, 268207.6589617059, 268826.0, 269068.0, 51701.741491292305, 52943.20585191148, 53003.0, 58607.0, 59620.0, 64681.0, 65527.0, 65646.34353656498, 65774.0, 66058.66970665116, 14331.355089021019, 14350.354234059492, 14355.712885742227, 14467.710645787025, 14485.420583176765, 11904.166708330335, 11906.761864762657, 11975.46110425033, 11982.0, 11995.160338776202, 19225.898094296863, 20133.798662013487, 20243.493912652302, 20303.796962030192, 20377.694334584994, 6936.0, 7255.52839065459, 7339.669714862844, 7364.33720965105, 7372.96313518429, 928.967486137982, 955.9378640388353, 1033.2921948465325, 1036.8133735927606, 1043.1446214104512, 28141.5929203539, 28352.291192720346, 28789.0, 28837.855810720812, 28869.27826804347, 983.8376667849868, 1007.7581380468781, 1018.8777346718293, 1038.9948050259852, 1060.3796778884284, 13521.526746563823, 13603.523876664269, 13632.727345453233, 13827.755502004868, 13856.0, 253492.0, 256908.0, 256999.0, 257100.0, 257290.0, 46621.0, 46647.30029049567, 46725.76637116793, 46769.0, 46807.0, 1078.2613909472036, 1223.9510419583116, 1235.950561977511, 1321.73565286943, 1337.7190789934223, 27459.725402745717, 28590.42819143564, 28777.46300295161, 29193.124206273846, 30352.84823575868, 45972.31041534313, 46323.53676463327, 46368.0, 46387.76806115948, 46425.0, 239880.0, 242215.0, 389114.0, 401016.0, 417980.0, 30293.69706302909, 30307.242318942204, 30433.39133217279, 30716.232094197825, 30740.23149421193, 42113.57886421097, 42740.572594274905, 42774.0, 43756.78121609372, 43779.78110109429, 35660.821695891354, 35705.46441803323, 35922.8203858979, 36306.636933630325, 36559.45160822537, 36607.0, 36773.32578927331, 36808.81595592005, 37130.25739485141, 37155.442668360534, 35255.82372088123, 35435.46846797249, 36161.45757813688, 36178.4573231407, 36206.27587448289, 30509.69490305157, 31196.220094497818, 33233.0, 33322.333553329285, 33368.49947250744, 24602.101165373624, 24812.751872481043, 24847.75152248527, 25305.240842774747, 25337.366565836], "unit": "score", "name": "ext.html", "value": 18031.51204475994}, {"lowerIsBetter": false, "replicates": [353.4486201525619, 355.69054922217674, 357.1339501708358, 360.87549795320615, 362.2411048852654, 13997.230152341617, 14031.579052628413, 16576.4198253061, 17280.48158555242, 18150.455486335413, 15399.153046582433, 16053.438129665465, 16244.269007894643, 16309.673806523862, 16457.588560285967, 5177.042247184274, 5307.628466007381, 5322.654027488201, 5325.733713314338, 5329.120695085326, 3939.0546268895546, 4050.51393832739, 5223.947760522403, 5296.523312901838, 5661.2357331760095, 5721.656700597957, 5727.3699893011735, 5766.942330576682, 5774.682392468412, 5788.247527821377, 5611.8597035074245, 5751.4536119068625, 5767.192593036978, 5767.826965191051, 5770.567207459415, 5091.541761241469, 5092.159793634046, 5101.438841727407, 5106.795728170869, 5108.2848401223855, 19232.807671923383, 20316.5936681267, 20561.588768224552, 20824.895875520524, 20864.895675521526, 2180.062573093569, 2256.368216899271, 2318.0869556555017, 2441.8779061047053, 2507.2728908616477, 12808.615741527676, 12970.546030888874, 13059.0, 13114.737868595228, 13383.531576394877, 1769.6637638848576, 1789.4005508154785, 1812.1573468337288, 1854.1841589700489, 1875.1468082022648, 56531.43468565344, 61218.999999999556, 61527.0, 61819.07271390888, 61966.69016654888, 9487.66793162236, 9683.176929960891, 9882.901170988342, 10022.3485473444, 10090.899091008996, 12993.675158121123, 13010.284434356201, 13015.544455943997, 13045.543405980934, 13048.412821423059, 7769.7669069928, 7782.571958542335, 7805.921940780633, 7812.84374312522, 7903.920960790433, 7620.695172193161, 7625.23747625233, 7672.80817979555, 7684.807879802938, 7714.498557593738, 5853.385394533598, 5876.529877609781, 5918.7928422505, 5974.790882319098, 6002.639841609517, 1667.4914151184005, 1703.8892471989282, 1750.0, 1763.4974032400705, 1778.644271145776, 951.3103000324709, 961.4663861556903, 984.9310548261697, 1005.1657124586576, 1020.1430798129607, 16818.495445136665, 18197.636047278982, 18214.908925455555, 18458.538536536693, 18461.907690461463, 899.2985471332371, 916.6654171227455, 927.8376284150247, 940.5532372123323, 985.522021819417, 9649.276304277208, 9800.950995244979, 9843.969798040653, 9848.901510984942, 9860.654877079267, 591134.0, 591392.0, 598316.0, 620733.0, 621732.0, 76277.0, 80072.19927800648, 80209.59895200486, 80904.0, 82879.5856020716, 1138.4137169357787, 1141.2182654881433, 1145.9427028648367, 1147.6614398752388, 1150.9942450287695, 15162.393504259706, 15178.848211517743, 15521.922390388427, 15571.922140389226, 15825.52523424299, 36044.63955360518, 36151.45772813357, 36590.45114323234, 37239.25521489609, 37791.0, 49438.25842612292, 49785.50214497954, 51593.0, 52004.0, 52810.471895280556, 9300.209482193819, 9568.95215523918, 9583.808323833624, 9584.37701549397, 9591.760205994906, 11219.60731374398, 11313.0, 16207.513774586781, 17044.23300951485, 17116.82883171202, 11686.474108664961, 12197.878021220029, 14549.19979401143, 14813.407463701331, 14814.185219812583, 13868.0, 14151.504697335544, 14266.928665356607, 14629.268536573341, 14746.0, 14466.782998255245, 14714.337854796773, 14759.114453132846, 14862.851371486147, 14880.330385132911, 9097.727068187965, 9482.810343792948, 9495.620175192915, 9518.0, 9679.0, 11989.520419183134, 14354.569362919128, 14486.38632356033, 14575.781363279772, 14657.340419681354], "unit": "score", "name": "jquery.html", "value": 9071.050411138765}, {"lowerIsBetter": false, "replicates": [1304.0741073837576, 1414.9787753183705, 1418.4042702065133, 1536.5159974607998, 1767.805541390446, 6673.0, 7319.451041171907, 7455.813604659873, 7455.962720186399, 7471.1408188058385, 7282.0, 7490.4756667033325, 7492.700291988328, 7494.250574942503, 7494.250574942503, 1951.3950675290705, 1985.0471773548704, 1988.9900550497227, 2214.645656694933, 2266.3880752196897, 2223.321886824518, 2266.9886650566723, 2268.920587779428, 2273.8408311418207, 2278.897449614763, 2223.0552015393473, 2263.6151854184745, 2272.704548408713, 2286.9885650571805, 2290.347251033456, 2209.591225623265, 2248.786365295294, 2249.224017713886, 2253.7182852143483, 2280.9201677941273, 2235.765244649305, 2251.8761468119246, 2267.319804058792, 2269.3645779181857, 2277.2485079923586, 7484.513506622106, 7563.886541701879, 7572.772816815449, 7573.734919277799, 7619.466637335388, 4549.909001819979, 4623.722576645412, 4663.694165633628, 4667.253239481701, 4676.462206846189, 2982.0904624089862, 3052.3437460946, 3083.398737246238, 3087.706667866549, 3113.2995076107923, 968.1141755293897, 970.0396607358734, 975.0201047946769, 992.2111921022732, 1022.8516865054526, 10218.693439196835, 10316.845247321371, 10363.585456581728, 10375.636852710195, 10382.480875956171, 4075.083106301118, 4087.713860029829, 4203.306454434984, 4227.281362168439, 4248.915021699549, 8574.22831945128, 8577.356698247657, 8588.914110858812, 8592.8711069334, 8640.30877529796, 4657.6273898088075, 4665.580097791215, 4674.555917187861, 4676.298555216745, 4692.694974826625, 4293.785310734451, 4295.162443323522, 4295.539516564383, 4295.72077814941, 4295.96896744779, 3119.064280715776, 3159.873605055772, 3334.1664583854035, 3389.356022355794, 3446.3968805458944, 1042.8904964978567, 1066.461436974323, 1074.9355038697547, 1076.2573824061374, 1087.9456027198767, 1020.2807021050141, 1026.6560702164709, 1087.8912108789216, 1164.9941750291193, 1329.9867001330056, 6837.931620683729, 6886.10480637524, 6908.965455172692, 6944.965275173592, 6956.79129626112, 600.5945986459122, 618.2550027217193, 623.0747340199799, 623.9251289845248, 761.1466080094987, 5225.166024473077, 5328.067588172132, 5444.0, 5451.373092094381, 5452.863678407992, 8923.107689231025, 9084.636614535477, 9205.907940920504, 9217.953910230406, 9219.492927888896, 9044.321675874337, 9088.318376121817, 9145.862812057825, 9163.541822908961, 9165.954170229239, 6969.337912898267, 7182.96408517954, 7183.281671832775, 7203.711851525985, 7256.818579535448, 6769.729210831709, 6798.7280508779095, 6802.489813263926, 6815.556988795713, 6822.3859852613505, 7380.483366164317, 7550.2072282411755, 7676.6161691916295, 7694.769156925301, 7697.846043078995, 7977.800554986172, 8101.959490202511, 8257.091719910924, 8265.95867020661, 8268.669253229804, 6136.877262454636, 6336.239651241881, 6346.809595712136, 6353.68231588428, 6354.491640668828, 6143.0, 6285.905711414424, 6909.827254318682, 7224.241454647186, 7287.744928927462, 6598.736050557924, 6669.83325416849, 6822.897656535255, 6865.759698410531, 7011.614361210182, 6653.933460665525, 6677.232118306457, 6677.599344039373, 6808.38724514796, 6827.249002609609, 6701.8324541884895, 6750.29121942189, 6755.831104222434, 6757.560758550679, 6761.22245941723, 6304.432601065926, 6334.3665633436285, 6360.459360954371, 6369.14016607752, 6371.139896113966, 6189.412005859436, 6323.873522529616, 6389.393007664265, 6402.743890244525, 6405.551611387158], "unit": "score", "name": "mootools.html", "value": 4255.391757215849}, {"lowerIsBetter": false, "replicates": [319.7122589669297, 327.07274875727313, 331.05153734550515, 337.2378424760042, 350.0496152944755, 5876.0, 5891.057430811065, 5900.173975643408, 5932.525397968163, 5977.163197152402, 5569.220309156722, 5702.657840529571, 5742.339630942442, 5840.649561026341, 5867.295924489056, 4534.97732511337, 4538.432695913011, 4547.272436410175, 4551.544845515438, 4557.475890272612, 4346.217680817452, 4549.567791059844, 4552.8861778455475, 4561.635069194456, 4570.360149579045, 4383.0, 4410.580994805488, 4508.77456127194, 4516.029053753442, 4523.660725445605, 4494.303382975644, 4505.3917721107555, 4510.0979804039325, 4510.345999830022, 4513.729176249419, 4027.939580906289, 4044.69664775143, 4044.8786536403954, 4044.8988775280854, 4049.5545489996075, 5522.447755224485, 5620.57845661577, 5623.634463759883, 5624.746886390122, 5641.68970706611, 5319.308489896327, 5325.866853328659, 5325.920111198335, 5329.307190065306, 5330.893382132335, 6213.502919766408, 6222.782202622887, 6226.0, 6228.5017198624, 6231.189945307126, 4485.1029794041, 4488.932666010012, 4489.73061616304, 4492.337835001038, 4498.775061246957, 6270.184875966095, 6473.579217350904, 6490.091387205795, 6498.252700939405, 6505.0892874997535, 4576.359309696645, 4579.771011449414, 4583.579090481961, 4604.30935359692, 4611.469680986729, 4668.4631267404, 4670.719756814534, 4672.299155126758, 4681.250999840009, 4685.8594242172785, 5314.335708036496, 5318.149096144675, 5318.840434786963, 5324.707141107277, 5330.760115794798, 6109.6639684817765, 6237.126802247689, 6244.063390491463, 6250.343713910065, 6356.491480681534, 1567.0910871694389, 1567.9451219207274, 1567.9843201567837, 1568.2080549322518, 1568.9529314120596, 1045.257866914484, 1056.6671498477954, 1056.6829951014665, 1062.1555863088863, 1077.902988731018, 5916.970415147896, 5920.230370051866, 5923.703814809329, 5928.466438020512, 5938.435848594377, 6457.257415397289, 6466.6766661666725, 6466.902996455057, 6469.935300647027, 6490.383413575702, 5721.141828725725, 5884.705764711747, 5886.58793884424, 5889.823305300848, 5896.233489646319, 3308.1894935740897, 3448.2413868948793, 3475.461303497962, 3508.7894726316504, 3508.807015614115, 6560.475161987029, 6563.7702680405955, 6646.335366463315, 6658.633775142317, 6662.633555154416, 6694.397504224643, 6695.531312808154, 6704.296048914891, 6712.597244165366, 6714.630695311707, 6479.708413121421, 6558.0, 6608.570442921195, 6614.0740296358545, 6615.669216539154, 6485.643289619116, 6505.349465053457, 6510.31641677636, 6513.381228783258, 6513.7068831903625, 6304.148939893055, 6399.424051835357, 6426.775062872777, 6427.357264273535, 6436.871262574816, 6359.3640635936035, 6539.901901471387, 6553.967230163818, 6555.311692272434, 6599.637019963948, 4399.472063352418, 4427.756473393994, 4433.490148632948, 4435.689501734848, 4438.60052595268, 6435.485161187, 6440.774572889926, 6511.251206111168, 6515.73937042513, 6517.641529715911, 6204.81385558434, 6326.398992095744, 6326.652034137983, 6358.0780786784735, 6359.713812878525, 6337.207849018872, 6341.682915854097, 6345.302016778243, 6346.650934198663, 6350.206224221973, 6236.875262494634, 6239.906401403891, 6242.219722534684, 6248.187735594343, 6248.4376406123665, 6256.749730010749, 6362.459190968634, 6378.521610879296, 6379.74481020754, 6383.776567820104, 6209.379062093935, 6217.96891015542, 6222.191115155001, 6224.47500362396, 6225.875482490415], "unit": "score", "name": "prototype.html", "value": 4738.524368658028}, {"lowerIsBetter": false, "replicates": [691.3812138136368, 833.7457075591944, 845.8646616541355, 852.2329903087225, 859.5702148925537, 13220.933895330521, 13442.126261792977, 13444.865551344483, 13457.865421345783, 13469.865301346983, 11941.0, 12000.759984800321, 12008.699782505442, 12012.399380031007, 12028.097892658065, 669.4577392312231, 677.8847595908683, 806.4677312973439, 819.5533434278303, 821.2116368286422, 817.0522194254665, 817.5707753429438, 824.3940703582862, 824.8474032304013, 825.0099880143821, 858.3133493205411, 862.2843040276556, 863.2144748279068, 863.8833757442727, 864.658459908339, 689.9172099348085, 696.2271878215178, 836.180543067797, 858.0132847225678, 858.3733874271754, 631.3497097989078, 632.137132813706, 632.2192092765431, 632.4497687012283, 632.6330728177692, 12299.569515066929, 12367.257964522158, 12386.256824590553, 12495.125341226118, 12563.623091307183, 11661.358625275607, 11693.239939403997, 11829.526818927232, 12029.578964736193, 12035.338056406894, 10656.946715266451, 10677.626283080133, 10677.83983240252, 10730.731731706692, 10758.408287544182, 3597.784132952031, 3671.0, 3682.852685892588, 3724.1434470071763, 3757.1734218472166, 45959.0, 46133.30800038002, 46256.3061554077, 46420.30369544459, 46601.30098048532, 10141.847872281922, 10176.236782241362, 10212.540435680412, 10275.280730348954, 10302.793944121226, 18984.240630374905, 19048.42854714361, 19107.80892191088, 19109.713354299696, 19123.90438047801, 7154.856902861914, 7277.3086556777025, 7320.267973202637, 7387.9261207387235, 7558.093028836574, 4251.744895306291, 4302.720323178984, 4338.501072376654, 4361.0, 4385.868423947223, 2885.9033567244314, 2929.6484421869513, 2930.8094973826633, 2931.5016447204025, 2936.0898121582377, 588.6203398807745, 596.8388535095587, 598.7245866901275, 609.9085137229363, 619.5632079384023, 571.9256496655491, 586.4428792647002, 587.5974957154363, 612.5895649914568, 631.36232405271, 7532.300193276774, 7789.182135875765, 7802.219778022266, 7948.841023179504, 7974.800629984296, 482.1273494974108, 519.6232731269799, 551.9503244707915, 579.3164066401603, 641.9215717594393, 3400.285939952638, 3484.337975784593, 3492.877749278763, 3535.611082780892, 3662.249238906018, 53544.0, 53727.46272537224, 54184.18723719147, 54308.728456357465, 55520.44479555153, 49323.50676493261, 51750.0, 51933.0, 52150.739246304285, 52359.214611780095, 43990.56009439928, 44052.55947440484, 44104.558954410684, 44685.55314446814, 45074.54925450769, 7308.415326773952, 7308.648866870511, 7376.114866216089, 7458.589777562069, 7517.285857843496, 32404.67595324111, 32929.341413171125, 33477.497837532945, 33642.0, 34179.14552136217, 38617.0, 38661.03347416337, 45436.091278174914, 45503.31745023894, 48443.757781210865, 10212.387256764618, 10327.638532651321, 10500.267455870002, 10668.35989840581, 10675.252732308663, 11456.312621242752, 11527.654170374903, 11882.881171188523, 11957.820632690691, 12018.0, 10548.314359566604, 10648.414337211529, 10681.092107170978, 11076.169287303646, 11105.222634415513, 10548.84176737365, 11021.559137634405, 11032.0, 11062.280951738434, 11153.442327883411, 9725.221982241545, 9981.351212171186, 10646.148308135176, 10658.680239592824, 10704.09015233714, 8672.436291641023, 8844.646214151362, 9011.324150688593, 9501.097395747393, 9697.951510242403, 8419.95790021046, 8485.193906578865, 8776.297896168164, 9154.40496367734, 9195.172434480928], "unit": "score", "name": "yui.html", "value": 6066.087249888177}], "name": "dromaeo_css", "value": 6845.2025425619}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 111.0, 111.0, 110.0, 99.0, 111.0, 110.0, 104.0], "unit": "score", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [145.0, 126.0, 156.0, 175.0, 126.0, 151.0, 149.0, 126.0, 165.0, 133.0], "unit": "score", "name": "audio-beat-detection", "value": 145.2}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 161.0, 164.0, 163.0, 159.0, 159.0, 161.0, 160.0], "unit": "score", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 80.0, 95.0, 74.0, 73.0, 72.0, 98.0, 88.0, 83.0], "unit": "score", "name": "audio-fft", "value": 80.8}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 150.0, 96.0, 96.0, 96.0, 142.0, 96.0, 96.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 110.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 58.0, 56.0, 59.0, 57.0, 62.0, 67.0, 54.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 58.4}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [94.0, 86.0, 86.0, 94.0, 92.0, 86.0, 85.0, 85.0, 84.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.8}, {"lowerIsBetter": true, "replicates": [119.0, 122.0, 117.0, 124.0, 125.0, 115.0, 136.0, 121.0, 115.0, 130.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.4}, {"lowerIsBetter": true, "replicates": [141.0, 155.0, 150.0, 151.0, 145.0, 143.0, 150.0, 149.0, 150.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.4}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 70.0, 69.0, 67.0, 67.0, 68.0, 72.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1507.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.897918065816], "unit": "score", "name": "Richards", "value": 210.25871200000006}, {"lowerIsBetter": false, "replicates": [197.27756954034325], "unit": "score", "name": "DeltaBlue", "value": 335.15214199999997}, {"lowerIsBetter": false, "replicates": [285.46959748786753], "unit": "score", "name": "Encrypt", "value": 932.4320429999997}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1108.6474501108648], "unit": "score", "name": "RayTrace", "value": 667.4700779999998}, {"lowerIsBetter": false, "replicates": [798.0845969672786], "unit": "score", "name": "Earley", "value": 835.078139}, {"lowerIsBetter": false, "replicates": [12537.5], "unit": "score", "name": "Boyer", "value": 53.15756729810566}, {"lowerIsBetter": false, "replicates": [26868.42105263158], "unit": "score", "name": "RegExp", "value": 33.90541625857002}, {"lowerIsBetter": false, "replicates": [599.8807394156231], "unit": "score", "name": "Splay", "value": 135.8453349900597}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20744.753347797065}]} 17:27:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20744.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1507.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6845.20"}}} 17:27:40 INFO - Return code: 0 17:27:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 17:27:40 INFO - # TBPL SUCCESS # 17:27:40 INFO - Running post-action listener: _resource_record_post_action 17:27:40 INFO - Running post-run listener: _resource_record_post_run 17:27:41 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 16384; Write bytes: 570523648; Read time: 156; Write time: 467280 17:27:41 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 251027456; Read time: 136; Write time: 299364 17:27:41 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 12288; Write bytes: 319496192; Read time: 20; Write time: 167916 17:27:41 INFO - Running post-run listener: _upload_blobber_files 17:27:41 INFO - Blob upload gear active. 17:27:41 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 17:27:41 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 17:27:41 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', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 17:27:41 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 Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 17:27:42 INFO - (blobuploader) - INFO - Open directory for files ... 17:27:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:27:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:27:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:43 INFO - (blobuploader) - INFO - Done attempting. 17:27:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 17:27:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:27:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:44 INFO - (blobuploader) - INFO - Done attempting. 17:27:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:27:44 INFO - Return code: 0 17:27:44 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 17:27:44 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 17:27:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a261928a857ab946c620b3a099e0340dbcd7008a4ee5647ee4d82c2f92e485df0b32e5c5b493f00a2bbae2e2d6a4ac69e4208e3fa12b1f0cb048308f373b0fcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04a19906171242be8acfd15952ad7c641a60dd7db8e7795b8aefa806917852a6e3af0ed5e127a5383d08d454a04927ed216931eaedfcd7f748ab8e937f5da2be"} 17:27:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 17:27:44 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 17:27:44 INFO - Contents: 17:27:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a261928a857ab946c620b3a099e0340dbcd7008a4ee5647ee4d82c2f92e485df0b32e5c5b493f00a2bbae2e2d6a4ac69e4208e3fa12b1f0cb048308f373b0fcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04a19906171242be8acfd15952ad7c641a60dd7db8e7795b8aefa806917852a6e3af0ed5e127a5383d08d454a04927ed216931eaedfcd7f748ab8e937f5da2be"} 17:27:44 INFO - Copying logs to upload dir... 17:27:44 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.403942 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-01 17:27:43.855444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-01 17:27:43.858726) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a261928a857ab946c620b3a099e0340dbcd7008a4ee5647ee4d82c2f92e485df0b32e5c5b493f00a2bbae2e2d6a4ac69e4208e3fa12b1f0cb048308f373b0fcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04a19906171242be8acfd15952ad7c641a60dd7db8e7795b8aefa806917852a6e3af0ed5e127a5383d08d454a04927ed216931eaedfcd7f748ab8e937f5da2be"} build_url:https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/GtiUzhATTKiVHDiysc_sCA/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/Fx-Team/sha512/a261928a857ab946c620b3a099e0340dbcd7008a4ee5647ee4d82c2f92e485df0b32e5c5b493f00a2bbae2e2d6a4ac69e4208e3fa12b1f0cb048308f373b0fcc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04a19906171242be8acfd15952ad7c641a60dd7db8e7795b8aefa806917852a6e3af0ed5e127a5383d08d454a04927ed216931eaedfcd7f748ab8e937f5da2be"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-01 17:27:43.883259) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:27:43.883596) ========= 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=6ef107882637bc5fc02d78b7000001c2-1451696798.65010-1905431798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-01 17:27:43.968849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-01 17:27:43.969155) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-01 17:27:43.969419) ========= ========= Total master_lag: 0.27 =========