builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1452059962.36 results: success (0) buildid: 20160105193124 builduid: d78abdb8e90f4387ba494b8a502142ce revision: b2ec3d6497157b97c59dc45274a1406653a7d197 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.359093) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.359637) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.359951) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006206 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.382905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.383259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.397457) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.397878) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004602 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.449787) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.450107) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.450490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.450787) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-05 21:59:22-- 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% 223M=0s 2016-01-05 21:59:22 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165499 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.628633) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.628960) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021530 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.678119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:22.678482) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b2ec3d6497157b97c59dc45274a1406653a7d197 --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/mozilla-inbound --rev b2ec3d6497157b97c59dc45274a1406653a7d197 --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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-05 21:59:23,076 truncating revision to first 12 chars 2016-01-05 21:59:23,076 Setting DEBUG logging. 2016-01-05 21:59:23,076 attempt 1/10 2016-01-05 21:59:23,076 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/b2ec3d649715?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-05 21:59:23,337 unpacking tar archive at: mozilla-inbound-b2ec3d649715/testing/mozharness/ program finished with exit code 0 elapsedTime=0.510413 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:23.202231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:23.202602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:23.212464) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:23.212874) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 21:59:23.213349) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-05 21:59:23.213667) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:59:23 INFO - MultiFileLogger online at 20160105 21:59:23 in /builds/slave/test-pgo 21:59:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 21:59:23 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:59:23 INFO - {'append_to_log': False, 21:59:23 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:59:23 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 21:59:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:59:23 INFO - 'branch': 'Mozilla-Inbound', 21:59:23 INFO - 'buildbot_json_path': 'buildprops.json', 21:59:23 INFO - 'config_files': ('talos/linux_config.py',), 21:59:23 INFO - 'default_actions': ('clobber', 21:59:23 INFO - 'read-buildbot-config', 21:59:23 INFO - 'download-and-extract', 21:59:23 INFO - 'populate-webroot', 21:59:23 INFO - 'create-virtualenv', 21:59:23 INFO - 'install', 21:59:23 INFO - 'run-tests'), 21:59:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:59:23 INFO - 'download_minidump_stackwalk': True, 21:59:23 INFO - 'download_symbols': 'ondemand', 21:59:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:59:23 INFO - 'tooltool.py': '/tools/tooltool.py', 21:59:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:59:23 INFO - '/tools/misc-python/virtualenv.py')}, 21:59:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:59:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:59:23 INFO - 'installer_path': 'installer.exe', 21:59:23 INFO - 'log_level': 'info', 21:59:23 INFO - 'log_name': 'talos', 21:59:23 INFO - 'log_to_console': True, 21:59:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:59:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:59:23 INFO - 'opt_config_files': (), 21:59:23 INFO - 'pip_index': False, 21:59:23 INFO - 'sps_profile': False, 21:59:23 INFO - 'sps_profile_interval': 0, 21:59:23 INFO - 'suite': 'dromaeojs', 21:59:23 INFO - 'system_bits': '32', 21:59:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:59:23 INFO - 'title': 'talos-linux64-ix-034', 21:59:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:59:23 INFO - 'use_talos_json': True, 21:59:23 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:59:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:59:23 INFO - 'work_dir': 'build'} 21:59:23 INFO - ##### 21:59:23 INFO - ##### Running clobber step. 21:59:23 INFO - ##### 21:59:23 INFO - Running pre-action listener: _resource_record_pre_action 21:59:23 INFO - Running main action method: clobber 21:59:23 INFO - rmtree: /builds/slave/test-pgo/build 21:59:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:59:24 INFO - Running post-action listener: _resource_record_post_action 21:59:24 INFO - ##### 21:59:24 INFO - ##### Running read-buildbot-config step. 21:59:24 INFO - ##### 21:59:24 INFO - Running pre-action listener: _resource_record_pre_action 21:59:24 INFO - Running main action method: read_buildbot_config 21:59:24 INFO - Using buildbot properties: 21:59:24 INFO - { 21:59:24 INFO - "project": "", 21:59:24 INFO - "product": "firefox", 21:59:24 INFO - "script_repo_revision": "production", 21:59:24 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 21:59:24 INFO - "repository": "", 21:59:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 21:59:24 INFO - "buildid": "20160105193124", 21:59:24 INFO - "pgo_build": "True", 21:59:24 INFO - "basedir": "/builds/slave/test-pgo", 21:59:24 INFO - "buildnumber": 597, 21:59:24 INFO - "slavename": "talos-linux64-ix-034", 21:59:24 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:59:24 INFO - "platform": "ubuntu64_hw", 21:59:24 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 21:59:24 INFO - "branch": "mozilla-inbound", 21:59:24 INFO - "repo_path": "integration/mozilla-inbound", 21:59:24 INFO - "revision": "b2ec3d6497157b97c59dc45274a1406653a7d197", 21:59:24 INFO - "stage_platform": "linux64-pgo", 21:59:24 INFO - "builduid": "d78abdb8e90f4387ba494b8a502142ce", 21:59:24 INFO - "slavebuilddir": "test" 21:59:24 INFO - } 21:59:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:59:24 INFO - Running post-action listener: _resource_record_post_action 21:59:24 INFO - ##### 21:59:24 INFO - ##### Running download-and-extract step. 21:59:24 INFO - ##### 21:59:24 INFO - Running pre-action listener: _resource_record_pre_action 21:59:24 INFO - Running main action method: download_and_extract 21:59:24 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:59:24 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')]} 21:59:24 INFO - https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:59:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/test_packages.json 21:59:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/test_packages.json 21:59:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:59:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:59:24 INFO - Downloaded 1302 bytes. 21:59:24 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:59:24 INFO - Using the following test package requirements: 21:59:24 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:24 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:59:24 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'jsshell-linux-x86_64.zip'], 21:59:24 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:59:24 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:24 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:59:24 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:59:24 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:59:24 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:59:24 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:59:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:59:24 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:59:24 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')]} 21:59:24 INFO - https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:25 INFO - Downloaded 24058709 bytes. 21:59:25 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 21:59:25 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:25 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 21:59:26 INFO - Return code: 0 21:59:26 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 21:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:26 INFO - https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:27 INFO - Downloaded 24058709 bytes. 21:59:27 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 21:59:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:59:27 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 21:59:29 INFO - Return code: 0 21:59:29 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')]} 21:59:29 INFO - https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:59:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:30 INFO - Downloaded 11132285 bytes. 21:59:30 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 21:59:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:59:30 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 21:59:30 INFO - Return code: 0 21:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:30 INFO - https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:59:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:59:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:59:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d-5R8pj5T8mxwR-yyL61nw/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 21:59:31 INFO - Downloaded 55044693 bytes. 21:59:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:59:31 INFO - mkdir: /builds/slave/test-pgo/properties 21:59:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:59:31 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:59:31 INFO - Contents: 21:59:31 INFO - build_url:https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:59:31 INFO - Running post-action listener: _resource_record_post_action 21:59:31 INFO - Running post-action listener: set_extra_try_arguments 21:59:31 INFO - ##### 21:59:31 INFO - ##### Running populate-webroot step. 21:59:31 INFO - ##### 21:59:31 INFO - Running pre-action listener: _resource_record_pre_action 21:59:31 INFO - Running main action method: populate_webroot 21:59:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:31 INFO - '--tppagecycles', 21:59:31 INFO - '7'], 21:59:31 INFO - 'tests': ['tsvgm']}}, 21:59:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:59:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:59:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:59:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'tests': ['damp', 'tps']}, 21:59:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['damp', 'tps']}, 21:59:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:59:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:31 INFO - 'other': {'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'other_l64': {'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'other_nol64': {'tests': ['a11yr', 21:59:31 INFO - 'ts_paint', 21:59:31 INFO - 'tpaint', 21:59:31 INFO - 'sessionrestore', 21:59:31 INFO - 'sessionrestore_no_auto_restore']}, 21:59:31 INFO - 'svgr': {'tests': ['tsvgx', 21:59:31 INFO - 'tsvgr_opacity', 21:59:31 INFO - 'tart', 21:59:31 INFO - 'tscrollx', 21:59:31 INFO - 'cart']}, 21:59:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['tsvgx', 21:59:31 INFO - 'tsvgr_opacity', 21:59:31 INFO - 'tart', 21:59:31 INFO - 'tscrollx', 21:59:31 INFO - 'cart']}, 21:59:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'tests': ['tp5o']}, 21:59:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'talos_options': ['--e10s'], 21:59:31 INFO - 'tests': ['tp5o']}, 21:59:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'talos_options': ['--xperf_path', 21:59:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:31 INFO - 'tests': ['tp5n']}, 21:59:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:31 INFO - 'talos_options': ['--e10s', 21:59:31 INFO - '--xperf_path', 21:59:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:31 INFO - 'tests': ['tp5n']}}, 21:59:31 INFO - 'talos.zip': {'path': '', 21:59:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:31 INFO - Running post-action listener: _resource_record_post_action 21:59:31 INFO - ##### 21:59:31 INFO - ##### Running create-virtualenv step. 21:59:31 INFO - ##### 21:59:31 INFO - Running pre-action listener: _resource_record_pre_action 21:59:31 INFO - Running main action method: create_virtualenv 21:59:31 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:59:31 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 21:59:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:59:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:31 INFO - Using real prefix '/usr' 21:59:31 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:59:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:59:34 INFO - Installing pip.................done. 21:59:34 INFO - Return code: 0 21:59:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:59:34 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')]} 21:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 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')]} 21:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:34 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 21:59:34 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 21:59:34 INFO - Using env: {'DISPLAY': ':0', 21:59:34 INFO - 'HOME': '/home/cltbld', 21:59:34 INFO - 'LANG': 'en_US.UTF-8', 21:59:34 INFO - 'LANGUAGE': 'en_US:en', 21:59:34 INFO - 'LOGNAME': 'cltbld', 21:59:34 INFO - 'MAIL': '/var/mail/cltbld', 21:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:34 INFO - 'MOZ_NO_REMOTE': '1', 21:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:34 INFO - 'NO_EM_RESTART': '1', 21:59:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:34 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:34 INFO - 'SHELL': '/bin/bash', 21:59:34 INFO - 'SHLVL': '1', 21:59:34 INFO - 'TERM': 'linux', 21:59:34 INFO - 'TMOUT': '86400', 21:59:34 INFO - 'USER': 'cltbld', 21:59:34 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:34 INFO - '_': '/tools/buildbot/bin/python'} 21:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:34 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 21:59:34 INFO - Cleaning up... 21:59:34 INFO - Return code: 0 21:59:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:59:34 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')]} 21:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 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')]} 21:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:34 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:34 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 21:59:34 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 21:59:34 INFO - Using env: {'DISPLAY': ':0', 21:59:34 INFO - 'HOME': '/home/cltbld', 21:59:34 INFO - 'LANG': 'en_US.UTF-8', 21:59:34 INFO - 'LANGUAGE': 'en_US:en', 21:59:34 INFO - 'LOGNAME': 'cltbld', 21:59:34 INFO - 'MAIL': '/var/mail/cltbld', 21:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:34 INFO - 'MOZ_NO_REMOTE': '1', 21:59:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:34 INFO - 'NO_EM_RESTART': '1', 21:59:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:34 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:34 INFO - 'SHELL': '/bin/bash', 21:59:34 INFO - 'SHLVL': '1', 21:59:34 INFO - 'TERM': 'linux', 21:59:34 INFO - 'TMOUT': '86400', 21:59:34 INFO - 'USER': 'cltbld', 21:59:34 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:34 INFO - '_': '/tools/buildbot/bin/python'} 21:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:34 INFO - Downloading/unpacking psutil>=0.7.1 21:59:34 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 21:59:34 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 21:59:34 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 21:59:34 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 21:59:34 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 21:59:34 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 21:59:36 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:59:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:59:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:36 INFO - Installing collected packages: psutil 21:59:36 INFO - Running setup.py install for psutil 21:59:36 INFO - building 'psutil._psutil_linux' extension 21:59:36 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 21:59:36 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 21:59:36 INFO - building 'psutil._psutil_posix' extension 21:59:36 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 21:59:36 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 21:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:59:36 INFO - Successfully installed psutil 21:59:36 INFO - Cleaning up... 21:59:36 INFO - Return code: 0 21:59:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:36 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:36 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 21:59:36 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 21:59:36 INFO - Using env: {'DISPLAY': ':0', 21:59:36 INFO - 'HOME': '/home/cltbld', 21:59:36 INFO - 'LANG': 'en_US.UTF-8', 21:59:36 INFO - 'LANGUAGE': 'en_US:en', 21:59:36 INFO - 'LOGNAME': 'cltbld', 21:59:36 INFO - 'MAIL': '/var/mail/cltbld', 21:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:36 INFO - 'MOZ_NO_REMOTE': '1', 21:59:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:36 INFO - 'NO_EM_RESTART': '1', 21:59:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:36 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:36 INFO - 'SHELL': '/bin/bash', 21:59:36 INFO - 'SHLVL': '1', 21:59:36 INFO - 'TERM': 'linux', 21:59:36 INFO - 'TMOUT': '86400', 21:59:36 INFO - 'USER': 'cltbld', 21:59:36 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:36 INFO - '_': '/tools/buildbot/bin/python'} 21:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:37 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 21:59:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:59:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:59:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:59:38 INFO - Installing collected packages: mozsystemmonitor 21:59:38 INFO - Running setup.py install for mozsystemmonitor 21:59:38 INFO - Successfully installed mozsystemmonitor 21:59:38 INFO - Cleaning up... 21:59:39 INFO - Return code: 0 21:59:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:39 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:39 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 21:59:39 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 21:59:39 INFO - Using env: {'DISPLAY': ':0', 21:59:39 INFO - 'HOME': '/home/cltbld', 21:59:39 INFO - 'LANG': 'en_US.UTF-8', 21:59:39 INFO - 'LANGUAGE': 'en_US:en', 21:59:39 INFO - 'LOGNAME': 'cltbld', 21:59:39 INFO - 'MAIL': '/var/mail/cltbld', 21:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:39 INFO - 'MOZ_NO_REMOTE': '1', 21:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:39 INFO - 'NO_EM_RESTART': '1', 21:59:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:39 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:39 INFO - 'SHELL': '/bin/bash', 21:59:39 INFO - 'SHLVL': '1', 21:59:39 INFO - 'TERM': 'linux', 21:59:39 INFO - 'TMOUT': '86400', 21:59:39 INFO - 'USER': 'cltbld', 21:59:39 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:39 INFO - '_': '/tools/buildbot/bin/python'} 21:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:39 INFO - Downloading/unpacking blobuploader==1.2.4 21:59:39 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 21:59:39 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 21:59:39 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 21:59:39 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 21:59:39 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 21:59:39 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 21:59:40 INFO - Downloading blobuploader-1.2.4.tar.gz 21:59:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:59:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:59:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:59:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:59:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 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 21:59:41 INFO - Downloading docopt-0.6.1.tar.gz 21:59:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:59:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:59:41 INFO - Installing collected packages: blobuploader, requests, docopt 21:59:41 INFO - Running setup.py install for blobuploader 21:59:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:59:41 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:59:41 INFO - Running setup.py install for requests 21:59:42 INFO - Running setup.py install for docopt 21:59:42 INFO - Successfully installed blobuploader requests docopt 21:59:42 INFO - Cleaning up... 21:59:42 INFO - Return code: 0 21:59:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:59:42 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')]} 21:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:42 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')]} 21:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:42 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:42 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 21:59:42 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 21:59:42 INFO - Using env: {'DISPLAY': ':0', 21:59:42 INFO - 'HOME': '/home/cltbld', 21:59:42 INFO - 'LANG': 'en_US.UTF-8', 21:59:42 INFO - 'LANGUAGE': 'en_US:en', 21:59:42 INFO - 'LOGNAME': 'cltbld', 21:59:42 INFO - 'MAIL': '/var/mail/cltbld', 21:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:42 INFO - 'MOZ_NO_REMOTE': '1', 21:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:42 INFO - 'NO_EM_RESTART': '1', 21:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:42 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:42 INFO - 'SHELL': '/bin/bash', 21:59:42 INFO - 'SHLVL': '1', 21:59:42 INFO - 'TERM': 'linux', 21:59:42 INFO - 'TMOUT': '86400', 21:59:42 INFO - 'USER': 'cltbld', 21:59:42 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:42 INFO - '_': '/tools/buildbot/bin/python'} 21:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:59:42 INFO - Running setup.py (path:/tmp/pip-ZKO3iZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:59:42 INFO - Running setup.py (path:/tmp/pip-X1FVFR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:59:42 INFO - Running setup.py (path:/tmp/pip-WpTVJe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:59:42 INFO - Running setup.py (path:/tmp/pip-fRwKJU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:59:42 INFO - Running setup.py (path:/tmp/pip-eVqtak-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:59:42 INFO - Running setup.py (path:/tmp/pip-iIuk2p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:59:42 INFO - Running setup.py (path:/tmp/pip-swacZp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:59:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:59:42 INFO - Running setup.py (path:/tmp/pip-hVhC8e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:59:43 INFO - Running setup.py (path:/tmp/pip-wPOTL1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:59:43 INFO - Running setup.py (path:/tmp/pip-xw6x59-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:59:43 INFO - Running setup.py (path:/tmp/pip-XoDdqO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:59:43 INFO - Running setup.py (path:/tmp/pip-6d6EEE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:59:43 INFO - Running setup.py (path:/tmp/pip-PeXyLZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:59:43 INFO - Running setup.py (path:/tmp/pip-nIKTBh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:59:43 INFO - Running setup.py (path:/tmp/pip-BTYheB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:59:43 INFO - Running setup.py (path:/tmp/pip-ygnB3_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:59:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:59:43 INFO - Running setup.py (path:/tmp/pip-_WAdqJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:59:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:59:43 INFO - Running setup.py install for manifestparser 21:59:43 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:59:43 INFO - Running setup.py install for mozcrash 21:59:43 INFO - Running setup.py install for mozdebug 21:59:43 INFO - Running setup.py install for mozdevice 21:59:44 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for mozfile 21:59:44 INFO - Running setup.py install for mozhttpd 21:59:44 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for mozinfo 21:59:44 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for mozInstall 21:59:44 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for mozleak 21:59:44 INFO - Running setup.py install for mozlog 21:59:44 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for moznetwork 21:59:44 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Running setup.py install for mozprocess 21:59:44 INFO - Running setup.py install for mozprofile 21:59:44 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:59:44 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:59:45 INFO - Running setup.py install for mozrunner 21:59:45 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:59:45 INFO - Running setup.py install for mozscreenshot 21:59:45 INFO - Running setup.py install for moztest 21:59:45 INFO - Running setup.py install for mozversion 21:59:45 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:59:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:59:45 INFO - Cleaning up... 21:59:45 INFO - Return code: 0 21:59:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:59: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')]} 21:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59: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')]} 21:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:45 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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:45 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 21:59:45 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 21:59:45 INFO - Using env: {'DISPLAY': ':0', 21:59:45 INFO - 'HOME': '/home/cltbld', 21:59:45 INFO - 'LANG': 'en_US.UTF-8', 21:59:45 INFO - 'LANGUAGE': 'en_US:en', 21:59:45 INFO - 'LOGNAME': 'cltbld', 21:59:45 INFO - 'MAIL': '/var/mail/cltbld', 21:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:45 INFO - 'MOZ_NO_REMOTE': '1', 21:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:45 INFO - 'NO_EM_RESTART': '1', 21:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:45 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:45 INFO - 'SHELL': '/bin/bash', 21:59:45 INFO - 'SHLVL': '1', 21:59:45 INFO - 'TERM': 'linux', 21:59:45 INFO - 'TMOUT': '86400', 21:59:45 INFO - 'USER': 'cltbld', 21:59:45 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:45 INFO - '_': '/tools/buildbot/bin/python'} 21:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:59:45 INFO - Running setup.py (path:/tmp/pip-EByUxO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:59:45 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)) 21:59:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:59:45 INFO - Running setup.py (path:/tmp/pip-_4UVJH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:59:45 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)) 21:59:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:59:45 INFO - Running setup.py (path:/tmp/pip-OX5YVY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:59:45 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)) 21:59:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:59:45 INFO - Running setup.py (path:/tmp/pip-ic3B3J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:59:45 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)) 21:59:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:59:45 INFO - Running setup.py (path:/tmp/pip-a4vMT_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:59:46 INFO - Running setup.py (path:/tmp/pip-4YKsh9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:59:46 INFO - Running setup.py (path:/tmp/pip-TGiSzm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:59:46 INFO - Running setup.py (path:/tmp/pip-o4hitm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:59:46 INFO - Running setup.py (path:/tmp/pip-EUUGCt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:59:46 INFO - Running setup.py (path:/tmp/pip-I5OqHP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:59:46 INFO - Running setup.py (path:/tmp/pip-iGxiIp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:59:46 INFO - Running setup.py (path:/tmp/pip-XIkBVx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:59:46 INFO - Running setup.py (path:/tmp/pip-dw8RPe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:59:46 INFO - Running setup.py (path:/tmp/pip-dGIosW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:59:46 INFO - Running setup.py (path:/tmp/pip-T_rn_k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:59:46 INFO - Running setup.py (path:/tmp/pip-sqo20S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:59:46 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)) 21:59:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:59:46 INFO - Running setup.py (path:/tmp/pip-XS0uyP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:59:46 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)) 21:59:46 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)) 21:59:46 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)) 21:59:46 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)) 21:59:46 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)) 21:59:46 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)) 21:59:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:59:46 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 21:59:46 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 21:59:46 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 21:59:46 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 21:59:46 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 21:59:46 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 21:59:48 INFO - Downloading blessings-1.5.1.tar.gz 21:59:48 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 21:59:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:59:48 INFO - Installing collected packages: blessings 21:59:48 INFO - Running setup.py install for blessings 21:59:48 INFO - Successfully installed blessings 21:59:48 INFO - Cleaning up... 21:59:48 INFO - Return code: 0 21:59:48 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:59:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:59:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:59:49 INFO - Reading from file tmpfile_stdout 21:59:49 INFO - Current package versions: 21:59:49 INFO - argparse == 1.2.1 21:59:49 INFO - blessings == 1.5.1 21:59:49 INFO - blobuploader == 1.2.4 21:59:49 INFO - docopt == 0.6.1 21:59:49 INFO - manifestparser == 1.1 21:59:49 INFO - mozInstall == 1.12 21:59:49 INFO - mozcrash == 0.16 21:59:49 INFO - mozdebug == 0.1 21:59:49 INFO - mozdevice == 0.47 21:59:49 INFO - mozfile == 1.2 21:59:49 INFO - mozhttpd == 0.7 21:59:49 INFO - mozinfo == 0.9 21:59:49 INFO - mozleak == 0.1 21:59:49 INFO - mozlog == 3.1 21:59:49 INFO - moznetwork == 0.27 21:59:49 INFO - mozprocess == 0.22 21:59:49 INFO - mozprofile == 0.28 21:59:49 INFO - mozrunner == 6.11 21:59:49 INFO - mozscreenshot == 0.1 21:59:49 INFO - mozsystemmonitor == 0.0 21:59:49 INFO - moztest == 0.7 21:59:49 INFO - mozversion == 1.4 21:59:49 INFO - psutil == 3.1.1 21:59:49 INFO - requests == 1.2.3 21:59:49 INFO - wsgiref == 0.1.2 21:59:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:49 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 21:59:49 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 21:59:49 INFO - Using env: {'DISPLAY': ':0', 21:59:49 INFO - 'HOME': '/home/cltbld', 21:59:49 INFO - 'LANG': 'en_US.UTF-8', 21:59:49 INFO - 'LANGUAGE': 'en_US:en', 21:59:49 INFO - 'LOGNAME': 'cltbld', 21:59:49 INFO - 'MAIL': '/var/mail/cltbld', 21:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:49 INFO - 'MOZ_NO_REMOTE': '1', 21:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:49 INFO - 'NO_EM_RESTART': '1', 21:59:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:49 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:49 INFO - 'SHELL': '/bin/bash', 21:59:49 INFO - 'SHLVL': '1', 21:59:49 INFO - 'TERM': 'linux', 21:59:49 INFO - 'TMOUT': '86400', 21:59:49 INFO - 'USER': 'cltbld', 21:59:49 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:49 INFO - '_': '/tools/buildbot/bin/python'} 21:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 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)) 21:59:49 INFO - Cleaning up... 21:59:49 INFO - Return code: 0 21:59:49 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 21:59:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:59:49 INFO - Using env: {'DISPLAY': ':0', 21:59:49 INFO - 'HOME': '/home/cltbld', 21:59:49 INFO - 'LANG': 'en_US.UTF-8', 21:59:49 INFO - 'LANGUAGE': 'en_US:en', 21:59:49 INFO - 'LOGNAME': 'cltbld', 21:59:49 INFO - 'MAIL': '/var/mail/cltbld', 21:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:49 INFO - 'MOZ_NO_REMOTE': '1', 21:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:49 INFO - 'NO_EM_RESTART': '1', 21:59:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:49 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:49 INFO - 'SHELL': '/bin/bash', 21:59:49 INFO - 'SHLVL': '1', 21:59:49 INFO - 'TERM': 'linux', 21:59:49 INFO - 'TMOUT': '86400', 21:59:49 INFO - 'USER': 'cltbld', 21:59:49 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:49 INFO - '_': '/tools/buildbot/bin/python'} 21:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:49 INFO - Downloading/unpacking jsonschema 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:59:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:59:51 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 21:59:51 INFO - Downloading/unpacking functools32 (from jsonschema) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:51 INFO - Downloading functools32-3.2.3-2.tar.gz 21:59:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:59:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:59:51 INFO - warning: no files found matching '*.txt' 21:59:51 INFO - no previously-included directories found matching 'build' 21:59:51 INFO - no previously-included directories found matching 'dist' 21:59:51 INFO - no previously-included directories found matching '.git*' 21:59:51 INFO - Installing collected packages: jsonschema, functools32 21:59:51 INFO - Running setup.py install for functools32 21:59:51 INFO - warning: no files found matching '*.txt' 21:59:51 INFO - no previously-included directories found matching 'build' 21:59:51 INFO - no previously-included directories found matching 'dist' 21:59:51 INFO - no previously-included directories found matching '.git*' 21:59:51 INFO - Successfully installed jsonschema functools32 21:59:51 INFO - Cleaning up... 21:59:51 INFO - Return code: 0 21:59:51 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:59: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')]} 21:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59: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')]} 21:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:59: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfb161bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d6360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, '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': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', '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 21:59: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', 'flake8'] in /builds/slave/test-pgo/build 21:59: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 flake8 21:59:51 INFO - Using env: {'DISPLAY': ':0', 21:59:51 INFO - 'HOME': '/home/cltbld', 21:59:51 INFO - 'LANG': 'en_US.UTF-8', 21:59:51 INFO - 'LANGUAGE': 'en_US:en', 21:59:51 INFO - 'LOGNAME': 'cltbld', 21:59:51 INFO - 'MAIL': '/var/mail/cltbld', 21:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:59:51 INFO - 'MOZ_NO_REMOTE': '1', 21:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:59:51 INFO - 'NO_EM_RESTART': '1', 21:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:59:51 INFO - 'PWD': '/builds/slave/test-pgo', 21:59:51 INFO - 'SHELL': '/bin/bash', 21:59:51 INFO - 'SHLVL': '1', 21:59:51 INFO - 'TERM': 'linux', 21:59:51 INFO - 'TMOUT': '86400', 21:59:51 INFO - 'USER': 'cltbld', 21:59:51 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 21:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:59:51 INFO - '_': '/tools/buildbot/bin/python'} 21:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:59:51 INFO - Downloading/unpacking flake8 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:59:53 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 21:59:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:59:53 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 21:59:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:53 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 21:59:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59: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 21:59:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:59:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:59:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:59:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:59:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:59:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:59:54 INFO - no previously-included directories found matching 'docs/_build' 21:59:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:59:54 INFO - Running setup.py install for pep8 21:59:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:59:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:59:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:59:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:59:54 INFO - no previously-included directories found matching 'docs/_build' 21:59:54 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:59:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:59:54 INFO - Cleaning up... 21:59:54 INFO - Return code: 0 21:59:54 INFO - Running post-action listener: _resource_record_post_action 21:59:54 INFO - Running post-action listener: _start_resource_monitoring 21:59:54 INFO - Starting resource monitoring. 21:59:54 INFO - ##### 21:59:54 INFO - ##### Running install step. 21:59:54 INFO - ##### 21:59:54 INFO - Running pre-action listener: _resource_record_pre_action 21:59:54 INFO - Running main action method: install 21:59:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:59:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:59:54 INFO - Reading from file tmpfile_stdout 21:59:54 INFO - Detecting whether we're running mozinstall >=1.0... 21:59:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:59:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:59:54 INFO - Reading from file tmpfile_stdout 21:59:54 INFO - Output received: 21:59:54 INFO - Usage: mozinstall [options] installer 21:59:54 INFO - Options: 21:59:54 INFO - -h, --help show this help message and exit 21:59:54 INFO - -d DEST, --destination=DEST 21:59:54 INFO - Directory to install application into. [default: 21:59:54 INFO - "/builds/slave/test-pgo"] 21:59:54 INFO - --app=APP Application being installed. [default: firefox] 21:59:54 INFO - mkdir: /builds/slave/test-pgo/build/application 21:59:54 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'] 21:59:54 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 22:00:10 INFO - Reading from file tmpfile_stdout 22:00:10 INFO - Output received: 22:00:10 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:00:10 INFO - Running post-action listener: _resource_record_post_action 22:00:10 INFO - ##### 22:00:10 INFO - ##### Running run-tests step. 22:00:10 INFO - ##### 22:00:10 INFO - Running pre-action listener: _resource_record_pre_action 22:00:10 INFO - Running main action method: run_tests 22:00:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:00:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:00:10 INFO - Python 2.7.3 22:00:10 INFO - Return code: 0 22:00:10 INFO - grabbing minidump binary from tooltool 22:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:10 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 0x2565540>, '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 0x2566440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25668f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:00:10 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 22:00:10 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 22:00:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:10 INFO - Return code: 0 22:00:10 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:00:10 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:00:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:00:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:00:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:00:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:00:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:00:11 INFO - Return code: 0 22:00:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 22:00:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 22:00:11 INFO - Using env: {'DISPLAY': ':0', 22:00:11 INFO - 'HOME': '/home/cltbld', 22:00:11 INFO - 'LANG': 'en_US.UTF-8', 22:00:11 INFO - 'LANGUAGE': 'en_US:en', 22:00:11 INFO - 'LOGNAME': 'cltbld', 22:00:11 INFO - 'MAIL': '/var/mail/cltbld', 22:00:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:00:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:11 INFO - 'MOZ_NO_REMOTE': '1', 22:00:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:11 INFO - 'NO_EM_RESTART': '1', 22:00:11 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:00:11 INFO - 'PWD': '/builds/slave/test-pgo', 22:00:11 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:00:11 INFO - 'SHELL': '/bin/bash', 22:00:11 INFO - 'SHLVL': '1', 22:00:11 INFO - 'TERM': 'linux', 22:00:11 INFO - 'TMOUT': '86400', 22:00:11 INFO - 'USER': 'cltbld', 22:00:11 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163', 22:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:11 INFO - '_': '/tools/buildbot/bin/python'} 22:00:11 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 22:00:11 INFO - mozversion application_buildid: 20160105193124 22:00:11 INFO - mozversion application_changeset: b2ec3d6497157b97c59dc45274a1406653a7d197 22:00:11 INFO - mozversion application_display_name: Nightly 22:00:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:11 INFO - mozversion application_name: Firefox 22:00:11 INFO - mozversion application_remotingname: firefox 22:00:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:00:11 INFO - mozversion application_vendor: Mozilla 22:00:11 INFO - mozversion application_version: 46.0a1 22:00:11 INFO - mozversion platform_buildid: 20160105193124 22:00:11 INFO - mozversion platform_changeset: b2ec3d6497157b97c59dc45274a1406653a7d197 22:00:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:00:11 INFO - mozversion platform_version: 46.0a1 22:00:11 INFO - using testdate: 1452060011 22:00:11 INFO - actual date: 1452060011 22:00:11 INFO - starting webserver on 'localhost:50969' 22:00:11 INFO - SUITE-START | Running 3 tests 22:00:11 INFO - TEST-START | dromaeo_css 22:00:11 INFO - operating with platform_type : linux_ 22:00:11 INFO - Initialising browser for dromaeo_css test... 22:00:11 INFO - TEST-INFO | started process 2480 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTWUyHg/profile http://localhost:50969/getInfo.html) 22:00:16 INFO - PROCESS | 2480 | __metrics Screen width/height:1600/1200 22:00:16 INFO - PROCESS | 2480 | colorDepth:24 22:00:16 INFO - PROCESS | 2480 | Browser inner width/height: 1024/697 22:00:16 INFO - PROCESS | 2480 | __metrics 22:00:16 INFO - PROCESS | 2480 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:00:16 INFO - PROCESS | 2480 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:00:17 INFO - TEST-INFO | 2480: exit 0 22:00:17 INFO - Browser initialized. 22:00:17 INFO - Running cycle 1/1 for dromaeo_css test... 22:00:17 INFO - TEST-INFO | started process 2592 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTWUyHg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:29 INFO - PROCESS | 2592 | RSS: Main: 150245376 22:00:29 INFO - PROCESS | 2592 | 22:01:19 INFO - PROCESS | 2592 | 1452060079918 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] 22:03:29 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50969/tests/dromaeo/cssquery-ext.html) 22:03:30 INFO - PROCESS | 2592 | RSS: Main: 266219520 22:03:30 INFO - PROCESS | 2592 | 22:06:33 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-ext.html (next: http://localhost:50969/tests/dromaeo/cssquery-jquery.html) 22:06:33 INFO - PROCESS | 2592 | RSS: Main: 235012096 22:06:33 INFO - PROCESS | 2592 | 22:09:35 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50969/tests/dromaeo/cssquery-mootools.html) 22:09:36 INFO - PROCESS | 2592 | RSS: Main: 225492992 22:09:36 INFO - PROCESS | 2592 | 22:12:38 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50969/tests/dromaeo/cssquery-prototype.html) 22:12:38 INFO - PROCESS | 2592 | RSS: Main: 216465408 22:12:38 INFO - PROCESS | 2592 | 22:15:41 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50969/tests/dromaeo/cssquery-yui.html) 22:15:42 INFO - PROCESS | 2592 | RSS: Main: 220356608 22:15:42 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | Cycle 1(1): loaded http://localhost:50969/tests/dromaeo/cssquery-yui.html (next: http://localhost:50969/tests/dromaeo/cssquery-dojo.html) 22:18:44 INFO - PROCESS | 2592 | RSS: Main: 250195968 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | __start_tp_report 22:18:44 INFO - PROCESS | 2592 | _x_x_mozilla_page_load 22:18:44 INFO - PROCESS | 2592 | _x_x_mozilla_page_load_details 22:18:44 INFO - PROCESS | 2592 | |i|pagename|runs| 22:18:44 INFO - PROCESS | 2592 | |0;dojo.html;3108.0675797260815;3140.434721750084;3141.607299087614;3151.700588444097;3175.6348019977695;5067.949320506794;5179.119549676555;5214.713190774506;5219.8956020879605;5266.68399896006;5175.223716442535;5248.763805628746;5276.8153114641;5313.38896026957;5322;4717.69334993226;4744.027474367755;4796.352492413532;4848.078865015654;4865.951340486586;4803.3995750531185;4804.375431193957;4862.416510018803;4868.488808675092;4875.171220892456;4664.603508701747;4738.099761045391;4751.928721069186;4831.541003604687;4852.417709874802;4801.01579176268;4801.73590452525;4819.349387832633;4820;4855.514448555152;4757.809687612491;4794.568488836021;4805.783739731721;4839.322494850724;4872;4897.559219670246;4956.231784073474;4971.1549036663855;5061.063703214881;5091.05815424144;5448.400675925645;5489.780408783644;5490.286262785812;5495.587830912697;5499.312585926759;5486.807961721361;5553.139263414178;5569.136783798518;5623.353314368818;5624.718764061821;5171.844844654666;5193.065248255312;5219.217117432378;5261.394939581921;5289.841304760864;5647.29408823897;5658.519025882763;5663.065594176997;5675.744591493392;5712.457316554921;5394.352677678625;5472.26124473199;5493.423190565013;5495.505404513613;5504.532114770209;5490.176473529003;5539.086050801653;5547.306586676666;5561.77752889888;5610.214569960209;5524.171374293888;5552.639078459968;5556.527695145877;5561;5564.026295398289;5398.082326004589;5494.752736126884;5568.470995255444;5575.358833734092;5619.325680918316;4277.144571085795;4323.113761678848;4335.2196604610845;4376.627986621173;4380.080183161508;3844.7693138411787;3844.942325865114;3847.7691338519217;3876.3991581304945;3877.2439374321734;5081.72050537224;5122.154844450624;5122.92315615266;5123.948760512421;5126.256692779526;5441.347038355343;5482.342118945672;5566.415526369754;5568.303962004749;5568.832935011957;5111.977604479119;5119.334486516729;5120.5135512127035;5122.487751224848;5147.253648221062;4694.460137084211;4697.4832768395445;4703.106409782131;4737.715737055788;4762.595179409789;5646.858828529237;5690.174924635988;5722.685252311163;5723.570732195102;5776.884462310731;5524.75138618755;5614.41048689898;5625.887482250414;5628.549716022791;5633.915491267716;5653.265075540154;5654.943450565442;5658.886822263614;5678.716064196857;5701.116326969326;5291.074062039204;5373.758180881791;5427.3215848019;5435.510804027656;5445.7549410277425;5562.137868630369;5577.219189313419;5589.888202235851;5628.831135065954;5654.123610840326;5469.288992430958;5476.698781566893;5478.808241711521;5481.314835645544;5482.23248745168;5396.379416367168;5449.34607847045;5487.64330318528;5519.3652729936575;5547.667139971614;5348.224507446476;5357.464253574612;5358.651687640291;5359.705216212989;5363.517283444508;5456.154296084113;5457.263269458573;5460.481254280837;5513.448655134454;5520.558355331484;5231.529162375252;5316.574674026146;5327.733613319241;5359.973200133974;5419 22:18:44 INFO - PROCESS | 2592 | |1;ext.html;521.6348556010793;526.1450143516788;540.308405241291;547.9369872464666;555.91383335583;29494.262643433896;30225.546616800766;30232.546511802342;30262.84868575657;35019.29961400777;38961;39337.40993885087;39355.80322098386;39358.60641393585;39404.21191576174;8477.618507167177;8560;8628.439151455135;8629.870551941694;8633.13668633138;7476.476646634711;7643.350315223212;7644.61776911155;7651.693932242703;8395.202455766692;10239.078482936535;10369.118624916891;10378.429186394746;10448.216383771209;10480.371177729361;9970.65102721405;10116.241281903813;10143.695689129338;10144.847827282596;10149.644762433316;7550.6602202901;7719.961400192963;7728.6522106505345;9799.16707079899;9938.055884690943;39994.60005399938;40430;40551;40556;40564.594354056375;3179.2210908327374;3410.7441941854213;3987.4218238355274;4033.455483509718;4035.152617950234;27210;27513.31216719578;27520;27786.305342366602;28295.858520707472;3725.9813700931595;4022.939655905164;4047.1096358801015;4072.6334629883454;4079.836806527765;254863;255284;255620;256575;256938;53256.467435325925;53514;53619;53805.73097134489;53808.46191538034;61829.38170618326;62112.378876211566;62238;62297.06554401688;62364.688176558826;15000.024998375071;15420;15432.691346173238;15442.0734755915;15451.227438628248;11273.943630281796;11302.886971130349;11337.489812958436;11361.829572556253;11392.886071139348;19875;20111.49721256951;20141.09365078575;20160.899195503927;20174.89912550428;7539.849203015909;7765.92234077652;7772.805679858049;7892.881606775903;7968.243016913384;891.741394995444;962.485070487286;1015.9332700664273;1045.4877110216091;1101.862267216598;28092.43815123727;28660.14019579416;31041;32778.67221327804;32936.34127317488;945.6028468043438;992.3153024413132;1055.7571758495435;1070.635983765508;1095.780843831237;13733.244671543162;13803.792943105862;13815.861841381658;14109.153450792985;14119.364628591738;299758;301795;303839;305352;462620;43083;43647.56352436499;52670.20994685083;54777;54894;1326.7943468762357;1329.0696512441136;1331.068252223428;1349.5209200733796;1366.1598117157876;25714.485710286062;26085.73914260833;26294.605580916683;26370.868145659148;26480.337991550365;44354.33468498039;44999;45226.54773452355;45247.547524524336;45331.54668453405;239882;240669;240796;241344;241567;29319;30007.249818754706;30303.848480758337;30595;30627.2343191422;39382.60617393904;42073.579264206965;42240.577594224895;42489.36265956075;42666;35046.82476587703;35824.82087589546;36316.636833631324;36693.129099343976;36753.816230918674;35231.47152792761;36228.275434490635;36257.81871090628;36399.45400819043;36422.635773642985;35565.82217088898;37936.81031594824;38923.2215355697;39011.80494097511;39039.6096039036;27871.721282787723;30685.846570767004;31285.645913930923;31385.8430707845;31397.372052559276;23903.282901512983;25060.12289569856;25197.606227408367;25512.872435637702;25623.615645764956 22:18:44 INFO - PROCESS | 2592 | |2;jquery.html;348.42683785173386;349.97200223982065;351.46577202651963;352.07404526096394;352.1917200025942;14169.14985100892;14606.123632582026;16393.262303196356;16609.501714948557;17406;15856.28646710898;16034.83965160351;16044.83955160448;16046.197690115478;16071.276792544335;5116.5139311765415;5389.487998640123;5391.460853914617;5396.082665946798;5397.082495975694;3946;4058.0260737422955;5327.520523152915;5827.475527202551;5980.820575382745;5862.3551409345155;5868.853278668024;5889.793857214999;5918.319393269764;5933.110033494967;5733.226014488053;5911.940880591182;5915.911261331084;5916.378780228057;5919.023361145406;5169.844904652867;5174.404943431516;5174.456682048406;5174.689518628894;5191.0656081905245;11594.6521604352;11610.419479026099;16249.756253656205;18329.083545822654;18958.715619265586;2008.1866843928233;2098.1712223671716;2154.536774593454;2194.9890250548647;2215.1139544182297;9248.722538323862;9320.114589114024;9448.763780905463;11068;12372.948299394562;1754.140471169118;1801.5766294920684;1835.0916296433352;1861.283405888729;1888.830005299516;57975.71012144954;59857;60638;61200.69399653018;62110.68944655293;9428.198603118812;9522.190613797766;9524.333296669167;9653.372530785617;9671.839379274392;12466.376681165906;12531.749365012649;12571;12585.559505417266;12603.495860165674;7107.538010029331;7155.17715462718;7174.533655312388;7379.446541509408;7415.814604634819;6568.474522038226;6649.60102393858;6657.800265992028;6675.299093595199;6914.654267286615;3962.227365663668;4127.814248358831;4131.690123240709;4166.749995000309;4172.061286210578;1663.376233912283;1679.7816283883017;1732.2291580246922;1769.398404542436;1780.97328540072;887.5917078144126;917.6235646530205;934.4533447933036;975.6048800235916;1066.3921564708041;14239.572812815632;15785.68428631444;15914.840851591336;16240.350385984428;17120.229589668244;919.1681528216974;928.4939707859232;931.7065124485765;951.8477043672979;989.2135752077116;7703.691852325845;8953.59708813105;8991.325650576233;9030.871141107362;9044.095590440904;476769;491521;539254;566695;579096;79075.60462197653;79826.20173798304;80836;81628.59185704033;82013.58993204996;1125.7861006408757;1141.0928311992152;1173.9236949598248;1190.565443613075;1203.1457665057733;14777.482788102365;15476.380944762084;15592.220388980733;15687.1372074537;15856.207189640703;33289.334213316084;33626;34102.31795364029;34562.654373455945;34614;45352.54647453615;46504.18697662792;47604.2859357103;48276.27585586149;51496;9027.593758280762;9142.222911052635;9297.372959732154;9328.113829186217;9508.762280942756;11102.222844400814;11113.83329250078;11212.27120237182;11461.48423320936;16694.66610667804;13340.599782006555;13619;14116.576502704935;14122.858771412566;14151.64620884486;13567.728645427234;14018;14150.929245353707;14198.148111113367;14345.64135896611;14088.084274522123;14153.221572813594;14294.857051429353;14513.201773902536;14653.267336632913;8698.47809131454;9060;9082.591283392396;9393.107654772786;9444.763880903032;9926.205903527845;11087.889121108685;11417.771644567229;14541.927290363481;14980.026298290577 22:18:44 INFO - PROCESS | 2592 | |3;mootools.html;1241.205628397826;1386.362273354257;1393.8466768655449;1513.4400271899399;1749.6150846813705;6338.968305158473;6934.479914006451;7113.146422429316;7122.109736282965;7138.393236574884;6934.479914006444;7047.964760176204;7054.294570542943;7071.717131314754;7114.608696521689;1943.4849764812336;1985.5830275642134;2122.7240458740343;2154.795294447029;2190.342897130862;2169.305822136917;2172.804447599716;2173.4131784418196;2179.0521123311332;2180.4766855954535;2075;2169.078141789741;2169.5443956769095;2169.945751356213;2176.1948079210633;2124.5538436928264;2126.457753272913;2150.0969592771075;2189.5292512109813;2190.255313193506;2162.535054963175;2168.7722789107074;2201.933941981743;2204.129368899292;2205.7353117625826;7246.637668116573;7391.22392148822;7401.148867880155;7405.148407933103;7405.333519983227;4369.759663218521;4438.045820148651;4443.244648409745;4463.218936686067;4466.977665111654;2882.7117288271215;2936.5595160725848;2942.691017443159;2955;3009.0371081254007;939.3940908114228;949.1979277510561;959.4818797849153;961.4519723757461;992.9404235745806;9878;9918.851217231748;9930.155936745385;9941.502924853728;9958.900410995942;3933.4296527003426;3959.425883246913;4028.7582745035393;4031.3952907064177;4063.227986682521;8669.046404895456;8679.218870301704;8686.913130868737;8687.565621719015;8692.087330830298;4483.708558943724;4483.887902802456;4485.439320084989;4485.46174459067;4490.910181796346;4065.715399921977;4070;4070.3283958146576;4070.3283958147167;4074.5517993020744;3261.8369081546302;3304.2235074757414;3468.6531346865113;3509.666581674737;3522.894313170609;1093.1364222264322;1102.779444111181;1112.9666110016713;1115.1469126118525;1115.9497822598003;1094.7372630568607;1276.6042526816532;1342.7851543752952;1368.5620601407652;1371.0334214378838;6845.212800528003;6920.273371295942;6921.515493915478;6929.722811087601;6933.5493192942295;1107.7673688525338;1113.7494063835572;1122.680036189682;1130.2088538023415;1144.8053830848776;5540.556755459554;5594.1888426177975;5620.381758006616;5634.802781902614;5636.126400407943;8514.106018868053;8773.341999350074;8803.383763136648;8951;8951.641934322553;8765.430247033923;8770.649174033095;8831.337649676172;8837.072107428756;8852.424592401474;6811.25076241613;6831.6242606656115;6978.267281935425;6982.825429364307;6995.405390541861;6667.066610674607;6708.463322934251;6711.798646040626;6721.731130754911;6722.226943901514;7801.414893882868;7896.605169741605;7908.327792137733;8076.192380761877;8089.514629122272;8041.718539851077;8130.105688374392;8240.876386854321;8242.670293188205;8270.503769773833;6541.901871471837;6562.8359291016195;6570.5400621956005;6584.8024559263295;6673.532852700265;6281.937180628135;6393.93606063952;6808.978653202059;6941.444684425335;6954.652267386509;6567.934320656924;6671.66641667905;6720.462363011045;6735.562188457735;6792.796216113525;6558;6593.142891424084;6604.537682362188;6682.231543372574;6700.865982680221;6616.073749674953;6620.536562440583;6642.867142657216;6672.165979252593;6681.632510211985;6136.570440069152;6140.293866205265;6142.815715528542;6158.507319414346;6160.414760597736;6137.539684523768;6178.289496707936;6199.070139479114;6206.224221972254;6212.4408803207925 22:18:44 INFO - PROCESS | 2592 | |4;prototype.html;317.98728050877963;321.1009174311926;329.17705735660843;329.81760389005416;337.2227016726445;5827.4463925927075;5842.912356314653;5855.882882342361;5856.619319744219;5901.203337549429;5502.174673798922;5572.498475137237;5681.630694004897;5731.713414329288;5777.797777077803;4497.482789479202;4501.909961800779;4512.97743511282;4528.796204170803;4535.274356103024;4303.859477238534;4524.049949510607;4526.977365113186;4531.365608814768;4547.499775024745;4354.869353919387;4361.302191649337;4445.666575006888;4449;4464.620507256887;4382.408374869384;4424.823007079713;4433.357163211349;4434.935615452268;4441.489228738704;3914.6868250539887;3914.7651140931353;3919.7452165609143;3920.039590300366;3925.0187453136714;5487.533559647435;5517.862053448655;5528.281323427969;5549.750261238254;5570.359408668014;5169.534741873212;5180.4042535108565;5180.533751962342;5187.170052791573;5194.7662355193725;6020.5785595008365;6081.452669259788;6086.573939824214;6087.786927457518;6090.847728806815;4340.891477713051;4350.499692535367;4353;4354.216241076605;4354.847580334673;6136.325004249529;6301.52738544611;6329.272133704637;6362.968185159091;6366.586171898812;4329.480462344539;4353.368761529624;4363.803628836647;4364.8908777280185;4407.6914615976575;4407.691461597721;4422.800973956179;4423.778811059434;4426.18115648606;4428.247197976351;5121.564667003272;5123.333966584395;5133.589312854962;5136.717480538607;5143.6656617320505;6009.06859436788;6076.696165191723;6147.938520614736;6151.200343955257;6154.076888466766;1555.4944642991072;1564.8982816116916;1567.2163918040978;1567.8275389707123;1570.2541292885787;1032.8192566300868;1035.570238351091;1057.049743223751;1057.9418132002659;1058.2962330050584;5836.241288632451;5843.18195452637;5848.005839007379;5849.064149736022;5852.619579727304;6348.460380867678;6351.587146835441;6361.9502782040445;6363.522735794834;6372.0760489729655;5635.436456354331;5716.056850619684;5817.447342502455;5912.497437717756;5918.260217472816;3433.5021421893684;3452.7583069184916;3453.5855697316483;3456.5160877477174;3457.6715212054814;6649.168853893263;6661.700223489857;6667.733290668416;6671.933280667228;6679.666016699146;6433.388828061326;6577.769778057745;6586.176727909012;6588.308227636125;6591.6374599397495;6637.203535575762;6656.96671516649;6662.96668516664;6668.766593169215;6672.766453174213;6405.263394709668;6410.326915673973;6413.679316034273;6416.358364163546;6419;6186.381361863778;6305.4009869062365;6305.653189074645;6307.085472606354;6309.652969086745;6605.306442823435;6635.502337324817;6643;6645.269020407848;6652.866942661217;4402.515723270501;4406.383106365176;4407.427034485497;4408.272635015187;4410.33844923264;6242.469390101892;6410.166678331787;6422.389872962061;6430.3891130342545;6447.3552644735155;6244.157038799885;6251.562390632612;6254.8123556293385;6256;6257.5932564383165;6207.81376558704;6247.375262473716;6254.343293954069;6256.280527739368;6261.96869015652;6025.337212906665;6105.572609917263;6127.877442451215;6129.601575897731;6142.662153581596;6220.222472190976;6253.530985176039;6263.812085637438;6265.248170219603;6265.780697675559;6117.204763380732;6119.173911522065;6122.051082082285;6132.540059495466;6133.877322453437 22:18:44 INFO - PROCESS | 2592 | |5;yui.html;697.532653122408;718.9748358807443;751.7293774241275;796.3032346696641;797.7646594254696;13485.325733713324;13749.725005499884;13841.93079034606;13927.582172534814;13940.721185576282;12779.424925878335;12933.870661293384;12936.611901642966;12961.35193240339;12965.481380744783;686.8248596607883;695.8886578147498;812.6708682983401;826.409117481001;827.755812035448;812.5937031484258;819.1849110135423;824.3528829868554;825.1294883897485;825.9091499935003;862.5687156421789;866.0343716755804;868.7306934850217;871.2724874729577;872.5419154943642;718.0127324928224;719.9784006479788;848.4569875279826;864.1962974433774;867.1068799136899;633.8256979330674;636.8789929913302;636.8917284061674;637.0985056145548;637.6174295422755;13157.671058223525;13265.535706250332;13309.134906231064;13337.73324533504;13354.26551539665;11184.496697648625;11506.654800355918;11645.359505227209;11739.7065073373;11919.761604767942;10112.848307275397;10175.389476631426;10196.89803101967;10297.485125743759;10328.845067323997;3655.597884232732;3682.2635472905527;3712.9814350928073;3720.6837418819164;3795.2788970095594;45303;45333;46309.5369046312;46461.07077858424;46507.069858602976;9714.902850971399;9778.608855645694;9798.31411801167;9853.113219810108;9973.30186886911;18720.34478793263;18759.624807503773;18766.155523001496;18773.530661733294;18778.906105469658;7594.772156835304;7661.195574464601;7670.7315243966195;7704.345130663956;7813.531188128731;4108.322126849036;4115.8765237042935;4162.729422587522;4178.206140833232;4298.957010429918;3119.32934419101;3151.7163455289133;3158.7788854779947;3167.192365946669;3176.348848486055;549.2502733768457;570.9286339207599;572.816698656426;572.9512991395694;575.726529898295;460.7028466638999;516.9974150129301;543.6221825831088;594.4917095883044;630.2972186012655;7085.716571337191;7444.5060904596485;7450.18048014718;7480.17718051014;7968.641411136513;552.8947564334798;601.5758889982552;607.2012282700196;609.6585911889356;623.9598599071069;3589.5692516897616;3630.546181727284;3643.307771523402;3758.5113935188797;3763.849446022183;45268.77365613151;45409.77295113503;45859.54140458619;46049.76975115103;46507.30239046417;46752.5324746755;49370;49503.78225415337;49563;49615.50384496181;44902.77548612236;45042.77478612586;45389.319160212624;45645.31532027022;45975;7408.111026676834;7525.39909304956;7600.885986710093;7624.313811756969;7796.571188584456;32025;32325.35349293048;32829.671703282664;33502.83248583742;33682;40419.79790101148;43465.78267108644;43957.78021109874;44068.33897491604;44629.55370446254;9612.231021518402;9735.09239046293;9803.460809655537;9834;10108.54511546997;9816.705498835046;10080.445575493124;10131.898681013095;10327.070563649308;10349.689509314732;9318.72043838686;9543.284253680862;9604.327697061419;10115.94706656387;10290.3825770454;9975.850362244717;10045.49772511357;10247.231457640557;10259.179265658879;10543.051125398752;9178.678746244117;9342.766430839283;9665.855012174963;9715.854262185932;9754;8671.739847804576;8829.470231785857;9064;9166.404183728037;9183.816323673356;7922.7227047053075;8315.459495132796;8327.583620818814;8966.775830604287;9055.773605659911 22:18:44 INFO - PROCESS | 2592 | __end_tp_report 22:18:44 INFO - PROCESS | 2592 | __start_cc_report 22:18:44 INFO - PROCESS | 2592 | _x_x_mozilla_cycle_collect,912 22:18:44 INFO - PROCESS | 2592 | __end_cc_report 22:18:44 INFO - PROCESS | 2592 | __startTimestamp1452061124809__endTimestamp 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | ------- Summary: start ------- 22:18:44 INFO - PROCESS | 2592 | Number of tests: 6 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#0] dojo.html Cycles:165 Average:5166.24 Median:5359.71 stddev:551.01 (10.3%) stddev-sans-first:528.52 22:18:44 INFO - PROCESS | 2592 | Values: 3108.1 3140.4 3141.6 3151.7 3175.6 5067.9 5179.1 5214.7 5219.9 5266.7 5175.2 5248.8 5276.8 5313.4 5322.0 4717.7 4744.0 4796.4 4848.1 4866.0 4803.4 4804.4 4862.4 4868.5 4875.2 4664.6 4738.1 4751.9 4831.5 4852.4 4801.0 4801.7 4819.3 4820.0 4855.5 4757.8 4794.6 4805.8 4839.3 4872.0 4897.6 4956.2 4971.2 5061.1 5091.1 5448.4 5489.8 5490.3 5495.6 5499.3 5486.8 5553.1 5569.1 5623.4 5624.7 5171.8 5193.1 5219.2 5261.4 5289.8 5647.3 5658.5 5663.1 5675.7 5712.5 5394.4 5472.3 5493.4 5495.5 5504.5 5490.2 5539.1 5547.3 5561.8 5610.2 5524.2 5552.6 5556.5 5561.0 5564.0 5398.1 5494.8 5568.5 5575.4 5619.3 4277.1 4323.1 4335.2 4376.6 4380.1 3844.8 3844.9 3847.8 3876.4 3877.2 5081.7 5122.2 5122.9 5123.9 5126.3 5441.3 5482.3 5566.4 5568.3 5568.8 5112.0 5119.3 5120.5 5122.5 5147.3 4694.5 4697.5 4703.1 4737.7 4762.6 5646.9 5690.2 5722.7 5723.6 5776.9 5524.8 5614.4 5625.9 5628.5 5633.9 5653.3 5654.9 5658.9 5678.7 5701.1 5291.1 5373.8 5427.3 5435.5 5445.8 5562.1 5577.2 5589.9 5628.8 5654.1 5469.3 5476.7 5478.8 5481.3 5482.2 5396.4 5449.3 5487.6 5519.4 5547.7 5348.2 5357.5 5358.7 5359.7 5363.5 5456.2 5457.3 5460.5 5513.4 5520.6 5231.5 5316.6 5327.7 5360.0 5419.0 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#1] ext.html Cycles:180 Average:44462.61 Median:35033.06 stddev:73988.58 (211.2%) stddev-sans-first:74122.57 22:18:44 INFO - PROCESS | 2592 | Values: 521.6 526.1 540.3 547.9 555.9 29494.3 30225.5 30232.5 30262.8 35019.3 38961.0 39337.4 39355.8 39358.6 39404.2 8477.6 8560.0 8628.4 8629.9 8633.1 7476.5 7643.4 7644.6 7651.7 8395.2 10239.1 10369.1 10378.4 10448.2 10480.4 9970.7 10116.2 10143.7 10144.8 10149.6 7550.7 7720.0 7728.7 9799.2 9938.1 39994.6 40430.0 40551.0 40556.0 40564.6 3179.2 3410.7 3987.4 4033.5 4035.2 27210.0 27513.3 27520.0 27786.3 28295.9 3726.0 4022.9 4047.1 4072.6 4079.8 254863.0 255284.0 255620.0 256575.0 256938.0 53256.5 53514.0 53619.0 53805.7 53808.5 61829.4 62112.4 62238.0 62297.1 62364.7 15000.0 15420.0 15432.7 15442.1 15451.2 11273.9 11302.9 11337.5 11361.8 11392.9 19875.0 20111.5 20141.1 20160.9 20174.9 7539.8 7765.9 7772.8 7892.9 7968.2 891.7 962.5 1015.9 1045.5 1101.9 28092.4 28660.1 31041.0 32778.7 32936.3 945.6 992.3 1055.8 1070.6 1095.8 13733.2 13803.8 13815.9 14109.2 14119.4 299758.0 301795.0 303839.0 305352.0 462620.0 43083.0 43647.6 52670.2 54777.0 54894.0 1326.8 1329.1 1331.1 1349.5 1366.2 25714.5 26085.7 26294.6 26370.9 26480.3 44354.3 44999.0 45226.5 45247.5 45331.5 239882.0 240669.0 240796.0 241344.0 241567.0 29319.0 30007.2 30303.8 30595.0 30627.2 39382.6 42073.6 42240.6 42489.4 42666.0 35046.8 35824.8 36316.6 36693.1 36753.8 35231.5 36228.3 36257.8 36399.5 36422.6 35565.8 37936.8 38923.2 39011.8 39039.6 27871.7 30685.8 31285.6 31385.8 31397.4 23903.3 25060.1 25197.6 25512.9 25623.6 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#2] jquery.html Cycles:180 Average:28142.01 Median:4129.75 stddev:87064.57 (2108.2%) stddev-sans-first:87283.79 22:18:44 INFO - PROCESS | 2592 | Values: 348.4 350.0 351.5 352.1 352.2 14169.1 14606.1 16393.3 16609.5 17406.0 15856.3 16034.8 16044.8 16046.2 16071.3 5116.5 5389.5 5391.5 5396.1 5397.1 3946.0 4058.0 5327.5 5827.5 5980.8 5862.4 5868.9 5889.8 5918.3 5933.1 5733.2 5911.9 5915.9 5916.4 5919.0 5169.8 5174.4 5174.5 5174.7 5191.1 11594.7 11610.4 16249.8 18329.1 18958.7 2008.2 2098.2 2154.5 2195.0 2215.1 9248.7 9320.1 9448.8 11068.0 12372.9 1754.1 1801.6 1835.1 1861.3 1888.8 57975.7 59857.0 60638.0 61200.7 62110.7 9428.2 9522.2 9524.3 9653.4 9671.8 12466.4 12531.7 12571.0 12585.6 12603.5 7107.5 7155.2 7174.5 7379.4 7415.8 6568.5 6649.6 6657.8 6675.3 6914.7 3962.2 4127.8 4131.7 4166.7 4172.1 1663.4 1679.8 1732.2 1769.4 1781.0 887.6 917.6 934.5 975.6 1066.4 14239.6 15785.7 15914.8 16240.4 17120.2 919.2 928.5 931.7 951.8 989.2 7703.7 8953.6 8991.3 9030.9 9044.1 476769.0 491521.0 539254.0 566695.0 579096.0 79075.6 79826.2 80836.0 81628.6 82013.6 1125.8 1141.1 1173.9 1190.6 1203.1 14777.5 15476.4 15592.2 15687.1 15856.2 33289.3 33626.0 34102.3 34562.7 34614.0 45352.5 46504.2 47604.3 48276.3 51496.0 9027.6 9142.2 9297.4 9328.1 9508.8 11102.2 11113.8 11212.3 11461.5 16694.7 13340.6 13619.0 14116.6 14122.9 14151.6 13567.7 14018.0 14150.9 14198.1 14345.6 14088.1 14153.2 14294.9 14513.2 14653.3 8698.5 9060.0 9082.6 9393.1 9444.8 9926.2 11087.9 11417.8 14541.9 14980.0 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#3] mootools.html Cycles:180 Average:5151.62 Median:6310.45 stddev:2632.88 (41.7%) stddev-sans-first:2623.86 22:18:44 INFO - PROCESS | 2592 | Values: 1241.2 1386.4 1393.8 1513.4 1749.6 6339.0 6934.5 7113.1 7122.1 7138.4 6934.5 7048.0 7054.3 7071.7 7114.6 1943.5 1985.6 2122.7 2154.8 2190.3 2169.3 2172.8 2173.4 2179.1 2180.5 2075.0 2169.1 2169.5 2169.9 2176.2 2124.6 2126.5 2150.1 2189.5 2190.3 2162.5 2168.8 2201.9 2204.1 2205.7 7246.6 7391.2 7401.1 7405.1 7405.3 4369.8 4438.0 4443.2 4463.2 4467.0 2882.7 2936.6 2942.7 2955.0 3009.0 939.4 949.2 959.5 961.5 992.9 9878.0 9918.9 9930.2 9941.5 9958.9 3933.4 3959.4 4028.8 4031.4 4063.2 8669.0 8679.2 8686.9 8687.6 8692.1 4483.7 4483.9 4485.4 4485.5 4490.9 4065.7 4070.0 4070.3 4070.3 4074.6 3261.8 3304.2 3468.7 3509.7 3522.9 1093.1 1102.8 1113.0 1115.1 1115.9 1094.7 1276.6 1342.8 1368.6 1371.0 6845.2 6920.3 6921.5 6929.7 6933.5 1107.8 1113.7 1122.7 1130.2 1144.8 5540.6 5594.2 5620.4 5634.8 5636.1 8514.1 8773.3 8803.4 8951.0 8951.6 8765.4 8770.6 8831.3 8837.1 8852.4 6811.3 6831.6 6978.3 6982.8 6995.4 6667.1 6708.5 6711.8 6721.7 6722.2 7801.4 7896.6 7908.3 8076.2 8089.5 8041.7 8130.1 8240.9 8242.7 8270.5 6541.9 6562.8 6570.5 6584.8 6673.5 6281.9 6393.9 6809.0 6941.4 6954.7 6567.9 6671.7 6720.5 6735.6 6792.8 6558.0 6593.1 6604.5 6682.2 6700.9 6616.1 6620.5 6642.9 6672.2 6681.6 6136.6 6140.3 6142.8 6158.5 6160.4 6137.5 6178.3 6199.1 6206.2 6212.4 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#4] prototype.html Cycles:180 Average:5170.06 Median:5843.05 stddev:1555.99 (26.6%) stddev-sans-first:1517.14 22:18:44 INFO - PROCESS | 2592 | Values: 318.0 321.1 329.2 329.8 337.2 5827.4 5842.9 5855.9 5856.6 5901.2 5502.2 5572.5 5681.6 5731.7 5777.8 4497.5 4501.9 4513.0 4528.8 4535.3 4303.9 4524.0 4527.0 4531.4 4547.5 4354.9 4361.3 4445.7 4449.0 4464.6 4382.4 4424.8 4433.4 4434.9 4441.5 3914.7 3914.8 3919.7 3920.0 3925.0 5487.5 5517.9 5528.3 5549.8 5570.4 5169.5 5180.4 5180.5 5187.2 5194.8 6020.6 6081.5 6086.6 6087.8 6090.8 4340.9 4350.5 4353.0 4354.2 4354.8 6136.3 6301.5 6329.3 6363.0 6366.6 4329.5 4353.4 4363.8 4364.9 4407.7 4407.7 4422.8 4423.8 4426.2 4428.2 5121.6 5123.3 5133.6 5136.7 5143.7 6009.1 6076.7 6147.9 6151.2 6154.1 1555.5 1564.9 1567.2 1567.8 1570.3 1032.8 1035.6 1057.0 1057.9 1058.3 5836.2 5843.2 5848.0 5849.1 5852.6 6348.5 6351.6 6362.0 6363.5 6372.1 5635.4 5716.1 5817.4 5912.5 5918.3 3433.5 3452.8 3453.6 3456.5 3457.7 6649.2 6661.7 6667.7 6671.9 6679.7 6433.4 6577.8 6586.2 6588.3 6591.6 6637.2 6657.0 6663.0 6668.8 6672.8 6405.3 6410.3 6413.7 6416.4 6419.0 6186.4 6305.4 6305.7 6307.1 6309.7 6605.3 6635.5 6643.0 6645.3 6652.9 4402.5 4406.4 4407.4 4408.3 4410.3 6242.5 6410.2 6422.4 6430.4 6447.4 6244.2 6251.6 6254.8 6256.0 6257.6 6207.8 6247.4 6254.3 6256.3 6262.0 6025.3 6105.6 6127.9 6129.6 6142.7 6220.2 6253.5 6263.8 6265.2 6265.8 6117.2 6119.2 6122.1 6132.5 6133.9 22:18:44 INFO - PROCESS | 2592 | 22:18:44 INFO - PROCESS | 2592 | [#5] yui.html Cycles:180 Average:12876.73 Median:49589.25 stddev:14722.62 (29.7%) stddev-sans-first:14735.51 22:18:44 INFO - PROCESS | 2592 | Values: 697.5 719.0 751.7 796.3 797.8 13485.3 13749.7 13841.9 13927.6 13940.7 12779.4 12933.9 12936.6 12961.4 12965.5 686.8 695.9 812.7 826.4 827.8 812.6 819.2 824.4 825.1 825.9 862.6 866.0 868.7 871.3 872.5 718.0 720.0 848.5 864.2 867.1 633.8 636.9 636.9 637.1 637.6 13157.7 13265.5 13309.1 13337.7 13354.3 11184.5 11506.7 11645.4 11739.7 11919.8 10112.8 10175.4 10196.9 10297.5 10328.8 3655.6 3682.3 3713.0 3720.7 3795.3 45303.0 45333.0 46309.5 46461.1 46507.1 9714.9 9778.6 9798.3 9853.1 9973.3 18720.3 18759.6 18766.2 18773.5 18778.9 7594.8 7661.2 7670.7 7704.3 7813.5 4108.3 4115.9 4162.7 4178.2 4299.0 3119.3 3151.7 3158.8 3167.2 3176.3 549.3 570.9 572.8 573.0 575.7 460.7 517.0 543.6 594.5 630.3 7085.7 7444.5 7450.2 7480.2 7968.6 552.9 601.6 607.2 609.7 624.0 3589.6 3630.5 3643.3 3758.5 3763.8 45268.8 45409.8 45859.5 46049.8 46507.3 46752.5 49370.0 49503.8 49563.0 49615.5 44902.8 45042.8 45389.3 45645.3 45975.0 7408.1 7525.4 7600.9 7624.3 7796.6 32025.0 32325.4 32829.7 33502.8 33682.0 40419.8 43465.8 43957.8 44068.3 44629.6 9612.2 9735.1 9803.5 9834.0 10108.5 9816.7 10080.4 10131.9 10327.1 10349.7 9318.7 9543.3 9604.3 10115.9 10290.4 9975.9 10045.5 10247.2 10259.2 10543.1 9178.7 9342.8 9665.9 9715.9 9754.0 8671.7 8829.5 9064.0 9166.4 9183.8 7922.7 8315.5 8327.6 8966.8 9055.8 22:18:44 INFO - PROCESS | 2592 | -------- Summary: end -------- 22:18:44 INFO - PROCESS | 2592 | 22:18:45 INFO - TEST-INFO | 2592: exit 0 22:18:45 INFO - TEST-OK | dromaeo_css | took 1113630ms 22:18:45 INFO - TEST-START | kraken 22:18:45 INFO - operating with platform_type : linux_ 22:18:45 INFO - Initialising browser for kraken test... 22:18:45 INFO - TEST-INFO | started process 2882 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDxJibC/profile http://localhost:50969/getInfo.html) 22:18:49 INFO - PROCESS | 2882 | __metrics Screen width/height:1600/1200 22:18:49 INFO - PROCESS | 2882 | colorDepth:24 22:18:49 INFO - PROCESS | 2882 | Browser inner width/height: 1024/697 22:18:49 INFO - PROCESS | 2882 | __metrics 22:18:50 INFO - PROCESS | 2882 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:18:50 INFO - PROCESS | 2882 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:18:50 INFO - TEST-INFO | 2882: exit 0 22:18:50 INFO - Browser initialized. 22:18:50 INFO - Running cycle 1/1 for kraken test... 22:18:50 INFO - TEST-INFO | started process 2975 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDxJibC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:19:02 INFO - PROCESS | 2975 | RSS: Main: 149950464 22:19:02 INFO - PROCESS | 2975 | 22:19:05 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50969/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:05 INFO - PROCESS | 2975 | RSS: Main: 184225792 22:19:05 INFO - PROCESS | 2975 | 22:19:08 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50969/tests/kraken/driver.html?testName=audio-dft) 22:19:09 INFO - PROCESS | 2975 | RSS: Main: 174645248 22:19:09 INFO - PROCESS | 2975 | 22:19:12 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50969/tests/kraken/driver.html?testName=audio-fft) 22:19:12 INFO - PROCESS | 2975 | RSS: Main: 161034240 22:19:12 INFO - PROCESS | 2975 | 22:19:14 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50969/tests/kraken/driver.html?testName=audio-oscillator) 22:19:15 INFO - PROCESS | 2975 | RSS: Main: 162795520 22:19:15 INFO - PROCESS | 2975 | 22:19:17 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:17 INFO - PROCESS | 2975 | RSS: Main: 164864000 22:19:17 INFO - PROCESS | 2975 | 22:19:20 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50969/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:21 INFO - PROCESS | 2975 | RSS: Main: 164499456 22:19:21 INFO - PROCESS | 2975 | 22:19:24 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50969/tests/kraken/driver.html?testName=imaging-desaturate) 22:19:25 INFO - PROCESS | 2975 | RSS: Main: 164491264 22:19:25 INFO - PROCESS | 2975 | 22:19:28 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50969/tests/kraken/driver.html?testName=json-parse-financial) 22:19:28 INFO - PROCESS | 2975 | RSS: Main: 164519936 22:19:28 INFO - PROCESS | 2975 | 22:19:30 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:19:30 INFO - PROCESS | 2975 | RSS: Main: 176996352 22:19:30 INFO - PROCESS | 2975 | 22:19:32 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:19:33 INFO - PROCESS | 2975 | RSS: Main: 175009792 22:19:33 INFO - PROCESS | 2975 | 22:19:35 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:19:35 INFO - PROCESS | 2975 | RSS: Main: 182906880 22:19:35 INFO - PROCESS | 2975 | 22:19:38 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:19:38 INFO - PROCESS | 2975 | RSS: Main: 194416640 22:19:38 INFO - PROCESS | 2975 | 22:19:41 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:19:41 INFO - PROCESS | 2975 | RSS: Main: 190550016 22:19:41 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | Cycle 1(1): loaded http://localhost:50969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50969/tests/kraken/driver.html?testName=ai-astar) 22:19:43 INFO - PROCESS | 2975 | RSS: Main: 248553472 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | __start_tp_report 22:19:43 INFO - PROCESS | 2975 | _x_x_mozilla_page_load 22:19:43 INFO - PROCESS | 2975 | _x_x_mozilla_page_load_details 22:19:43 INFO - PROCESS | 2975 | |i|pagename|runs| 22:19:43 INFO - PROCESS | 2975 | |0;ai-astar;111;111;105;110;112;112;101;111;111;111 22:19:43 INFO - PROCESS | 2975 | |1;audio-beat-detection;154;126;153;162;127;157;154;127;166;126 22:19:43 INFO - PROCESS | 2975 | |2;audio-dft;161;161;161;163;162;161;161;160;161;162 22:19:43 INFO - PROCESS | 2975 | |3;audio-fft;72;74;89;91;75;76;71;126;99;73 22:19:43 INFO - PROCESS | 2975 | |4;audio-oscillator;96;95;137;95;97;96;137;97;96;145 22:19:43 INFO - PROCESS | 2975 | |5;imaging-gaussian-blur;110;110;109;111;110;110;109;110;109;109 22:19:43 INFO - PROCESS | 2975 | |6;imaging-darkroom;146;146;146;146;145;145;145;144;146;145 22:19:43 INFO - PROCESS | 2975 | |7;imaging-desaturate;96;96;96;96;97;96;96;95;96;97 22:19:43 INFO - PROCESS | 2975 | |8;json-parse-financial;58;58;60;58;58;59;57;67;55;57 22:19:43 INFO - PROCESS | 2975 | |9;json-stringify-tinderbox;67;68;67;67;68;68;68;68;72;68 22:19:43 INFO - PROCESS | 2975 | |10;stanford-crypto-aes;85;86;85;85;96;86;87;85;87;86 22:19:43 INFO - PROCESS | 2975 | |11;stanford-crypto-ccm;122;134;121;119;122;120;125;118;120;116 22:19:43 INFO - PROCESS | 2975 | |12;stanford-crypto-pbkdf2;151;140;151;151;152;140;155;151;153;141 22:19:43 INFO - PROCESS | 2975 | |13;stanford-crypto-sha256-iterative;70;69;69;68;69;67;68;68;69;67 22:19:43 INFO - PROCESS | 2975 | __end_tp_report 22:19:43 INFO - PROCESS | 2975 | __start_cc_report 22:19:43 INFO - PROCESS | 2975 | _x_x_mozilla_cycle_collect,910 22:19:43 INFO - PROCESS | 2975 | __end_cc_report 22:19:43 INFO - PROCESS | 2975 | __startTimestamp1452061183868__endTimestamp 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | ------- Summary: start ------- 22:19:43 INFO - PROCESS | 2975 | Number of tests: 14 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.60 (3.2%) stddev-sans-first:3.77 22:19:43 INFO - PROCESS | 2975 | Values: 111.0 111.0 105.0 110.0 112.0 112.0 101.0 111.0 111.0 111.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:154.00 stddev:16.57 (10.8%) stddev-sans-first:17.26 22:19:43 INFO - PROCESS | 2975 | Values: 154.0 126.0 153.0 162.0 127.0 157.0 154.0 127.0 166.0 126.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 22:19:43 INFO - PROCESS | 2975 | Values: 161.0 161.0 161.0 163.0 162.0 161.0 161.0 160.0 161.0 162.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#3] audio-fft Cycles:10 Average:84.60 Median:75.50 stddev:17.44 (23.1%) stddev-sans-first:17.90 22:19:43 INFO - PROCESS | 2975 | Values: 72.0 74.0 89.0 91.0 75.0 76.0 71.0 126.0 99.0 73.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#4] audio-oscillator Cycles:10 Average:109.10 Median:96.00 stddev:21.22 (22.1%) stddev-sans-first:21.97 22:19:43 INFO - PROCESS | 2975 | Values: 96.0 95.0 137.0 95.0 97.0 96.0 137.0 97.0 96.0 145.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 22:19:43 INFO - PROCESS | 2975 | Values: 110.0 110.0 109.0 111.0 110.0 110.0 109.0 110.0 109.0 109.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 22:19:43 INFO - PROCESS | 2975 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 145.0 144.0 146.0 145.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:19:43 INFO - PROCESS | 2975 | Values: 96.0 96.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 97.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:58.00 stddev:3.20 (5.5%) stddev-sans-first:3.38 22:19:43 INFO - PROCESS | 2975 | Values: 58.0 58.0 60.0 58.0 58.0 59.0 57.0 67.0 55.0 57.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.48 22:19:43 INFO - PROCESS | 2975 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 68.0 68.0 72.0 68.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:86.00 stddev:3.33 (3.9%) stddev-sans-first:3.46 22:19:43 INFO - PROCESS | 2975 | Values: 85.0 86.0 85.0 85.0 96.0 86.0 87.0 85.0 87.0 86.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:121.50 stddev:4.97 (4.1%) stddev-sans-first:5.27 22:19:43 INFO - PROCESS | 2975 | Values: 122.0 134.0 121.0 119.0 122.0 120.0 125.0 118.0 120.0 116.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.50 Median:151.00 stddev:5.78 (3.8%) stddev-sans-first:6.06 22:19:43 INFO - PROCESS | 2975 | Values: 151.0 140.0 151.0 151.0 152.0 140.0 155.0 151.0 153.0 141.0 22:19:43 INFO - PROCESS | 2975 | 22:19:43 INFO - PROCESS | 2975 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 22:19:43 INFO - PROCESS | 2975 | Values: 70.0 69.0 69.0 68.0 69.0 67.0 68.0 68.0 69.0 67.0 22:19:43 INFO - PROCESS | 2975 | -------- Summary: end -------- 22:19:43 INFO - PROCESS | 2975 | 22:19:44 INFO - TEST-INFO | 2975: exit 0 22:19:44 INFO - TEST-OK | kraken | took 59145ms 22:19:44 INFO - TEST-START | v8_7 22:19:44 INFO - operating with platform_type : linux_ 22:19:44 INFO - Initialising browser for v8_7 test... 22:19:44 INFO - TEST-INFO | started process 3228 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8680O9/profile http://localhost:50969/getInfo.html) 22:19:49 INFO - PROCESS | 3228 | __metrics Screen width/height:1600/1200 22:19:49 INFO - PROCESS | 3228 | colorDepth:24 22:19:49 INFO - PROCESS | 3228 | Browser inner width/height: 1024/697 22:19:49 INFO - PROCESS | 3228 | __metrics 22:19:49 INFO - PROCESS | 3228 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:19:49 INFO - PROCESS | 3228 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:19:50 INFO - TEST-INFO | 3228: exit 0 22:19:50 INFO - Browser initialized. 22:19:50 INFO - Running cycle 1/1 for v8_7 test... 22:19:50 INFO - TEST-INFO | started process 3311 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8680O9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:01 INFO - PROCESS | 3311 | RSS: Main: 150065152 22:20:01 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | Cycle 1(1): loaded http://localhost:50969/tests/v8_7/run.html (next: http://localhost:50969/tests/v8_7/run.html) 22:20:23 INFO - PROCESS | 3311 | RSS: Main: 539299840 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | __start_tp_report 22:20:23 INFO - PROCESS | 3311 | _x_x_mozilla_page_load 22:20:23 INFO - PROCESS | 3311 | _x_x_mozilla_page_load_details 22:20:23 INFO - PROCESS | 3311 | |i|pagename|runs| 22:20:23 INFO - PROCESS | 3311 | |0;Richards;167.39203213927016 22:20:23 INFO - PROCESS | 3311 | |1;DeltaBlue;191.3143294432753 22:20:23 INFO - PROCESS | 3311 | |2;Encrypt;280.8199943836001 22:20:23 INFO - PROCESS | 3311 | |3;Decrypt;5256.544502617801 22:20:23 INFO - PROCESS | 3311 | |4;RayTrace;1978.2608695652175 22:20:23 INFO - PROCESS | 3311 | |5;Earley;796.9745222929936 22:20:23 INFO - PROCESS | 3311 | |6;Boyer;12481.481481481482 22:20:23 INFO - PROCESS | 3311 | |7;RegExp;26368.42105263158 22:20:23 INFO - PROCESS | 3311 | |8;Splay;587.1990604815032 22:20:23 INFO - PROCESS | 3311 | |9;NavierStokes;4845.410628019324 22:20:23 INFO - PROCESS | 3311 | __end_tp_report 22:20:23 INFO - PROCESS | 3311 | __startTimestamp1452061223458__endTimestamp 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | ------- Summary: start ------- 22:20:23 INFO - PROCESS | 3311 | Number of tests: 10 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#0] Richards Cycles:1 Average:167.39 Median:167.39 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 167.4 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#1] DeltaBlue Cycles:1 Average:191.31 Median:191.31 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 191.3 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#2] Encrypt Cycles:1 Average:280.82 Median:280.82 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 280.8 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#3] Decrypt Cycles:1 Average:5256.54 Median:5256.54 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 5256.5 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#4] RayTrace Cycles:1 Average:1978.26 Median:1978.26 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 1978.3 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#5] Earley Cycles:1 Average:796.97 Median:796.97 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 797.0 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#6] Boyer Cycles:1 Average:12481.48 Median:12481.48 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 12481.5 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#7] RegExp Cycles:1 Average:26368.42 Median:26368.42 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 26368.4 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#8] Splay Cycles:1 Average:587.20 Median:587.20 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 587.2 22:20:23 INFO - PROCESS | 3311 | 22:20:23 INFO - PROCESS | 3311 | [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 22:20:23 INFO - PROCESS | 3311 | Values: 4845.4 22:20:23 INFO - PROCESS | 3311 | -------- Summary: end -------- 22:20:23 INFO - PROCESS | 3311 | 22:20:24 INFO - TEST-INFO | 3311: exit 0 22:20:24 INFO - TEST-OK | v8_7 | took 39670ms 22:20:24 INFO - SUITE-END | took 1212s 22:20:24 INFO - Completed test suite (00:20:12) 22:20:24 INFO - Working with test: dromaeo_css 22:20:24 INFO - Generating results file: dromaeo_css 22:20:24 INFO - Working with test: kraken 22:20:24 INFO - Generating results file: kraken 22:20:24 INFO - javascript benchmark 22:20:24 INFO - Working with test: v8_7 22:20:24 INFO - Generating results file: v8_7 22:20:24 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:24 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 22:20:24 INFO - process_Request line: dromaeo_css 6747.73 graph.html#tests=[[72,63,35]] 22:20:24 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:24 INFO - process_Request line: kraken 1513.10 graph.html#tests=[[232,63,35]] 22:20:24 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:20:24 INFO - process_Request line: v8_7 19726.33 graph.html#tests=[[230,63,35]] 22:20:24 INFO - RETURN: dromaeo_css: 6747.73 22:20:24 INFO - RETURN: kraken: 1513.10 22:20:24 INFO - RETURN: v8_7: 19726.33 22:20:24 INFO - javascript benchmark 22:20:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3108.0675797260815, 3140.434721750084, 3141.607299087614, 3151.700588444097, 3175.6348019977695, 5067.949320506794, 5179.119549676555, 5214.713190774506, 5219.8956020879605, 5266.68399896006, 5175.223716442535, 5248.763805628746, 5276.8153114641, 5313.38896026957, 5322.0, 4717.69334993226, 4744.027474367755, 4796.352492413532, 4848.078865015654, 4865.951340486586, 4803.3995750531185, 4804.375431193957, 4862.416510018803, 4868.488808675092, 4875.171220892456, 4664.603508701747, 4738.099761045391, 4751.928721069186, 4831.541003604687, 4852.417709874802, 4801.01579176268, 4801.73590452525, 4819.349387832633, 4820.0, 4855.514448555152, 4757.809687612491, 4794.568488836021, 4805.783739731721, 4839.322494850724, 4872.0, 4897.559219670246, 4956.231784073474, 4971.1549036663855, 5061.063703214881, 5091.05815424144, 5448.400675925645, 5489.780408783644, 5490.286262785812, 5495.587830912697, 5499.312585926759, 5486.807961721361, 5553.139263414178, 5569.136783798518, 5623.353314368818, 5624.718764061821, 5171.844844654666, 5193.065248255312, 5219.217117432378, 5261.394939581921, 5289.841304760864, 5647.29408823897, 5658.519025882763, 5663.065594176997, 5675.744591493392, 5712.457316554921, 5394.352677678625, 5472.26124473199, 5493.423190565013, 5495.505404513613, 5504.532114770209, 5490.176473529003, 5539.086050801653, 5547.306586676666, 5561.77752889888, 5610.214569960209, 5524.171374293888, 5552.639078459968, 5556.527695145877, 5561.0, 5564.026295398289, 5398.082326004589, 5494.752736126884, 5568.470995255444, 5575.358833734092, 5619.325680918316, 4277.144571085795, 4323.113761678848, 4335.2196604610845, 4376.627986621173, 4380.080183161508, 3844.7693138411787, 3844.942325865114, 3847.7691338519217, 3876.3991581304945, 3877.2439374321734, 5081.72050537224, 5122.154844450624, 5122.92315615266, 5123.948760512421, 5126.256692779526, 5441.347038355343, 5482.342118945672, 5566.415526369754, 5568.303962004749, 5568.832935011957, 5111.977604479119, 5119.334486516729, 5120.5135512127035, 5122.487751224848, 5147.253648221062, 4694.460137084211, 4697.4832768395445, 4703.106409782131, 4737.715737055788, 4762.595179409789, 5646.858828529237, 5690.174924635988, 5722.685252311163, 5723.570732195102, 5776.884462310731, 5524.75138618755, 5614.41048689898, 5625.887482250414, 5628.549716022791, 5633.915491267716, 5653.265075540154, 5654.943450565442, 5658.886822263614, 5678.716064196857, 5701.116326969326, 5291.074062039204, 5373.758180881791, 5427.3215848019, 5435.510804027656, 5445.7549410277425, 5562.137868630369, 5577.219189313419, 5589.888202235851, 5628.831135065954, 5654.123610840326, 5469.288992430958, 5476.698781566893, 5478.808241711521, 5481.314835645544, 5482.23248745168, 5396.379416367168, 5449.34607847045, 5487.64330318528, 5519.3652729936575, 5547.667139971614, 5348.224507446476, 5357.464253574612, 5358.651687640291, 5359.705216212989, 5363.517283444508, 5456.154296084113, 5457.263269458573, 5460.481254280837, 5513.448655134454, 5520.558355331484, 5231.529162375252, 5316.574674026146, 5327.733613319241, 5359.973200133974, 5419.0], "unit": "score", "name": "dojo.html", "value": 5131.800119965522}, {"lowerIsBetter": false, "replicates": [521.6348556010793, 526.1450143516788, 540.308405241291, 547.9369872464666, 555.91383335583, 29494.262643433896, 30225.546616800766, 30232.546511802342, 30262.84868575657, 35019.29961400777, 38961.0, 39337.40993885087, 39355.80322098386, 39358.60641393585, 39404.21191576174, 8477.618507167177, 8560.0, 8628.439151455135, 8629.870551941694, 8633.13668633138, 7476.476646634711, 7643.350315223212, 7644.61776911155, 7651.693932242703, 8395.202455766692, 10239.078482936535, 10369.118624916891, 10378.429186394746, 10448.216383771209, 10480.371177729361, 9970.65102721405, 10116.241281903813, 10143.695689129338, 10144.847827282596, 10149.644762433316, 7550.6602202901, 7719.961400192963, 7728.6522106505345, 9799.16707079899, 9938.055884690943, 39994.60005399938, 40430.0, 40551.0, 40556.0, 40564.594354056375, 3179.2210908327374, 3410.7441941854213, 3987.4218238355274, 4033.455483509718, 4035.152617950234, 27210.0, 27513.31216719578, 27520.0, 27786.305342366602, 28295.858520707472, 3725.9813700931595, 4022.939655905164, 4047.1096358801015, 4072.6334629883454, 4079.836806527765, 254863.0, 255284.0, 255620.0, 256575.0, 256938.0, 53256.467435325925, 53514.0, 53619.0, 53805.73097134489, 53808.46191538034, 61829.38170618326, 62112.378876211566, 62238.0, 62297.06554401688, 62364.688176558826, 15000.024998375071, 15420.0, 15432.691346173238, 15442.0734755915, 15451.227438628248, 11273.943630281796, 11302.886971130349, 11337.489812958436, 11361.829572556253, 11392.886071139348, 19875.0, 20111.49721256951, 20141.09365078575, 20160.899195503927, 20174.89912550428, 7539.849203015909, 7765.92234077652, 7772.805679858049, 7892.881606775903, 7968.243016913384, 891.741394995444, 962.485070487286, 1015.9332700664273, 1045.4877110216091, 1101.862267216598, 28092.43815123727, 28660.14019579416, 31041.0, 32778.67221327804, 32936.34127317488, 945.6028468043438, 992.3153024413132, 1055.7571758495435, 1070.635983765508, 1095.780843831237, 13733.244671543162, 13803.792943105862, 13815.861841381658, 14109.153450792985, 14119.364628591738, 299758.0, 301795.0, 303839.0, 305352.0, 462620.0, 43083.0, 43647.56352436499, 52670.20994685083, 54777.0, 54894.0, 1326.7943468762357, 1329.0696512441136, 1331.068252223428, 1349.5209200733796, 1366.1598117157876, 25714.485710286062, 26085.73914260833, 26294.605580916683, 26370.868145659148, 26480.337991550365, 44354.33468498039, 44999.0, 45226.54773452355, 45247.547524524336, 45331.54668453405, 239882.0, 240669.0, 240796.0, 241344.0, 241567.0, 29319.0, 30007.249818754706, 30303.848480758337, 30595.0, 30627.2343191422, 39382.60617393904, 42073.579264206965, 42240.577594224895, 42489.36265956075, 42666.0, 35046.82476587703, 35824.82087589546, 36316.636833631324, 36693.129099343976, 36753.816230918674, 35231.47152792761, 36228.275434490635, 36257.81871090628, 36399.45400819043, 36422.635773642985, 35565.82217088898, 37936.81031594824, 38923.2215355697, 39011.80494097511, 39039.6096039036, 27871.721282787723, 30685.846570767004, 31285.645913930923, 31385.8430707845, 31397.372052559276, 23903.282901512983, 25060.12289569856, 25197.606227408367, 25512.872435637702, 25623.615645764956], "unit": "score", "name": "ext.html", "value": 18140.549644249477}, {"lowerIsBetter": false, "replicates": [348.42683785173386, 349.97200223982065, 351.46577202651963, 352.07404526096394, 352.1917200025942, 14169.14985100892, 14606.123632582026, 16393.262303196356, 16609.501714948557, 17406.0, 15856.28646710898, 16034.83965160351, 16044.83955160448, 16046.197690115478, 16071.276792544335, 5116.5139311765415, 5389.487998640123, 5391.460853914617, 5396.082665946798, 5397.082495975694, 3946.0, 4058.0260737422955, 5327.520523152915, 5827.475527202551, 5980.820575382745, 5862.3551409345155, 5868.853278668024, 5889.793857214999, 5918.319393269764, 5933.110033494967, 5733.226014488053, 5911.940880591182, 5915.911261331084, 5916.378780228057, 5919.023361145406, 5169.844904652867, 5174.404943431516, 5174.456682048406, 5174.689518628894, 5191.0656081905245, 11594.6521604352, 11610.419479026099, 16249.756253656205, 18329.083545822654, 18958.715619265586, 2008.1866843928233, 2098.1712223671716, 2154.536774593454, 2194.9890250548647, 2215.1139544182297, 9248.722538323862, 9320.114589114024, 9448.763780905463, 11068.0, 12372.948299394562, 1754.140471169118, 1801.5766294920684, 1835.0916296433352, 1861.283405888729, 1888.830005299516, 57975.71012144954, 59857.0, 60638.0, 61200.69399653018, 62110.68944655293, 9428.198603118812, 9522.190613797766, 9524.333296669167, 9653.372530785617, 9671.839379274392, 12466.376681165906, 12531.749365012649, 12571.0, 12585.559505417266, 12603.495860165674, 7107.538010029331, 7155.17715462718, 7174.533655312388, 7379.446541509408, 7415.814604634819, 6568.474522038226, 6649.60102393858, 6657.800265992028, 6675.299093595199, 6914.654267286615, 3962.227365663668, 4127.814248358831, 4131.690123240709, 4166.749995000309, 4172.061286210578, 1663.376233912283, 1679.7816283883017, 1732.2291580246922, 1769.398404542436, 1780.97328540072, 887.5917078144126, 917.6235646530205, 934.4533447933036, 975.6048800235916, 1066.3921564708041, 14239.572812815632, 15785.68428631444, 15914.840851591336, 16240.350385984428, 17120.229589668244, 919.1681528216974, 928.4939707859232, 931.7065124485765, 951.8477043672979, 989.2135752077116, 7703.691852325845, 8953.59708813105, 8991.325650576233, 9030.871141107362, 9044.095590440904, 476769.0, 491521.0, 539254.0, 566695.0, 579096.0, 79075.60462197653, 79826.20173798304, 80836.0, 81628.59185704033, 82013.58993204996, 1125.7861006408757, 1141.0928311992152, 1173.9236949598248, 1190.565443613075, 1203.1457665057733, 14777.482788102365, 15476.380944762084, 15592.220388980733, 15687.1372074537, 15856.207189640703, 33289.334213316084, 33626.0, 34102.31795364029, 34562.654373455945, 34614.0, 45352.54647453615, 46504.18697662792, 47604.2859357103, 48276.27585586149, 51496.0, 9027.593758280762, 9142.222911052635, 9297.372959732154, 9328.113829186217, 9508.762280942756, 11102.222844400814, 11113.83329250078, 11212.27120237182, 11461.48423320936, 16694.66610667804, 13340.599782006555, 13619.0, 14116.576502704935, 14122.858771412566, 14151.64620884486, 13567.728645427234, 14018.0, 14150.929245353707, 14198.148111113367, 14345.64135896611, 14088.084274522123, 14153.221572813594, 14294.857051429353, 14513.201773902536, 14653.267336632913, 8698.47809131454, 9060.0, 9082.591283392396, 9393.107654772786, 9444.763880903032, 9926.205903527845, 11087.889121108685, 11417.771644567229, 14541.927290363481, 14980.026298290577], "unit": "score", "name": "jquery.html", "value": 8563.84245504233}, {"lowerIsBetter": false, "replicates": [1241.205628397826, 1386.362273354257, 1393.8466768655449, 1513.4400271899399, 1749.6150846813705, 6338.968305158473, 6934.479914006451, 7113.146422429316, 7122.109736282965, 7138.393236574884, 6934.479914006444, 7047.964760176204, 7054.294570542943, 7071.717131314754, 7114.608696521689, 1943.4849764812336, 1985.5830275642134, 2122.7240458740343, 2154.795294447029, 2190.342897130862, 2169.305822136917, 2172.804447599716, 2173.4131784418196, 2179.0521123311332, 2180.4766855954535, 2075.0, 2169.078141789741, 2169.5443956769095, 2169.945751356213, 2176.1948079210633, 2124.5538436928264, 2126.457753272913, 2150.0969592771075, 2189.5292512109813, 2190.255313193506, 2162.535054963175, 2168.7722789107074, 2201.933941981743, 2204.129368899292, 2205.7353117625826, 7246.637668116573, 7391.22392148822, 7401.148867880155, 7405.148407933103, 7405.333519983227, 4369.759663218521, 4438.045820148651, 4443.244648409745, 4463.218936686067, 4466.977665111654, 2882.7117288271215, 2936.5595160725848, 2942.691017443159, 2955.0, 3009.0371081254007, 939.3940908114228, 949.1979277510561, 959.4818797849153, 961.4519723757461, 992.9404235745806, 9878.0, 9918.851217231748, 9930.155936745385, 9941.502924853728, 9958.900410995942, 3933.4296527003426, 3959.425883246913, 4028.7582745035393, 4031.3952907064177, 4063.227986682521, 8669.046404895456, 8679.218870301704, 8686.913130868737, 8687.565621719015, 8692.087330830298, 4483.708558943724, 4483.887902802456, 4485.439320084989, 4485.46174459067, 4490.910181796346, 4065.715399921977, 4070.0, 4070.3283958146576, 4070.3283958147167, 4074.5517993020744, 3261.8369081546302, 3304.2235074757414, 3468.6531346865113, 3509.666581674737, 3522.894313170609, 1093.1364222264322, 1102.779444111181, 1112.9666110016713, 1115.1469126118525, 1115.9497822598003, 1094.7372630568607, 1276.6042526816532, 1342.7851543752952, 1368.5620601407652, 1371.0334214378838, 6845.212800528003, 6920.273371295942, 6921.515493915478, 6929.722811087601, 6933.5493192942295, 1107.7673688525338, 1113.7494063835572, 1122.680036189682, 1130.2088538023415, 1144.8053830848776, 5540.556755459554, 5594.1888426177975, 5620.381758006616, 5634.802781902614, 5636.126400407943, 8514.106018868053, 8773.341999350074, 8803.383763136648, 8951.0, 8951.641934322553, 8765.430247033923, 8770.649174033095, 8831.337649676172, 8837.072107428756, 8852.424592401474, 6811.25076241613, 6831.6242606656115, 6978.267281935425, 6982.825429364307, 6995.405390541861, 6667.066610674607, 6708.463322934251, 6711.798646040626, 6721.731130754911, 6722.226943901514, 7801.414893882868, 7896.605169741605, 7908.327792137733, 8076.192380761877, 8089.514629122272, 8041.718539851077, 8130.105688374392, 8240.876386854321, 8242.670293188205, 8270.503769773833, 6541.901871471837, 6562.8359291016195, 6570.5400621956005, 6584.8024559263295, 6673.532852700265, 6281.937180628135, 6393.93606063952, 6808.978653202059, 6941.444684425335, 6954.652267386509, 6567.934320656924, 6671.66641667905, 6720.462363011045, 6735.562188457735, 6792.796216113525, 6558.0, 6593.142891424084, 6604.537682362188, 6682.231543372574, 6700.865982680221, 6616.073749674953, 6620.536562440583, 6642.867142657216, 6672.165979252593, 6681.632510211985, 6136.570440069152, 6140.293866205265, 6142.815715528542, 6158.507319414346, 6160.414760597736, 6137.539684523768, 6178.289496707936, 6199.070139479114, 6206.224221972254, 6212.4408803207925], "unit": "score", "name": "mootools.html", "value": 4263.300001981664}, {"lowerIsBetter": false, "replicates": [317.98728050877963, 321.1009174311926, 329.17705735660843, 329.81760389005416, 337.2227016726445, 5827.4463925927075, 5842.912356314653, 5855.882882342361, 5856.619319744219, 5901.203337549429, 5502.174673798922, 5572.498475137237, 5681.630694004897, 5731.713414329288, 5777.797777077803, 4497.482789479202, 4501.909961800779, 4512.97743511282, 4528.796204170803, 4535.274356103024, 4303.859477238534, 4524.049949510607, 4526.977365113186, 4531.365608814768, 4547.499775024745, 4354.869353919387, 4361.302191649337, 4445.666575006888, 4449.0, 4464.620507256887, 4382.408374869384, 4424.823007079713, 4433.357163211349, 4434.935615452268, 4441.489228738704, 3914.6868250539887, 3914.7651140931353, 3919.7452165609143, 3920.039590300366, 3925.0187453136714, 5487.533559647435, 5517.862053448655, 5528.281323427969, 5549.750261238254, 5570.359408668014, 5169.534741873212, 5180.4042535108565, 5180.533751962342, 5187.170052791573, 5194.7662355193725, 6020.5785595008365, 6081.452669259788, 6086.573939824214, 6087.786927457518, 6090.847728806815, 4340.891477713051, 4350.499692535367, 4353.0, 4354.216241076605, 4354.847580334673, 6136.325004249529, 6301.52738544611, 6329.272133704637, 6362.968185159091, 6366.586171898812, 4329.480462344539, 4353.368761529624, 4363.803628836647, 4364.8908777280185, 4407.6914615976575, 4407.691461597721, 4422.800973956179, 4423.778811059434, 4426.18115648606, 4428.247197976351, 5121.564667003272, 5123.333966584395, 5133.589312854962, 5136.717480538607, 5143.6656617320505, 6009.06859436788, 6076.696165191723, 6147.938520614736, 6151.200343955257, 6154.076888466766, 1555.4944642991072, 1564.8982816116916, 1567.2163918040978, 1567.8275389707123, 1570.2541292885787, 1032.8192566300868, 1035.570238351091, 1057.049743223751, 1057.9418132002659, 1058.2962330050584, 5836.241288632451, 5843.18195452637, 5848.005839007379, 5849.064149736022, 5852.619579727304, 6348.460380867678, 6351.587146835441, 6361.9502782040445, 6363.522735794834, 6372.0760489729655, 5635.436456354331, 5716.056850619684, 5817.447342502455, 5912.497437717756, 5918.260217472816, 3433.5021421893684, 3452.7583069184916, 3453.5855697316483, 3456.5160877477174, 3457.6715212054814, 6649.168853893263, 6661.700223489857, 6667.733290668416, 6671.933280667228, 6679.666016699146, 6433.388828061326, 6577.769778057745, 6586.176727909012, 6588.308227636125, 6591.6374599397495, 6637.203535575762, 6656.96671516649, 6662.96668516664, 6668.766593169215, 6672.766453174213, 6405.263394709668, 6410.326915673973, 6413.679316034273, 6416.358364163546, 6419.0, 6186.381361863778, 6305.4009869062365, 6305.653189074645, 6307.085472606354, 6309.652969086745, 6605.306442823435, 6635.502337324817, 6643.0, 6645.269020407848, 6652.866942661217, 4402.515723270501, 4406.383106365176, 4407.427034485497, 4408.272635015187, 4410.33844923264, 6242.469390101892, 6410.166678331787, 6422.389872962061, 6430.3891130342545, 6447.3552644735155, 6244.157038799885, 6251.562390632612, 6254.8123556293385, 6256.0, 6257.5932564383165, 6207.81376558704, 6247.375262473716, 6254.343293954069, 6256.280527739368, 6261.96869015652, 6025.337212906665, 6105.572609917263, 6127.877442451215, 6129.601575897731, 6142.662153581596, 6220.222472190976, 6253.530985176039, 6263.812085637438, 6265.248170219603, 6265.780697675559, 6117.204763380732, 6119.173911522065, 6122.051082082285, 6132.540059495466, 6133.877322453437], "unit": "score", "name": "prototype.html", "value": 4663.816220055334}, {"lowerIsBetter": false, "replicates": [697.532653122408, 718.9748358807443, 751.7293774241275, 796.3032346696641, 797.7646594254696, 13485.325733713324, 13749.725005499884, 13841.93079034606, 13927.582172534814, 13940.721185576282, 12779.424925878335, 12933.870661293384, 12936.611901642966, 12961.35193240339, 12965.481380744783, 686.8248596607883, 695.8886578147498, 812.6708682983401, 826.409117481001, 827.755812035448, 812.5937031484258, 819.1849110135423, 824.3528829868554, 825.1294883897485, 825.9091499935003, 862.5687156421789, 866.0343716755804, 868.7306934850217, 871.2724874729577, 872.5419154943642, 718.0127324928224, 719.9784006479788, 848.4569875279826, 864.1962974433774, 867.1068799136899, 633.8256979330674, 636.8789929913302, 636.8917284061674, 637.0985056145548, 637.6174295422755, 13157.671058223525, 13265.535706250332, 13309.134906231064, 13337.73324533504, 13354.26551539665, 11184.496697648625, 11506.654800355918, 11645.359505227209, 11739.7065073373, 11919.761604767942, 10112.848307275397, 10175.389476631426, 10196.89803101967, 10297.485125743759, 10328.845067323997, 3655.597884232732, 3682.2635472905527, 3712.9814350928073, 3720.6837418819164, 3795.2788970095594, 45303.0, 45333.0, 46309.5369046312, 46461.07077858424, 46507.069858602976, 9714.902850971399, 9778.608855645694, 9798.31411801167, 9853.113219810108, 9973.30186886911, 18720.34478793263, 18759.624807503773, 18766.155523001496, 18773.530661733294, 18778.906105469658, 7594.772156835304, 7661.195574464601, 7670.7315243966195, 7704.345130663956, 7813.531188128731, 4108.322126849036, 4115.8765237042935, 4162.729422587522, 4178.206140833232, 4298.957010429918, 3119.32934419101, 3151.7163455289133, 3158.7788854779947, 3167.192365946669, 3176.348848486055, 549.2502733768457, 570.9286339207599, 572.816698656426, 572.9512991395694, 575.726529898295, 460.7028466638999, 516.9974150129301, 543.6221825831088, 594.4917095883044, 630.2972186012655, 7085.716571337191, 7444.5060904596485, 7450.18048014718, 7480.17718051014, 7968.641411136513, 552.8947564334798, 601.5758889982552, 607.2012282700196, 609.6585911889356, 623.9598599071069, 3589.5692516897616, 3630.546181727284, 3643.307771523402, 3758.5113935188797, 3763.849446022183, 45268.77365613151, 45409.77295113503, 45859.54140458619, 46049.76975115103, 46507.30239046417, 46752.5324746755, 49370.0, 49503.78225415337, 49563.0, 49615.50384496181, 44902.77548612236, 45042.77478612586, 45389.319160212624, 45645.31532027022, 45975.0, 7408.111026676834, 7525.39909304956, 7600.885986710093, 7624.313811756969, 7796.571188584456, 32025.0, 32325.35349293048, 32829.671703282664, 33502.83248583742, 33682.0, 40419.79790101148, 43465.78267108644, 43957.78021109874, 44068.33897491604, 44629.55370446254, 9612.231021518402, 9735.09239046293, 9803.460809655537, 9834.0, 10108.54511546997, 9816.705498835046, 10080.445575493124, 10131.898681013095, 10327.070563649308, 10349.689509314732, 9318.72043838686, 9543.284253680862, 9604.327697061419, 10115.94706656387, 10290.3825770454, 9975.850362244717, 10045.49772511357, 10247.231457640557, 10259.179265658879, 10543.051125398752, 9178.678746244117, 9342.766430839283, 9665.855012174963, 9715.854262185932, 9754.0, 8671.739847804576, 8829.470231785857, 9064.0, 9166.404183728037, 9183.816323673356, 7922.7227047053075, 8315.459495132796, 8327.583620818814, 8966.775830604287, 9055.773605659911], "unit": "score", "name": "yui.html", "value": 5954.264370545068}], "name": "dromaeo_css", "value": 6747.727738995868}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 105.0, 110.0, 112.0, 112.0, 101.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 109.5}, {"lowerIsBetter": true, "replicates": [154.0, 126.0, 153.0, 162.0, 127.0, 157.0, 154.0, 127.0, 166.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 145.2}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 163.0, 162.0, 161.0, 161.0, 160.0, 161.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.3}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 89.0, 91.0, 75.0, 76.0, 71.0, 126.0, 99.0, 73.0], "unit": "score", "name": "audio-fft", "value": 84.6}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 137.0, 95.0, 97.0, 96.0, 137.0, 97.0, 96.0, 145.0], "unit": "score", "name": "audio-oscillator", "value": 109.1}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 111.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 144.0, 146.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 60.0, 58.0, 58.0, 59.0, 57.0, 67.0, 55.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.7}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 72.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 85.0, 85.0, 96.0, 86.0, 87.0, 85.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.8}, {"lowerIsBetter": true, "replicates": [122.0, 134.0, 121.0, 119.0, 122.0, 120.0, 125.0, 118.0, 120.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.7}, {"lowerIsBetter": true, "replicates": [151.0, 140.0, 151.0, 151.0, 152.0, 140.0, 155.0, 151.0, 153.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.5}, {"lowerIsBetter": true, "replicates": [70.0, 69.0, 69.0, 68.0, 69.0, 67.0, 68.0, 68.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1513.1000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.39203213927016], "unit": "score", "name": "Richards", "value": 210.89414799999994}, {"lowerIsBetter": false, "replicates": [191.3143294432753], "unit": "score", "name": "DeltaBlue", "value": 345.5987859999999}, {"lowerIsBetter": false, "replicates": [280.8199943836001], "unit": "score", "name": "Encrypt", "value": 947.8705409999999}, {"lowerIsBetter": false, "replicates": [5256.544502617801], "unit": "score", "name": "Decrypt", "value": 50.6380189243028}, {"lowerIsBetter": false, "replicates": [1978.2608695652175], "unit": "score", "name": "RayTrace", "value": 374.06037362637363}, {"lowerIsBetter": false, "replicates": [796.9745222929936], "unit": "score", "name": "Earley", "value": 836.2412867132866}, {"lowerIsBetter": false, "replicates": [12481.481481481482], "unit": "score", "name": "Boyer", "value": 53.39614540059344}, {"lowerIsBetter": false, "replicates": [26368.42105263158], "unit": "score", "name": "RegExp", "value": 34.54833333333334}, {"lowerIsBetter": false, "replicates": [587.1990604815032], "unit": "score", "name": "Splay", "value": 138.77917300000004}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 19726.32956360406}]} 22:20:24 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "19726.33"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1513.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6747.73"}}} 22:20:24 INFO - Return code: 0 22:20:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:20:24 INFO - # TBPL SUCCESS # 22:20:24 INFO - Running post-action listener: _resource_record_post_action 22:20:24 INFO - Running post-run listener: _resource_record_post_run 22:20:25 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 0; Write bytes: 574300160; Read time: 0; Write time: 760704 22:20:25 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 238845952; Read time: 0; Write time: 520152 22:20:25 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 0; Write bytes: 320053248; Read time: 0; Write time: 232440 22:20:25 INFO - Running post-run listener: _upload_blobber_files 22:20:25 INFO - Blob upload gear active. 22:20:25 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:20:25 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 22:20:25 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', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 22:20:25 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 Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 22:20:26 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:20:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:27 INFO - (blobuploader) - INFO - Done attempting. 22:20:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:20:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:20:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:27 INFO - (blobuploader) - INFO - Done attempting. 22:20:27 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:27 INFO - Return code: 0 22:20:27 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:20:27 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e082b3b4665926bc975fb1c5d6c136a77555741764d033ab93daf904f397debe3f4c78ae2c2bd111a4b19c2dc91d378ea46a275bd21f0559e833e2213bb77ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/19d27b3beec895ce10a672f121ffa3477425637476c849005c3ff95b87107533c8a8e407b113579d4c2006db30fe24585fd81c0590538d871bd3b2cff64634da"} 22:20:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:20:27 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:20:27 INFO - Contents: 22:20:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e082b3b4665926bc975fb1c5d6c136a77555741764d033ab93daf904f397debe3f4c78ae2c2bd111a4b19c2dc91d378ea46a275bd21f0559e833e2213bb77ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/19d27b3beec895ce10a672f121ffa3477425637476c849005c3ff95b87107533c8a8e407b113579d4c2006db30fe24585fd81c0590538d871bd3b2cff64634da"} 22:20:27 INFO - Copying logs to upload dir... 22:20:27 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.437897 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-01-05 22:20:27.666545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-05 22:20:27.669985) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e082b3b4665926bc975fb1c5d6c136a77555741764d033ab93daf904f397debe3f4c78ae2c2bd111a4b19c2dc91d378ea46a275bd21f0559e833e2213bb77ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/19d27b3beec895ce10a672f121ffa3477425637476c849005c3ff95b87107533c8a8e407b113579d4c2006db30fe24585fd81c0590538d871bd3b2cff64634da"} build_url:https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/d-5R8pj5T8mxwR-yyL61nw/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/Mozilla-Inbound/sha512/e082b3b4665926bc975fb1c5d6c136a77555741764d033ab93daf904f397debe3f4c78ae2c2bd111a4b19c2dc91d378ea46a275bd21f0559e833e2213bb77ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/19d27b3beec895ce10a672f121ffa3477425637476c849005c3ff95b87107533c8a8e407b113579d4c2006db30fe24585fd81c0590538d871bd3b2cff64634da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-05 22:20:27.696514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 22:20:27.696840) ========= 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=f919189b672bc2eeae365d21000001d6-1452059962.43747-142941163 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004403 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 22:20:27.778892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 22:20:27.779218) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 22:20:27.779520) ========= ========= Total master_lag: 0.23 =========