builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-034 starttime: 1453270095.76 results: success (0) buildid: 20160119193121 builduid: 7b06b4884ae944628429d845c469dd2a revision: 2f8be7ffe2df8d40ca5099755ce999590916d8aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.764346) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.764735) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.765019) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006355 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.820439) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.820733) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.882516) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.882786) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.935107) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.935368) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.935702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:15.935968) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-19 22:08:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 84.5M=0s 2016-01-19 22:08:16 (84.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.123168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.123494) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022239 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.210045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.210435) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 2f8be7ffe2df8d40ca5099755ce999590916d8aa --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 2f8be7ffe2df8d40ca5099755ce999590916d8aa --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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-19 22:08:16,585 truncating revision to first 12 chars 2016-01-19 22:08:16,585 Setting DEBUG logging. 2016-01-19 22:08:16,585 attempt 1/10 2016-01-19 22:08:16,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/2f8be7ffe2df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-19 22:08:16,835 unpacking tar archive at: mozilla-inbound-2f8be7ffe2df/testing/mozharness/ program finished with exit code 0 elapsedTime=0.556285 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.779449) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.779751) ========= echo '%(revision)s' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', '%(revision)s'] 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False %(revision)s program finished with exit code 0 elapsedTime=0.004715 script_repo_revision: '%(revision)s' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.830051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.830348) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.840205) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.840564) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/%(revision)s ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-19 22:08:16.841022) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-01-19 22:08:16.841302) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:08:17 INFO - MultiFileLogger online at 20160119 22:08:17 in /builds/slave/test-pgo 22:08:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:08:17 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:08:17 INFO - {'append_to_log': False, 22:08:17 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:08:17 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:08:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:08:17 INFO - 'branch': 'Mozilla-Inbound', 22:08:17 INFO - 'buildbot_json_path': 'buildprops.json', 22:08:17 INFO - 'config_files': ('talos/linux_config.py',), 22:08:17 INFO - 'default_actions': ('clobber', 22:08:17 INFO - 'read-buildbot-config', 22:08:17 INFO - 'download-and-extract', 22:08:17 INFO - 'populate-webroot', 22:08:17 INFO - 'create-virtualenv', 22:08:17 INFO - 'install', 22:08:17 INFO - 'run-tests'), 22:08:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:08:17 INFO - 'download_minidump_stackwalk': True, 22:08:17 INFO - 'download_symbols': 'ondemand', 22:08:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:08:17 INFO - 'tooltool.py': '/tools/tooltool.py', 22:08:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:08:17 INFO - '/tools/misc-python/virtualenv.py')}, 22:08:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:08:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:08:17 INFO - 'installer_path': 'installer.exe', 22:08:17 INFO - 'log_level': 'info', 22:08:17 INFO - 'log_name': 'talos', 22:08:17 INFO - 'log_to_console': True, 22:08:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:08:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:08:17 INFO - 'opt_config_files': (), 22:08:17 INFO - 'pip_index': False, 22:08:17 INFO - 'sps_profile': False, 22:08:17 INFO - 'sps_profile_interval': 0, 22:08:17 INFO - 'suite': 'dromaeojs-e10s', 22:08:17 INFO - 'system_bits': '32', 22:08:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:08:17 INFO - 'title': 'talos-linux64-ix-034', 22:08:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:08:17 INFO - 'use_talos_json': True, 22:08:17 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:08:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:08:17 INFO - 'work_dir': 'build'} 22:08:17 INFO - ##### 22:08:17 INFO - ##### Running clobber step. 22:08:17 INFO - ##### 22:08:17 INFO - Running pre-action listener: _resource_record_pre_action 22:08:17 INFO - Running main action method: clobber 22:08:17 INFO - rmtree: /builds/slave/test-pgo/build 22:08:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:08:17 INFO - Running post-action listener: _resource_record_post_action 22:08:17 INFO - ##### 22:08:17 INFO - ##### Running read-buildbot-config step. 22:08:17 INFO - ##### 22:08:17 INFO - Running pre-action listener: _resource_record_pre_action 22:08:17 INFO - Running main action method: read_buildbot_config 22:08:17 INFO - Using buildbot properties: 22:08:17 INFO - { 22:08:17 INFO - "project": "", 22:08:17 INFO - "product": "firefox", 22:08:17 INFO - "script_repo_revision": "production", 22:08:17 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:08:17 INFO - "repository": "", 22:08:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:08:17 INFO - "buildid": "20160119193121", 22:08:17 INFO - "pgo_build": "True", 22:08:17 INFO - "basedir": "/builds/slave/test-pgo", 22:08:17 INFO - "buildnumber": 265, 22:08:17 INFO - "slavename": "talos-linux64-ix-034", 22:08:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:08:17 INFO - "platform": "ubuntu64_hw", 22:08:17 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:08:17 INFO - "branch": "mozilla-inbound", 22:08:17 INFO - "repo_path": "integration/mozilla-inbound", 22:08:17 INFO - "revision": "2f8be7ffe2df8d40ca5099755ce999590916d8aa", 22:08:17 INFO - "stage_platform": "linux64-pgo", 22:08:17 INFO - "builduid": "7b06b4884ae944628429d845c469dd2a", 22:08:17 INFO - "slavebuilddir": "test" 22:08:17 INFO - } 22:08:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:08:17 INFO - Running post-action listener: _resource_record_post_action 22:08:17 INFO - ##### 22:08:17 INFO - ##### Running download-and-extract step. 22:08:17 INFO - ##### 22:08:17 INFO - Running pre-action listener: _resource_record_pre_action 22:08:17 INFO - Running main action method: download_and_extract 22:08:17 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:17 INFO - https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:08:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/test_packages.json 22:08:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/test_packages.json 22:08:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:08:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:08:27 INFO - Downloaded 1448 bytes. 22:08:27 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:08:27 INFO - Using the following test package requirements: 22:08:27 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:27 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:08:27 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:08:27 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'jsshell-linux-x86_64.zip'], 22:08:27 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:08:27 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:27 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:08:27 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:08:27 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:08:27 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:08:27 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:08:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:08:27 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:27 INFO - https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:08:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:08:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:34 INFO - Downloaded 22484638 bytes. 22:08:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:08:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:08:35 INFO - Return code: 0 22:08:35 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 22:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:35 INFO - https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:36 INFO - Downloaded 22484638 bytes. 22:08:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:08:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 22:08:38 INFO - Return code: 0 22:08:38 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:08:38 INFO - https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:39 INFO - Downloaded 11132144 bytes. 22:08:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 22:08:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 22:08:40 INFO - Return code: 0 22:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:40 INFO - https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:08:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:08:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:08:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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 22:08:43 INFO - Downloaded 55956722 bytes. 22:08:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:08:43 INFO - mkdir: /builds/slave/test-pgo/properties 22:08:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:08:43 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:08:43 INFO - Contents: 22:08:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:08:43 INFO - Running post-action listener: _resource_record_post_action 22:08:43 INFO - Running post-action listener: set_extra_try_arguments 22:08:43 INFO - ##### 22:08:43 INFO - ##### Running populate-webroot step. 22:08:43 INFO - ##### 22:08:43 INFO - Running pre-action listener: _resource_record_pre_action 22:08:43 INFO - Running main action method: populate_webroot 22:08:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:43 INFO - '--tppagecycles', 22:08:43 INFO - '7'], 22:08:43 INFO - 'tests': ['tsvgm']}}, 22:08:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:08:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:08:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:08:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'tests': ['damp', 'tps']}, 22:08:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['damp', 'tps']}, 22:08:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:08:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:43 INFO - 'other': {'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'other_l64': {'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'other_nol64': {'tests': ['a11yr', 22:08:43 INFO - 'ts_paint', 22:08:43 INFO - 'tpaint', 22:08:43 INFO - 'sessionrestore', 22:08:43 INFO - 'sessionrestore_no_auto_restore']}, 22:08:43 INFO - 'svgr': {'tests': ['tsvgx', 22:08:43 INFO - 'tsvgr_opacity', 22:08:43 INFO - 'tart', 22:08:43 INFO - 'tscrollx', 22:08:43 INFO - 'cart']}, 22:08:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['tsvgx', 22:08:43 INFO - 'tsvgr_opacity', 22:08:43 INFO - 'tart', 22:08:43 INFO - 'tscrollx', 22:08:43 INFO - 'cart']}, 22:08:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'tests': ['tp5o']}, 22:08:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'talos_options': ['--e10s'], 22:08:43 INFO - 'tests': ['tp5o']}, 22:08:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'talos_options': ['--xperf_path', 22:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:43 INFO - 'tests': ['tp5n']}, 22:08:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:08:43 INFO - 'talos_options': ['--e10s', 22:08:43 INFO - '--xperf_path', 22:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:43 INFO - 'tests': ['tp5n']}}, 22:08:43 INFO - 'talos.zip': {'path': '', 22:08:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:43 INFO - Running post-action listener: _resource_record_post_action 22:08:43 INFO - ##### 22:08:43 INFO - ##### Running create-virtualenv step. 22:08:43 INFO - ##### 22:08:43 INFO - Running pre-action listener: _resource_record_pre_action 22:08:43 INFO - Running main action method: create_virtualenv 22:08:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:08:43 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 22:08:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:08:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:43 INFO - Using real prefix '/usr' 22:08:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:08:44 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:46 INFO - Installing pip.................done. 22:08:46 INFO - Return code: 0 22:08:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08:46 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 22:08:46 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 22:08:46 INFO - Using env: {'DISPLAY': ':0', 22:08:46 INFO - 'HOME': '/home/cltbld', 22:08:46 INFO - 'LANG': 'en_US.UTF-8', 22:08:46 INFO - 'LANGUAGE': 'en_US:en', 22:08:46 INFO - 'LOGNAME': 'cltbld', 22:08:46 INFO - 'MAIL': '/var/mail/cltbld', 22:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:46 INFO - 'MOZ_NO_REMOTE': '1', 22:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:46 INFO - 'NO_EM_RESTART': '1', 22:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:46 INFO - 'SHELL': '/bin/bash', 22:08:46 INFO - 'SHLVL': '1', 22:08:46 INFO - 'TERM': 'linux', 22:08:46 INFO - 'TMOUT': '86400', 22:08:46 INFO - 'USER': 'cltbld', 22:08:46 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:46 INFO - '_': '/tools/buildbot/bin/python'} 22:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:46 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 22:08:46 INFO - Cleaning up... 22:08:46 INFO - Return code: 0 22:08:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08:46 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 22:08:46 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 22:08:46 INFO - Using env: {'DISPLAY': ':0', 22:08:46 INFO - 'HOME': '/home/cltbld', 22:08:46 INFO - 'LANG': 'en_US.UTF-8', 22:08:46 INFO - 'LANGUAGE': 'en_US:en', 22:08:46 INFO - 'LOGNAME': 'cltbld', 22:08:46 INFO - 'MAIL': '/var/mail/cltbld', 22:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:46 INFO - 'MOZ_NO_REMOTE': '1', 22:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:46 INFO - 'NO_EM_RESTART': '1', 22:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:46 INFO - 'SHELL': '/bin/bash', 22:08:46 INFO - 'SHLVL': '1', 22:08:46 INFO - 'TERM': 'linux', 22:08:46 INFO - 'TMOUT': '86400', 22:08:46 INFO - 'USER': 'cltbld', 22:08:46 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:46 INFO - '_': '/tools/buildbot/bin/python'} 22:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:46 INFO - Downloading/unpacking psutil>=0.7.1 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:48 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:08:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:08:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:48 INFO - Installing collected packages: psutil 22:08:48 INFO - Running setup.py install for psutil 22:08:48 INFO - building 'psutil._psutil_linux' extension 22:08:48 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 22:08:48 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 22:08:48 INFO - building 'psutil._psutil_posix' extension 22:08:48 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 22:08:48 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 22:08:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:48 INFO - Successfully installed psutil 22:08:48 INFO - Cleaning up... 22:08:48 INFO - Return code: 0 22:08:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 22:08:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:08:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:08:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:08:50 INFO - Installing collected packages: mozsystemmonitor 22:08:50 INFO - Running setup.py install for mozsystemmonitor 22:08:50 INFO - Successfully installed mozsystemmonitor 22:08:50 INFO - Cleaning up... 22:08:51 INFO - Return code: 0 22:08:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:08: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')]} 22:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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', '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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 22:08: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 blobuploader==1.2.4 22:08:51 INFO - Using env: {'DISPLAY': ':0', 22:08:51 INFO - 'HOME': '/home/cltbld', 22:08:51 INFO - 'LANG': 'en_US.UTF-8', 22:08:51 INFO - 'LANGUAGE': 'en_US:en', 22:08:51 INFO - 'LOGNAME': 'cltbld', 22:08:51 INFO - 'MAIL': '/var/mail/cltbld', 22:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:51 INFO - 'MOZ_NO_REMOTE': '1', 22:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:51 INFO - 'NO_EM_RESTART': '1', 22:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:51 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:51 INFO - 'SHELL': '/bin/bash', 22:08:51 INFO - 'SHLVL': '1', 22:08:51 INFO - 'TERM': 'linux', 22:08:51 INFO - 'TMOUT': '86400', 22:08:51 INFO - 'USER': 'cltbld', 22:08:51 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:51 INFO - '_': '/tools/buildbot/bin/python'} 22:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:51 INFO - Downloading/unpacking blobuploader==1.2.4 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:08:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:08:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:08:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08:53 INFO - Downloading docopt-0.6.1.tar.gz 22:08:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:08:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:53 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:53 INFO - Running setup.py install for blobuploader 22:08:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:08:53 INFO - Running setup.py install for requests 22:08:54 INFO - Running setup.py install for docopt 22:08:54 INFO - Successfully installed blobuploader requests docopt 22:08:54 INFO - Cleaning up... 22:08:54 INFO - Return code: 0 22:08:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:54 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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08:54 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 22:08:54 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 22:08:54 INFO - Using env: {'DISPLAY': ':0', 22:08:54 INFO - 'HOME': '/home/cltbld', 22:08:54 INFO - 'LANG': 'en_US.UTF-8', 22:08:54 INFO - 'LANGUAGE': 'en_US:en', 22:08:54 INFO - 'LOGNAME': 'cltbld', 22:08:54 INFO - 'MAIL': '/var/mail/cltbld', 22:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:54 INFO - 'MOZ_NO_REMOTE': '1', 22:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:54 INFO - 'NO_EM_RESTART': '1', 22:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:54 INFO - 'SHELL': '/bin/bash', 22:08:54 INFO - 'SHLVL': '1', 22:08:54 INFO - 'TERM': 'linux', 22:08:54 INFO - 'TMOUT': '86400', 22:08:54 INFO - 'USER': 'cltbld', 22:08:54 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:54 INFO - '_': '/tools/buildbot/bin/python'} 22:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:08:54 INFO - Running setup.py (path:/tmp/pip-d4ocKz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:08:54 INFO - Running setup.py (path:/tmp/pip-jcpbtj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:08:54 INFO - Running setup.py (path:/tmp/pip-ltQx5O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:08:54 INFO - Running setup.py (path:/tmp/pip-0gUUgA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:08:54 INFO - Running setup.py (path:/tmp/pip-y5DRng-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:08:54 INFO - Running setup.py (path:/tmp/pip-QkUjjC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:08:54 INFO - Running setup.py (path:/tmp/pip-q4Ooxr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:08:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:08:54 INFO - Running setup.py (path:/tmp/pip-1gwhdD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:08:55 INFO - Running setup.py (path:/tmp/pip-Cmv2xF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:08:55 INFO - Running setup.py (path:/tmp/pip-52gQo7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:08:55 INFO - Running setup.py (path:/tmp/pip-kMBWIG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:08:55 INFO - Running setup.py (path:/tmp/pip-URResp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:08:55 INFO - Running setup.py (path:/tmp/pip-CChm8T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:08:55 INFO - Running setup.py (path:/tmp/pip-Uf1zyo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:08:55 INFO - Running setup.py (path:/tmp/pip-Rz7eIS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:08:55 INFO - Running setup.py (path:/tmp/pip-b0XJvY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:08:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:08:55 INFO - Running setup.py (path:/tmp/pip-z1FPvD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:08:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:55 INFO - Running setup.py install for manifestparser 22:08:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:08:55 INFO - Running setup.py install for mozcrash 22:08:55 INFO - Running setup.py install for mozdebug 22:08:55 INFO - Running setup.py install for mozdevice 22:08:56 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for mozfile 22:08:56 INFO - Running setup.py install for mozhttpd 22:08:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for mozinfo 22:08:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for mozInstall 22:08:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for mozleak 22:08:56 INFO - Running setup.py install for mozlog 22:08:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for moznetwork 22:08:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:08:56 INFO - Running setup.py install for mozprocess 22:08:56 INFO - Running setup.py install for mozprofile 22:08:57 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:08:57 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:08:57 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:08:57 INFO - Running setup.py install for mozrunner 22:08:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:08:57 INFO - Running setup.py install for mozscreenshot 22:08:57 INFO - Running setup.py install for moztest 22:08:57 INFO - Running setup.py install for mozversion 22:08:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:08:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:57 INFO - Cleaning up... 22:08:57 INFO - Return code: 0 22:08:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:57 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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:08:57 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 22:08:57 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 22:08:57 INFO - Using env: {'DISPLAY': ':0', 22:08:57 INFO - 'HOME': '/home/cltbld', 22:08:57 INFO - 'LANG': 'en_US.UTF-8', 22:08:57 INFO - 'LANGUAGE': 'en_US:en', 22:08:57 INFO - 'LOGNAME': 'cltbld', 22:08:57 INFO - 'MAIL': '/var/mail/cltbld', 22:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:57 INFO - 'MOZ_NO_REMOTE': '1', 22:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:57 INFO - 'NO_EM_RESTART': '1', 22:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:08:57 INFO - 'PWD': '/builds/slave/test-pgo', 22:08:57 INFO - 'SHELL': '/bin/bash', 22:08:57 INFO - 'SHLVL': '1', 22:08:57 INFO - 'TERM': 'linux', 22:08:57 INFO - 'TMOUT': '86400', 22:08:57 INFO - 'USER': 'cltbld', 22:08:57 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:57 INFO - '_': '/tools/buildbot/bin/python'} 22:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:08:57 INFO - Running setup.py (path:/tmp/pip-gx8jiY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:08:57 INFO - Running setup.py (path:/tmp/pip-BAtOLp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:08:57 INFO - Running setup.py (path:/tmp/pip-Tpqqfa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:08:57 INFO - Running setup.py (path:/tmp/pip-3tW5c9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:08:58 INFO - Running setup.py (path:/tmp/pip-qB_wKN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:08:58 INFO - Running setup.py (path:/tmp/pip-f19n18-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:08:58 INFO - Running setup.py (path:/tmp/pip-jw2UXb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:08:58 INFO - Running setup.py (path:/tmp/pip-fUTpyi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:08:58 INFO - Running setup.py (path:/tmp/pip-Ms1Tgr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:08:58 INFO - Running setup.py (path:/tmp/pip-_5GyNN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:08:58 INFO - Running setup.py (path:/tmp/pip-fkTN0T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:08:58 INFO - Running setup.py (path:/tmp/pip-B18BFO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:08:58 INFO - Running setup.py (path:/tmp/pip-AaTizs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:08:58 INFO - Running setup.py (path:/tmp/pip-7t4gG7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:08:58 INFO - Running setup.py (path:/tmp/pip-9k8EyI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:08:58 INFO - Running setup.py (path:/tmp/pip-MGggAI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:08:58 INFO - Running setup.py (path:/tmp/pip-bLfuqs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:08:58 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)) 22:08:58 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)) 22:08:58 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)) 22:08:58 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)) 22:08:58 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:08:58 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:08:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:00 INFO - Downloading blessings-1.5.1.tar.gz 22:09:00 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 22:09:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:00 INFO - Installing collected packages: blessings 22:09:00 INFO - Running setup.py install for blessings 22:09:00 INFO - Successfully installed blessings 22:09:00 INFO - Cleaning up... 22:09:00 INFO - Return code: 0 22:09:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:09:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:09:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:09:01 INFO - Reading from file tmpfile_stdout 22:09:01 INFO - Current package versions: 22:09:01 INFO - argparse == 1.2.1 22:09:01 INFO - blessings == 1.5.1 22:09:01 INFO - blobuploader == 1.2.4 22:09:01 INFO - docopt == 0.6.1 22:09:01 INFO - manifestparser == 1.1 22:09:01 INFO - mozInstall == 1.12 22:09:01 INFO - mozcrash == 0.16 22:09:01 INFO - mozdebug == 0.1 22:09:01 INFO - mozdevice == 0.48 22:09:01 INFO - mozfile == 1.2 22:09:01 INFO - mozhttpd == 0.7 22:09:01 INFO - mozinfo == 0.9 22:09:01 INFO - mozleak == 0.1 22:09:01 INFO - mozlog == 3.1 22:09:01 INFO - moznetwork == 0.27 22:09:01 INFO - mozprocess == 0.22 22:09:01 INFO - mozprofile == 0.28 22:09:01 INFO - mozrunner == 6.11 22:09:01 INFO - mozscreenshot == 0.1 22:09:01 INFO - mozsystemmonitor == 0.0 22:09:01 INFO - moztest == 0.7 22:09:01 INFO - mozversion == 1.4 22:09:01 INFO - psutil == 3.1.1 22:09:01 INFO - requests == 1.2.3 22:09:01 INFO - wsgiref == 0.1.2 22:09:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:09:01 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 22:09:01 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 22:09:01 INFO - Using env: {'DISPLAY': ':0', 22:09:01 INFO - 'HOME': '/home/cltbld', 22:09:01 INFO - 'LANG': 'en_US.UTF-8', 22:09:01 INFO - 'LANGUAGE': 'en_US:en', 22:09:01 INFO - 'LOGNAME': 'cltbld', 22:09:01 INFO - 'MAIL': '/var/mail/cltbld', 22:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:01 INFO - 'MOZ_NO_REMOTE': '1', 22:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:01 INFO - 'NO_EM_RESTART': '1', 22:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:09:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:09:01 INFO - 'SHELL': '/bin/bash', 22:09:01 INFO - 'SHLVL': '1', 22:09:01 INFO - 'TERM': 'linux', 22:09:01 INFO - 'TMOUT': '86400', 22:09:01 INFO - 'USER': 'cltbld', 22:09:01 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:01 INFO - '_': '/tools/buildbot/bin/python'} 22:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 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)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:09:01 INFO - Cleaning up... 22:09:01 INFO - Return code: 0 22:09:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:09:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 22:09:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:09:01 INFO - Using env: {'DISPLAY': ':0', 22:09:01 INFO - 'HOME': '/home/cltbld', 22:09:01 INFO - 'LANG': 'en_US.UTF-8', 22:09:01 INFO - 'LANGUAGE': 'en_US:en', 22:09:01 INFO - 'LOGNAME': 'cltbld', 22:09:01 INFO - 'MAIL': '/var/mail/cltbld', 22:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:01 INFO - 'MOZ_NO_REMOTE': '1', 22:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:01 INFO - 'NO_EM_RESTART': '1', 22:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:09:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:09:01 INFO - 'SHELL': '/bin/bash', 22:09:01 INFO - 'SHLVL': '1', 22:09:01 INFO - 'TERM': 'linux', 22:09:01 INFO - 'TMOUT': '86400', 22:09:01 INFO - 'USER': 'cltbld', 22:09:01 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:01 INFO - '_': '/tools/buildbot/bin/python'} 22:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:01 INFO - Downloading/unpacking jsonschema 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:03 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 22:09:03 INFO - Downloading/unpacking functools32 (from jsonschema) 22:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:09:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:03 INFO - warning: no files found matching '*.txt' 22:09:03 INFO - no previously-included directories found matching 'build' 22:09:03 INFO - no previously-included directories found matching 'dist' 22:09:03 INFO - no previously-included directories found matching '.git*' 22:09:03 INFO - Installing collected packages: jsonschema, functools32 22:09:03 INFO - Running setup.py install for functools32 22:09:03 INFO - warning: no files found matching '*.txt' 22:09:03 INFO - no previously-included directories found matching 'build' 22:09:03 INFO - no previously-included directories found matching 'dist' 22:09:03 INFO - no previously-included directories found matching '.git*' 22:09:03 INFO - Successfully installed jsonschema functools32 22:09:03 INFO - Cleaning up... 22:09:03 INFO - Return code: 0 22:09:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f23c3473f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dbf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, '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-1453255419.145715-1707805862', '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 22:09:03 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 22:09:03 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 22:09:03 INFO - Using env: {'DISPLAY': ':0', 22:09:03 INFO - 'HOME': '/home/cltbld', 22:09:03 INFO - 'LANG': 'en_US.UTF-8', 22:09:03 INFO - 'LANGUAGE': 'en_US:en', 22:09:03 INFO - 'LOGNAME': 'cltbld', 22:09:03 INFO - 'MAIL': '/var/mail/cltbld', 22:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:03 INFO - 'MOZ_NO_REMOTE': '1', 22:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:03 INFO - 'NO_EM_RESTART': '1', 22:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:09:03 INFO - 'PWD': '/builds/slave/test-pgo', 22:09:03 INFO - 'SHELL': '/bin/bash', 22:09:03 INFO - 'SHLVL': '1', 22:09:03 INFO - 'TERM': 'linux', 22:09:03 INFO - 'TMOUT': '86400', 22:09:03 INFO - 'USER': 'cltbld', 22:09:03 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:03 INFO - '_': '/tools/buildbot/bin/python'} 22:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:03 INFO - Downloading/unpacking flake8 22:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:09:05 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 22:09:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:09:05 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 22:09:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:05 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 22:09:06 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 22:09:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:09:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:09:06 INFO - no previously-included directories found matching 'docs/_build' 22:09:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:09:06 INFO - Running setup.py install for pep8 22:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:09:06 INFO - no previously-included directories found matching 'docs/_build' 22:09:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:09:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:09:06 INFO - Cleaning up... 22:09:06 INFO - Return code: 0 22:09:06 INFO - Running post-action listener: _resource_record_post_action 22:09:06 INFO - Running post-action listener: _start_resource_monitoring 22:09:06 INFO - Starting resource monitoring. 22:09:06 INFO - ##### 22:09:06 INFO - ##### Running install step. 22:09:06 INFO - ##### 22:09:06 INFO - Running pre-action listener: _resource_record_pre_action 22:09:06 INFO - Running main action method: install 22:09:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:09:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:09:06 INFO - Reading from file tmpfile_stdout 22:09:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:09:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:09:06 INFO - Reading from file tmpfile_stdout 22:09:06 INFO - Output received: 22:09:06 INFO - Usage: mozinstall [options] installer 22:09:06 INFO - Options: 22:09:06 INFO - -h, --help show this help message and exit 22:09:06 INFO - -d DEST, --destination=DEST 22:09:06 INFO - Directory to install application into. [default: 22:09:06 INFO - "/builds/slave/test-pgo"] 22:09:06 INFO - --app=APP Application being installed. [default: firefox] 22:09:06 INFO - mkdir: /builds/slave/test-pgo/build/application 22:09:06 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'] 22:09:06 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:09:22 INFO - Reading from file tmpfile_stdout 22:09:22 INFO - Output received: 22:09:22 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:09:22 INFO - Running post-action listener: _resource_record_post_action 22:09:22 INFO - ##### 22:09:22 INFO - ##### Running run-tests step. 22:09:22 INFO - ##### 22:09:22 INFO - Running pre-action listener: _resource_record_pre_action 22:09:22 INFO - Running main action method: run_tests 22:09:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:09:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:09:22 INFO - Python 2.7.3 22:09:22 INFO - Return code: 0 22:09:22 INFO - grabbing minidump binary from tooltool 22:09:22 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:09:22 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 0x1e517f0>, '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 0x1dbd2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dbd760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:09:22 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:09:22 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:09:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:23 INFO - Return code: 0 22:09:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:09:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:09:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:09:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:09:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:09:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:09:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:09:23 INFO - Return code: 0 22:09:23 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 22:09:23 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:09:23 INFO - Using env: {'DISPLAY': ':0', 22:09:23 INFO - 'HOME': '/home/cltbld', 22:09:23 INFO - 'LANG': 'en_US.UTF-8', 22:09:23 INFO - 'LANGUAGE': 'en_US:en', 22:09:23 INFO - 'LOGNAME': 'cltbld', 22:09:23 INFO - 'MAIL': '/var/mail/cltbld', 22:09:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:09:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:23 INFO - 'MOZ_NO_REMOTE': '1', 22:09:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:23 INFO - 'NO_EM_RESTART': '1', 22:09:23 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:09:23 INFO - 'PWD': '/builds/slave/test-pgo', 22:09:23 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:09:23 INFO - 'SHELL': '/bin/bash', 22:09:23 INFO - 'SHLVL': '1', 22:09:23 INFO - 'TERM': 'linux', 22:09:23 INFO - 'TMOUT': '86400', 22:09:23 INFO - 'USER': 'cltbld', 22:09:23 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453255419.145715-1707805862', 22:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:23 INFO - '_': '/tools/buildbot/bin/python'} 22:09:23 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:09:24 INFO - mozversion application_buildid: 20160119193121 22:09:24 INFO - mozversion application_changeset: 2f8be7ffe2df8d40ca5099755ce999590916d8aa 22:09:24 INFO - mozversion application_display_name: Nightly 22:09:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:24 INFO - mozversion application_name: Firefox 22:09:24 INFO - mozversion application_remotingname: firefox 22:09:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:24 INFO - mozversion application_vendor: Mozilla 22:09:24 INFO - mozversion application_version: 46.0a1 22:09:24 INFO - mozversion platform_buildid: 20160119193121 22:09:24 INFO - mozversion platform_changeset: 2f8be7ffe2df8d40ca5099755ce999590916d8aa 22:09:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:09:24 INFO - mozversion platform_version: 46.0a1 22:09:24 INFO - using testdate: 1453270164 22:09:24 INFO - actual date: 1453270164 22:09:24 INFO - starting webserver on 'localhost:46647' 22:09:24 INFO - SUITE-START | Running 3 tests 22:09:24 INFO - TEST-START | dromaeo_css 22:09:24 INFO - operating with platform_type : linux_ 22:09:24 INFO - Initialising browser for dromaeo_css test... 22:09:24 INFO - TEST-INFO | started process 12367 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpM9bXUs/profile http://localhost:46647/getInfo.html) 22:09:28 INFO - PROCESS | 12367 | 22:09:28 INFO - PROCESS | 12367 | (process:12452): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:09:29 INFO - PROCESS | 12367 | __metrics Screen width/height:1600/1200 22:09:29 INFO - PROCESS | 12367 | colorDepth:24 22:09:29 INFO - PROCESS | 12367 | Browser inner width/height: 1024/697 22:09:29 INFO - PROCESS | 12367 | __metrics 22:09:29 INFO - PROCESS | 12367 | 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:09:29 INFO - PROCESS | 12367 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 22:09:30 INFO - TEST-INFO | 12367: exit 0 22:09:30 INFO - Browser initialized. 22:09:30 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:30 INFO - TEST-INFO | started process 12501 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpM9bXUs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:30 INFO - PROCESS | 12501 | -- checkerboarding starts below -- 22:09:30 INFO - PROCESS | 12501 | RENDERTRACE 0.055998 rect red 0 0 10 10 // viewport 22:09:30 INFO - PROCESS | 12501 | RENDERTRACE 26.165 rect brown 0 0 10 10 // page 22:09:30 INFO - PROCESS | 12501 | RENDERTRACE 26.1957 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 22:09:30 INFO - PROCESS | 12501 | Checkerboarded for 1 frames (26.2621 ms), 100 peak, 1 severity. 22:09:31 INFO - PROCESS | 12501 | 22:09:31 INFO - PROCESS | 12501 | (process:12561): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:09:41 INFO - PROCESS | 12501 | RSS: Main: 153657344 22:09:41 INFO - PROCESS | 12501 | 22:10:31 INFO - PROCESS | 12501 | 1453270231428 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:12:40 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46647/tests/dromaeo/cssquery-ext.html) 22:12:41 INFO - PROCESS | 12501 | RSS: Main: 160944128 22:12:41 INFO - PROCESS | 12501 | 22:15:43 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-ext.html (next: http://localhost:46647/tests/dromaeo/cssquery-jquery.html) 22:15:43 INFO - PROCESS | 12501 | RSS: Main: 157224960 22:15:43 INFO - PROCESS | 12501 | 22:18:45 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46647/tests/dromaeo/cssquery-mootools.html) 22:18:46 INFO - PROCESS | 12501 | RSS: Main: 157814784 22:18:46 INFO - PROCESS | 12501 | 22:21:47 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46647/tests/dromaeo/cssquery-prototype.html) 22:21:48 INFO - PROCESS | 12501 | RSS: Main: 156540928 22:21:48 INFO - PROCESS | 12501 | 22:24:50 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46647/tests/dromaeo/cssquery-yui.html) 22:24:51 INFO - PROCESS | 12501 | RSS: Main: 157245440 22:24:51 INFO - PROCESS | 12501 | 22:27:53 INFO - PROCESS | 12501 | Cycle 1(1): loaded http://localhost:46647/tests/dromaeo/cssquery-yui.html (next: http://localhost:46647/tests/dromaeo/cssquery-dojo.html) 22:27:54 INFO - PROCESS | 12501 | RSS: Main: 159305728 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | __start_tp_report 22:27:54 INFO - PROCESS | 12501 | _x_x_mozilla_page_load 22:27:54 INFO - PROCESS | 12501 | _x_x_mozilla_page_load_details 22:27:54 INFO - PROCESS | 12501 | |i|pagename|runs| 22:27:54 INFO - PROCESS | 12501 | |0;dojo.html;3152.4483215437294;3160.762942779291;3224.3873664003845;3228.564143840581;3252.951205731913;5172.431032586422;5249.081410753117;5252.737363131842;5263.368395792506;5280.3663560372715;5259.132243179871;5270.288511051007;5296.9735151324285;5351.946480535194;5359.222912677669;4826.444958829736;4884.829030983917;4932.605391568683;4943.332650092246;4970.179920313144;4873.585745211662;4926.211806111023;4945.085159245533;4945.802167913297;4956.702597844141;4759.16714574948;4828.034393121355;4830.985493046464;4861.440934292566;4900.191468407708;4807.134715751164;4874.878128046792;4877.658563900528;4888.584470320027;4920.975395123037;4795.136875362434;4811.13399588074;4812.855614331575;4823.927641085386;4872.512748725099;4966;5135.614828887811;5141.794328226866;5152.278680984665;5204.219367094966;5734.684592347419;5736.684482353469;5738.139279108125;5748.770049198069;5756.769729210827;5739.684317362543;5755.654660720328;5767.567432442584;5781.075027995501;5787.971060144672;5266.262723218752;5343.625946183769;5344.278522399465;5368.731563421813;5468.945310546923;5756.539476841843;5808.5353171747;5829.621074630136;5842.970785146132;5852.297724273115;5593.552515798727;5606.24315717373;5622.718864056781;5637.8590535236945;5637.943620563824;5596.356419011784;5631.662100273997;5657.434256574392;5682.40334764852;5683.204351390808;5543.307086614173;5589.273394458694;5625.212470254168;5637.46444087811;5640.605157638925;5649.830505084854;5670.177824215466;5670.603057785998;5687.118496633028;5692.174634677948;4356.411884395629;4407.977960110243;4408.25059739845;4414.867553973386;4442.489113751895;3906.3554513505524;3962.821673024721;3979.925420136547;3985.04358953849;4002.2795896738867;5265.368155821326;5273.973630131824;5281.102212623863;5283.498067683564;5296.576273898079;5704.857378565486;5705.372409035002;5714;5725.08398656213;5726.914096288559;5143.974280128576;5152.562032227303;5216.58267338612;5259.973700131475;5268.1834315681135;4833.589144922651;4833.589144922651;4834.226523756182;4840.6127509799135;4872.3909511311085;5878.382769809108;5919.052951527821;5933.614315069507;5937.139114828326;5947.226860508193;5621.8594535136945;5684.65892046462;5699.088145896802;5712.0860662293835;5736.799212027559;5856.882862342814;5942.61373010753;5960.5231581474245;5989.221401217814;6011.579189456696;5577.191307260506;5583.916241256466;5596.832095037155;5603.131514615241;5611.298587676541;5631.662100273997;5675.091985282335;5680.091185410314;5692.686902220417;5778.56660750437;5470.480304371078;5552.833414997556;5583.609147359808;5650.519705824887;5664.951983882995;5468.207109969112;5530.115181570929;5545.223668686468;5559.694216817952;5594;5389.083855744454;5448.618596698193;5461.972690136524;5472.671639701631;5521.089020311764;5490.396056433709;5496.0931446310915;5642.887142257214;5654.6889921054735;5694;5436.293281873489;5458.754356053908;5465.234867118686;5466.863328416822;5470.5350045245 22:27:54 INFO - PROCESS | 12501 | |1;ext.html;548.945105489451;551.5008916930179;574.2477354665389;576.0897781505222;586.6011112443537;37129.81435092821;37139;37540.62459375405;37579.24841503172;37678.43482347763;38834.223315533745;39053.609463905355;39305.41041884374;39349.21301573967;39367;8383.203595658402;8497.61760720769;8544.70093546726;8585.527795971218;8606.698765543208;8244.83510329793;8339.874901876477;8340.207680270394;8366.58167091643;8404.07555168931;8349.749507514784;8372.748817535483;8430.83138337233;8432.45189062712;8457.915420845775;8310.667573297062;8397.95801020994;8409.369297302695;8428.704995325133;8472.576371181416;8285.047219569766;8366.121557236464;8385.538795366252;8404.24361807434;8444.83110337796;35899.4615080774;36124.27751444956;36327.27345453102;36413;36660.450093248626;3377.9155521111925;3378.408778463759;3400.4899265110184;3453.913652158691;3597.8740744074094;29072;29379.118626441243;29549.704502955126;29593.85203073971;29807.25481862949;3427.1260828488826;3476.3568739783223;3517.929641407183;3651.9634803652157;3678.393065144248;258531;263292;263958;264296;264902;52572;58203;59209.70395148083;59279.70360148171;59284;46221.30668039982;63637.36362636407;64754.67622661857;65085.349146508874;65185;13159.802602960963;13207.207567545978;13282.203067815963;13308.334583270798;13327.866721332857;11097.667069987914;11179.552817887356;11221.887781122085;11287.887121128684;11310.038646715122;19107.808921910604;19431.805681943282;19527.804721952598;19617.901910490356;20192.39422817318;7955.204479551999;8220.671173153007;8243.134470880592;8300.2114799094;8300.521655707204;967.5065716484644;968.7190714692802;1002.0480543483574;1006.9295149339476;1127.9492422840992;28194.577081343796;28229;28291;28392.148235552966;28557.714422855923;1044.9216308776872;1045.1259037646473;1059.6979860739807;1071.7320669832543;1079.082779637302;14512.492062777752;14788;14913.776293355608;14948.402063917321;14973.251337433085;290213;296348;297282;297608;298744;51328.23007654888;51478.74260628673;51633.483665162865;51635.741821290656;51720.48279517156;1322.3388305847077;1350.4935649131576;1357.6605848537865;1360.4966162519931;1366.4943970730894;27367.72632273652;28082.438351232453;28293;28301.57547636746;28609.570856436752;45896.31155532603;46073;46240.07519849517;46585.53414465812;46600;272838;274697;275184;275269;276502;31709.20726981844;31932.5210121853;32113;32212;32224;38008.61991380051;39949.60050399459;40839;41435.585644144376;41946.790266048476;34659.653403466655;34940.301193976484;35445;35598.64401355953;35803.462948055276;34595.654043459246;34889.651103489654;35105.47341789824;35392;35445;33746.493802593475;34159.82920085483;34328;34445.31109377849;34453.138671533416;31696.841515792272;32016.81679282575;32074.518882217253;32350;33084;23015.19446819353;23164.305070847902;23177.3046808596;23343.649845252676;23369.532609347378 22:27:54 INFO - PROCESS | 12501 | |2;jquery.html;366.2199515032979;367.2269871919609;370.3130692565291;372.21462713674157;375.23639393833554;13763.380647870845;14307.499237526688;15612.219389030546;16356.263968121433;17088.487345379643;15502.767458488132;15667.041619797525;16283.674326513492;16288.26702798374;16324.83675163248;5042.974785126078;5392.406835248139;5406.783728650869;5408.107662235746;5452.672839629635;5080.542751152396;5237.86905327368;5246.658967167141;5279.41926388097;5310.097283461821;5225.190095535179;5250.133727934886;5307.814226502073;5711.114777209539;5727.57043221758;5430.59270554706;5776.151631477929;5855.23881895353;5931.703414829263;5933.673647949383;5423.7830486780485;5445.346558412976;5445.53712934401;5455.809046683386;5477.260569823063;11691.181617286793;12100.394980250952;12106.757864842742;12124.454399552042;12514.31171285579;1971.9704204436946;2025.5543780368444;2057.177129148353;2109.0614676468977;2240.451089483071;8812.07473215316;8899.510526921016;9231.907680923172;10109.292349535535;11980.460879260454;1805.3681211575974;1830.8718389712726;1839.2734869726382;1851.5556266496078;1868.6169335286233;54242.18636720452;54264.186037209474;54872.176917346274;56284.718576406856;56582.15126773102;9837.409755414697;9911.058449447291;9938.652147174815;9942.602295908227;9995.350302230332;12785.61643150707;12873.098883078283;12920.870791291967;12943.741125177443;12952.481900723866;6827.180738311435;6854.143232095988;6951.65241737911;7002.614856182857;7066.152061752623;6479.157709497735;6589.967050164719;6831.4534837212905;6885.173779146535;6900.068490753784;3987.7806720630642;4148.357004664282;4195.853145139906;4315.4605674290715;4388.71473354231;1821.3807305516189;1842.2078506242444;1844.2807305151002;1854;1866.9626607467774;921.2721949659827;933.710549729586;976.1653786012998;993.4982833668951;1008.037324355239;16036.358545658277;16138.112403817902;16182.595435114215;16392.590185245226;16652;955.7992821507561;961.024560071528;990.9792913299314;995.4922989275377;1002.6390499420203;9402.95298523503;9494.810103798023;9529.094736000068;9602;9687.757806054906;367893;373543;378162;519108;527803;69178.65410672914;72966;73913;74226;77338;1101.0860985382274;1120.7254222715487;1124.3366413815888;1135.7558125003163;1138.772245550893;15001.849981500482;15046.305536705437;15112;15194.108772610924;15580.454684086002;31857.61067919869;32184.517232242004;33185.336293274486;33286.33427331488;33702.66297336995;41894.37158442565;42220.577794222896;42259;42832.5716742841;45304;6952.270011648704;7104;7111.111111111111;7158.570485770871;7321.828507438784;8435.8734618982;10164.13604843567;10663.253572249869;10929.453527323443;10930.234883558393;9382.03305473757;9442.952785236304;9570.760730981781;9933.255005874444;10038.598456061676;9619.326647134912;9735.464549449849;9759.9024009759;10070.295079344669;10168.542415591464;9874.654387096705;9896.158826499828;10143.7464063399;10223.488825558841;10328.741781455523;7325.706971721072;7409.777706668593;7463.067116610424;7473;7504.1370242420635;8093.716719914774;10019.599216031569;10027.448490333101;10029.348092373972;10082.848757268794 22:27:54 INFO - PROCESS | 12501 | |3;mootools.html;1490.9105453672778;1502.4516051641147;1675.2796297592038;1784.1079460269866;1805.2147315917584;7764.18476060014;7779.688812447503;7977.5612341321275;7995;8079.151689072636;8042.356611471083;8102.513849169054;8184.0588332341795;8186.959065204681;8232.588370581478;2317.640765681322;2352.6353415220583;2353.9882300588474;2354.1878052072093;2363.385519764865;2341.496578235679;2343.8476499027593;2377.5482658294954;2381.416552944522;2385.0459816073535;2261.276391554695;2313.8611683299055;2331.9300420987397;2340.871252081135;2356.634721618152;2315.4674424882205;2318.9564695886834;2320.1995311617466;2325.244295603927;2340.1107579119825;2360.3745007573007;2374.78626923576;2387.6299173628117;2389.6654468374268;2390.7011623547055;7877;7890.368770498347;7902.723404680866;7907.762767116996;7917.2874441300555;4825.9034819303415;4953.6037117030555;4959.925601115986;4964.205727083649;4977.129002424561;3095.721385075354;3123.453395655774;3170.4134735074085;3187.4103290891258;3232.9030129096163;894.3828758156851;910.0944560162615;930.5114814722257;957.0525180071679;985.1724551376806;11197.60808371703;11203.887961120367;11282;11297.209195356329;11318.264312819723;4293.119910418357;4295.935560966588;4347.7826108694435;4366.104948485553;4412.271975124133;8778.385513014155;8795.648174072965;8800.339974501938;8811.118888111137;8830.86753698695;4930.802767889244;4948.960718249136;4954.058728841508;4955.702657840541;4959.107360675041;4531.184386810406;4535.569120933572;4542.477615074243;4556.817727290938;4557.384753058361;3495.7378196635354;3512.332656795201;3523.94714079289;3527.1534831640734;3545.503629491873;1037.1650821088945;1067.1196263082988;1092.8032954068344;1112.0991996482874;1113.2318700096907;1104.2325583719398;1123.9494222759995;1195.5397172088808;1289.6260084575556;1366.8838148757268;7341.669624866894;7365.52124111931;7377.704891804375;7388.630568471555;7492.887606685904;1150.5857891159176;1158.467105131632;1162.3839365136491;1169.1231576317762;1180.4569897846916;5764.625299355528;5847.6783776891825;5913.379095195029;5929.644221346733;5932.525397968152;9331.16019558243;9607.615695372248;9612.279079069098;9667.371620844622;9743.123118919331;9421.38760980534;9429.528523573794;9516.714498565054;9532.52337383142;9600.231981441468;7612.200718924544;7646.23537646242;7656.885146722803;7700.075990881131;7722.034745656793;7242.275772422716;7344.559326440217;7360.66876990526;7386.704531818667;7398.223186565333;8503.574821259035;8646.956765216133;8674.349423793366;8701.260392866678;8749.781255468664;8803.207711306013;8921.776955576162;8961.103889610988;8992.55037248148;9001.729948101567;6799.626020568719;6934.7226110955;6938.722451102101;6938.757143499954;6955.130608673916;7592.734254301073;7620.5808680523105;7715.57564333967;7848.058233011847;7868.567228802471;6957.652117394211;7179.820504487429;7308.707651693872;7486.064241969754;7558.2441755823975;7042.366187043191;7103.2541583133025;7114.181869085121;7138.250483699345;7265.346118849329;7024.051753013277;7054.753083642048;7115.857682846417;7115.857682846417;7122.5726456412785;6515.185601799775;6517.674116294072;6531.314212007671;6573.901391479036;6577.80266592003;6380.202474690664;6471.288158302677;6507.707153178024;6527.184101987252;6603.669816509251 22:27:54 INFO - PROCESS | 12501 | |4;prototype.html;335.5319329535297;340.02413074476243;341.28671077448143;344.97067749241313;355.3585777671303;6175.919214137519;6239.376062393764;6327.778527751524;6330.65181415022;6332.208473940757;6076.605020673653;6193.1329613854095;6211.223597050369;6212.78255261066;6218.4714299284615;4721.291806229059;4782.30656554801;4788.832390866321;4798.4001999750035;4807.78364973577;4800.42394912611;4810.759462026902;4811.759412029384;4816.783244753994;4816.951830481703;4644.86065418038;4735.550122738335;4754.548317909793;4765.142274390609;4790.784414701329;4732.621390288786;4751.524847515256;4752.429708434993;4758.3100450434495;4764.547368000034;4183;4184.121334519755;4186.623203911632;4196.454460920061;4211.410402543646;5910.34986151523;5947.1376650385455;5953.315368732608;5962.970185149046;5968.462838344526;5455.454454554552;5605.5515558755205;5605.943940560583;5609.775608975636;5620.016497112989;6637.767678131242;6691.3308669133185;6720.966395168041;6723.025161351626;6731.629760363178;4617.584417402416;4637.582617564435;4657.394538709946;4662.463816661059;4676.672632915697;6801.42187914023;6891.345322194384;6959.095317608729;6960;6965.651717414109;4730.787114579852;4764.071006153836;4774.212254977959;4774.809007639656;4780.545848144421;4818.253170758538;4824.662273640882;4826.975865120652;4828.299896514985;4846.781894814671;5546.362168350611;5551.25058117149;5557.5831812614215;5558.944410555842;5561.860953476195;6503.6097834130105;6655.966720166368;6681.265060843303;6697.3637504437;6703.497237707192;1617.6683779825107;1649.0682764238406;1653.9503814885572;1654.7435147552148;1675.6062325353532;1090.8091084060259;1096.7203363142348;1107.0645304717661;1115.5147510832815;1115.5872327238974;6127.111568822585;6164.291106522717;6180.969095154495;6190.473809726133;6206.968965155145;6694.564853284521;6815.625140617313;6823.147106611674;6825.590464572042;6846.281140480278;6075.149479072933;6173.59871608344;6176.81469555914;6182.072689096672;6185.752569897244;3685.3734865073;3696.2607478504406;3708.8331025103935;3716.8141592920247;3720.6465385788847;7057.752978645723;7058.6823592938445;7097.361237488651;7145.571265724073;7146.856502959606;6817.795466136024;6926.064981227571;6967.268436814163;6975.337342952412;6979.092717946736;7104.147502299757;7114.430845532345;7120.074390329328;7135.678894449762;7138.785836424916;6770;6790.388865002174;6794.286599907136;6829.965850170717;6837.658117094026;6570.080188773671;6677;6706.597604143767;6745.898811517733;6752.628605426748;7068.71725130989;7068.9293107070325;7096.290370963069;7100.9289907100265;7127.928720712726;4704.435467743756;4708.599769019672;4711.175544279806;4711.552402521755;4718.150732867979;6715.160604924385;6779.593224406552;6800.82997925036;6804.76183333581;6926.030355750098;6704.765333213314;6753.594784312957;6754.797356079325;6791.966040169767;6792.049113124265;6651.700673469609;6678.699458524476;6678.831204539283;6699.933000670126;6711.060451536887;6522.412982831568;6602.504812139205;6628.569143005882;6646.933530664632;6648.301928297459;6778.525503214728;6781.389165171543;6782.0505129280955;6791.01530278097;6792.184937807495;6529.738810447719;6559.6064236144075;6570.080188773671;6605.009248612747;6611.966940165268 22:27:54 INFO - PROCESS | 12501 | |5;yui.html;736.3593673504053;748.7341993592278;777.4091690315361;791.9366450683946;810.8216192437661;12948.935255323722;13019.349032548358;13150.079494435382;13191.736165276701;13212.339383030858;12527.686807829808;12544.623661290154;12579.622611321653;12673.746525069517;12725.872741272584;810.5987536169604;814.8125931035866;814.9837003259946;821.4824660463929;822.8230930349973;816.0044745410619;827.5613924619931;828.349745449822;828.5443006346516;830.1947111302028;857.9013413457438;865.6364326982682;866.9263112635402;867.154524338771;867.6139118092451;857.4897935728231;859.2438653984516;863.011851430109;865.7316231968047;868.7741187291318;630.6373835044867;632.3076231526471;636.1285039495874;637.2194062273724;640;13153.802692959613;13233.338333083308;13243.072984891061;13258.204507729568;13303.40134693941;12235.510579576807;12256.877431225752;12463.002959763196;12618.116731828775;12818.359082045861;10189;10220.897791022144;10259.640912568098;10350.223733219964;10393.376397416103;3442.5524681791208;3505.4040813061843;3522.4716292556063;3625.4380571011534;3638.781673099596;44857.775711121234;45107.09785804265;45415.31877021847;45548.08903821905;45829.77085114553;9693.563789629343;9824.656137035312;9885.159761420216;9934.552945117486;10012.649557265606;17657.558561036076;17861.28554857791;17891.10544472797;17893.7315940261;17914.193861276275;7287.599182044933;7394.075740532434;7423.257674232534;7471.70113195477;7539.73610923626;4122.237386083584;4123.773192474383;4151.045259590311;4175.728577642443;4190.853320133841;3053.694630536973;3056.7401770849283;3083.2137804859617;3087.984560077185;3094.5822313987323;561.5788158880839;565.0619970848372;568.7724910035936;604.4982664388547;630.0989584893646;598.9066471673756;602.135934933372;603.9295150684443;632.4845251120322;639.3382848751613;7770.689172433039;7840.647170877324;7894.407919406068;7916.129225785159;7939.087004994384;502.45734606624757;549.5658429840381;558.2491548866768;579.9623024503394;663.9701213445406;3785.5268091488565;3859.26673931952;3934.881953541398;4079.143379890197;4109.239790638741;52930.20604690933;53512.46487535075;54298.45701542934;54533;54662.72668636632;46655.30017049747;47555;47618;47734;48054;43415.348769768476;43641.56358436438;43756.09292745439;43920.78039609845;44616;7928.405369597419;8172.959135204286;8198.754037378889;8211.917880821355;8235.21765432283;32138.678613214503;32333.191670208435;32457.675423245466;33518;33936.83031584925;37794.055148621504;40257;42342.78828605837;42826.35760463658;43296.13407731891;10251.179905607683;10442.895571044191;10506.317089389166;11081.612143574936;11329.376884271443;11551.711207219887;11657.88342116602;11753.647390578297;12048.879511204776;12065.517379304729;10537.766871653164;10823.296485728402;10838.132949363875;11152.832707509557;11362.375069371265;10427.497549836537;10470.152720950784;10481.713646889759;10519.053285204061;10536.209784266057;11121.332720036824;11284.435778210893;11380.374079425712;11393.259438136494;11743.530258789895;9147.496887671243;9352.29857760657;9505.334626576074;9561.139497445263;9756.46339451337;8608.65565377378;8712.778241548365;9132.360734748503;10041.497925103862;10134.391936483835 22:27:54 INFO - PROCESS | 12501 | __end_tp_report 22:27:54 INFO - PROCESS | 12501 | __start_cc_report 22:27:54 INFO - PROCESS | 12501 | _x_x_mozilla_cycle_collect,311 22:27:54 INFO - PROCESS | 12501 | __end_cc_report 22:27:54 INFO - PROCESS | 12501 | __startTimestamp1453271274130__endTimestamp 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | ------- Summary: start ------- 22:27:54 INFO - PROCESS | 12501 | Number of tests: 6 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#0] dojo.html Cycles:165 Average:5284.71 Median:5468.95 stddev:578.67 (10.6%) stddev-sans-first:555.74 22:27:54 INFO - PROCESS | 12501 | Values: 3152.4 3160.8 3224.4 3228.6 3253.0 5172.4 5249.1 5252.7 5263.4 5280.4 5259.1 5270.3 5297.0 5351.9 5359.2 4826.4 4884.8 4932.6 4943.3 4970.2 4873.6 4926.2 4945.1 4945.8 4956.7 4759.2 4828.0 4831.0 4861.4 4900.2 4807.1 4874.9 4877.7 4888.6 4921.0 4795.1 4811.1 4812.9 4823.9 4872.5 4966.0 5135.6 5141.8 5152.3 5204.2 5734.7 5736.7 5738.1 5748.8 5756.8 5739.7 5755.7 5767.6 5781.1 5788.0 5266.3 5343.6 5344.3 5368.7 5468.9 5756.5 5808.5 5829.6 5843.0 5852.3 5593.6 5606.2 5622.7 5637.9 5637.9 5596.4 5631.7 5657.4 5682.4 5683.2 5543.3 5589.3 5625.2 5637.5 5640.6 5649.8 5670.2 5670.6 5687.1 5692.2 4356.4 4408.0 4408.3 4414.9 4442.5 3906.4 3962.8 3979.9 3985.0 4002.3 5265.4 5274.0 5281.1 5283.5 5296.6 5704.9 5705.4 5714.0 5725.1 5726.9 5144.0 5152.6 5216.6 5260.0 5268.2 4833.6 4833.6 4834.2 4840.6 4872.4 5878.4 5919.1 5933.6 5937.1 5947.2 5621.9 5684.7 5699.1 5712.1 5736.8 5856.9 5942.6 5960.5 5989.2 6011.6 5577.2 5583.9 5596.8 5603.1 5611.3 5631.7 5675.1 5680.1 5692.7 5778.6 5470.5 5552.8 5583.6 5650.5 5665.0 5468.2 5530.1 5545.2 5559.7 5594.0 5389.1 5448.6 5462.0 5472.7 5521.1 5490.4 5496.1 5642.9 5654.7 5694.0 5436.3 5458.8 5465.2 5466.9 5470.5 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#1] ext.html Cycles:180 Average:44498.26 Median:19183.00 stddev:72654.70 (378.7%) stddev-sans-first:72783.58 22:27:54 INFO - PROCESS | 12501 | Values: 548.9 551.5 574.2 576.1 586.6 37129.8 37139.0 37540.6 37579.2 37678.4 38834.2 39053.6 39305.4 39349.2 39367.0 8383.2 8497.6 8544.7 8585.5 8606.7 8244.8 8339.9 8340.2 8366.6 8404.1 8349.7 8372.7 8430.8 8432.5 8457.9 8310.7 8398.0 8409.4 8428.7 8472.6 8285.0 8366.1 8385.5 8404.2 8444.8 35899.5 36124.3 36327.3 36413.0 36660.5 3377.9 3378.4 3400.5 3453.9 3597.9 29072.0 29379.1 29549.7 29593.9 29807.3 3427.1 3476.4 3517.9 3652.0 3678.4 258531.0 263292.0 263958.0 264296.0 264902.0 52572.0 58203.0 59209.7 59279.7 59284.0 46221.3 63637.4 64754.7 65085.3 65185.0 13159.8 13207.2 13282.2 13308.3 13327.9 11097.7 11179.6 11221.9 11287.9 11310.0 19107.8 19431.8 19527.8 19617.9 20192.4 7955.2 8220.7 8243.1 8300.2 8300.5 967.5 968.7 1002.0 1006.9 1127.9 28194.6 28229.0 28291.0 28392.1 28557.7 1044.9 1045.1 1059.7 1071.7 1079.1 14512.5 14788.0 14913.8 14948.4 14973.3 290213.0 296348.0 297282.0 297608.0 298744.0 51328.2 51478.7 51633.5 51635.7 51720.5 1322.3 1350.5 1357.7 1360.5 1366.5 27367.7 28082.4 28293.0 28301.6 28609.6 45896.3 46073.0 46240.1 46585.5 46600.0 272838.0 274697.0 275184.0 275269.0 276502.0 31709.2 31932.5 32113.0 32212.0 32224.0 38008.6 39949.6 40839.0 41435.6 41946.8 34659.7 34940.3 35445.0 35598.6 35803.5 34595.7 34889.7 35105.5 35392.0 35445.0 33746.5 34159.8 34328.0 34445.3 34453.1 31696.8 32016.8 32074.5 32350.0 33084.0 23015.2 23164.3 23177.3 23343.6 23369.5 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#2] jquery.html Cycles:180 Average:24261.81 Median:25173.49 stddev:72050.65 (286.2%) stddev-sans-first:72230.43 22:27:54 INFO - PROCESS | 12501 | Values: 366.2 367.2 370.3 372.2 375.2 13763.4 14307.5 15612.2 16356.3 17088.5 15502.8 15667.0 16283.7 16288.3 16324.8 5043.0 5392.4 5406.8 5408.1 5452.7 5080.5 5237.9 5246.7 5279.4 5310.1 5225.2 5250.1 5307.8 5711.1 5727.6 5430.6 5776.2 5855.2 5931.7 5933.7 5423.8 5445.3 5445.5 5455.8 5477.3 11691.2 12100.4 12106.8 12124.5 12514.3 1972.0 2025.6 2057.2 2109.1 2240.5 8812.1 8899.5 9231.9 10109.3 11980.5 1805.4 1830.9 1839.3 1851.6 1868.6 54242.2 54264.2 54872.2 56284.7 56582.2 9837.4 9911.1 9938.7 9942.6 9995.4 12785.6 12873.1 12920.9 12943.7 12952.5 6827.2 6854.1 6951.7 7002.6 7066.2 6479.2 6590.0 6831.5 6885.2 6900.1 3987.8 4148.4 4195.9 4315.5 4388.7 1821.4 1842.2 1844.3 1854.0 1867.0 921.3 933.7 976.2 993.5 1008.0 16036.4 16138.1 16182.6 16392.6 16652.0 955.8 961.0 991.0 995.5 1002.6 9403.0 9494.8 9529.1 9602.0 9687.8 367893.0 373543.0 378162.0 519108.0 527803.0 69178.7 72966.0 73913.0 74226.0 77338.0 1101.1 1120.7 1124.3 1135.8 1138.8 15001.8 15046.3 15112.0 15194.1 15580.5 31857.6 32184.5 33185.3 33286.3 33702.7 41894.4 42220.6 42259.0 42832.6 45304.0 6952.3 7104.0 7111.1 7158.6 7321.8 8435.9 10164.1 10663.3 10929.5 10930.2 9382.0 9443.0 9570.8 9933.3 10038.6 9619.3 9735.5 9759.9 10070.3 10168.5 9874.7 9896.2 10143.7 10223.5 10328.7 7325.7 7409.8 7463.1 7473.0 7504.1 8093.7 10019.6 10027.4 10029.3 10082.8 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#3] mootools.html Cycles:180 Average:5595.79 Median:6529.25 stddev:2897.75 (44.4%) stddev-sans-first:2889.45 22:27:54 INFO - PROCESS | 12501 | Values: 1490.9 1502.5 1675.3 1784.1 1805.2 7764.2 7779.7 7977.6 7995.0 8079.2 8042.4 8102.5 8184.1 8187.0 8232.6 2317.6 2352.6 2354.0 2354.2 2363.4 2341.5 2343.8 2377.5 2381.4 2385.0 2261.3 2313.9 2331.9 2340.9 2356.6 2315.5 2319.0 2320.2 2325.2 2340.1 2360.4 2374.8 2387.6 2389.7 2390.7 7877.0 7890.4 7902.7 7907.8 7917.3 4825.9 4953.6 4959.9 4964.2 4977.1 3095.7 3123.5 3170.4 3187.4 3232.9 894.4 910.1 930.5 957.1 985.2 11197.6 11203.9 11282.0 11297.2 11318.3 4293.1 4295.9 4347.8 4366.1 4412.3 8778.4 8795.6 8800.3 8811.1 8830.9 4930.8 4949.0 4954.1 4955.7 4959.1 4531.2 4535.6 4542.5 4556.8 4557.4 3495.7 3512.3 3523.9 3527.2 3545.5 1037.2 1067.1 1092.8 1112.1 1113.2 1104.2 1123.9 1195.5 1289.6 1366.9 7341.7 7365.5 7377.7 7388.6 7492.9 1150.6 1158.5 1162.4 1169.1 1180.5 5764.6 5847.7 5913.4 5929.6 5932.5 9331.2 9607.6 9612.3 9667.4 9743.1 9421.4 9429.5 9516.7 9532.5 9600.2 7612.2 7646.2 7656.9 7700.1 7722.0 7242.3 7344.6 7360.7 7386.7 7398.2 8503.6 8647.0 8674.3 8701.3 8749.8 8803.2 8921.8 8961.1 8992.6 9001.7 6799.6 6934.7 6938.7 6938.8 6955.1 7592.7 7620.6 7715.6 7848.1 7868.6 6957.7 7179.8 7308.7 7486.1 7558.2 7042.4 7103.3 7114.2 7138.3 7265.3 7024.1 7054.8 7115.9 7115.9 7122.6 6515.2 6517.7 6531.3 6573.9 6577.8 6380.2 6471.3 6507.7 6527.2 6603.7 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#4] prototype.html Cycles:180 Average:5553.92 Median:6191.80 stddev:1674.16 (27.0%) stddev-sans-first:1632.40 22:27:54 INFO - PROCESS | 12501 | Values: 335.5 340.0 341.3 345.0 355.4 6175.9 6239.4 6327.8 6330.7 6332.2 6076.6 6193.1 6211.2 6212.8 6218.5 4721.3 4782.3 4788.8 4798.4 4807.8 4800.4 4810.8 4811.8 4816.8 4817.0 4644.9 4735.6 4754.5 4765.1 4790.8 4732.6 4751.5 4752.4 4758.3 4764.5 4183.0 4184.1 4186.6 4196.5 4211.4 5910.3 5947.1 5953.3 5963.0 5968.5 5455.5 5605.6 5605.9 5609.8 5620.0 6637.8 6691.3 6721.0 6723.0 6731.6 4617.6 4637.6 4657.4 4662.5 4676.7 6801.4 6891.3 6959.1 6960.0 6965.7 4730.8 4764.1 4774.2 4774.8 4780.5 4818.3 4824.7 4827.0 4828.3 4846.8 5546.4 5551.3 5557.6 5558.9 5561.9 6503.6 6656.0 6681.3 6697.4 6703.5 1617.7 1649.1 1654.0 1654.7 1675.6 1090.8 1096.7 1107.1 1115.5 1115.6 6127.1 6164.3 6181.0 6190.5 6207.0 6694.6 6815.6 6823.1 6825.6 6846.3 6075.1 6173.6 6176.8 6182.1 6185.8 3685.4 3696.3 3708.8 3716.8 3720.6 7057.8 7058.7 7097.4 7145.6 7146.9 6817.8 6926.1 6967.3 6975.3 6979.1 7104.1 7114.4 7120.1 7135.7 7138.8 6770.0 6790.4 6794.3 6830.0 6837.7 6570.1 6677.0 6706.6 6745.9 6752.6 7068.7 7068.9 7096.3 7100.9 7127.9 4704.4 4708.6 4711.2 4711.6 4718.2 6715.2 6779.6 6800.8 6804.8 6926.0 6704.8 6753.6 6754.8 6792.0 6792.0 6651.7 6678.7 6678.8 6699.9 6711.1 6522.4 6602.5 6628.6 6646.9 6648.3 6778.5 6781.4 6782.1 6791.0 6792.2 6529.7 6559.6 6570.1 6605.0 6612.0 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - PROCESS | 12501 | [#5] yui.html Cycles:180 Average:13144.66 Median:43838.44 stddev:14896.93 (34.0%) stddev-sans-first:14909.58 22:27:54 INFO - PROCESS | 12501 | Values: 736.4 748.7 777.4 791.9 810.8 12948.9 13019.3 13150.1 13191.7 13212.3 12527.7 12544.6 12579.6 12673.7 12725.9 810.6 814.8 815.0 821.5 822.8 816.0 827.6 828.3 828.5 830.2 857.9 865.6 866.9 867.2 867.6 857.5 859.2 863.0 865.7 868.8 630.6 632.3 636.1 637.2 640.0 13153.8 13233.3 13243.1 13258.2 13303.4 12235.5 12256.9 12463.0 12618.1 12818.4 10189.0 10220.9 10259.6 10350.2 10393.4 3442.6 3505.4 3522.5 3625.4 3638.8 44857.8 45107.1 45415.3 45548.1 45829.8 9693.6 9824.7 9885.2 9934.6 10012.6 17657.6 17861.3 17891.1 17893.7 17914.2 7287.6 7394.1 7423.3 7471.7 7539.7 4122.2 4123.8 4151.0 4175.7 4190.9 3053.7 3056.7 3083.2 3088.0 3094.6 561.6 565.1 568.8 604.5 630.1 598.9 602.1 603.9 632.5 639.3 7770.7 7840.6 7894.4 7916.1 7939.1 502.5 549.6 558.2 580.0 664.0 3785.5 3859.3 3934.9 4079.1 4109.2 52930.2 53512.5 54298.5 54533.0 54662.7 46655.3 47555.0 47618.0 47734.0 48054.0 43415.3 43641.6 43756.1 43920.8 44616.0 7928.4 8173.0 8198.8 8211.9 8235.2 32138.7 32333.2 32457.7 33518.0 33936.8 37794.1 40257.0 42342.8 42826.4 43296.1 10251.2 10442.9 10506.3 11081.6 11329.4 11551.7 11657.9 11753.6 12048.9 12065.5 10537.8 10823.3 10838.1 11152.8 11362.4 10427.5 10470.2 10481.7 10519.1 10536.2 11121.3 11284.4 11380.4 11393.3 11743.5 9147.5 9352.3 9505.3 9561.1 9756.5 8608.7 8712.8 9132.4 10041.5 10134.4 22:27:54 INFO - PROCESS | 12501 | -------- Summary: end -------- 22:27:54 INFO - PROCESS | 12501 | 22:27:54 INFO - TEST-INFO | 12501: exit 0 22:27:54 INFO - TEST-OK | dromaeo_css | took 1110583ms 22:27:54 INFO - TEST-START | kraken 22:27:54 INFO - operating with platform_type : linux_ 22:27:54 INFO - Initialising browser for kraken test... 22:27:54 INFO - TEST-INFO | started process 12746 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppcyxyW/profile http://localhost:46647/getInfo.html) 22:27:58 INFO - PROCESS | 12746 | 22:27:58 INFO - PROCESS | 12746 | (process:12817): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:27:59 INFO - PROCESS | 12746 | __metrics Screen width/height:1600/1200 22:27:59 INFO - PROCESS | 12746 | colorDepth:24 22:27:59 INFO - PROCESS | 12746 | Browser inner width/height: 1024/697 22:27:59 INFO - PROCESS | 12746 | __metrics 22:27:59 INFO - PROCESS | 12746 | 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:28:00 INFO - TEST-INFO | 12746: exit 0 22:28:00 INFO - Browser initialized. 22:28:00 INFO - Running cycle 1/1 for kraken test... 22:28:00 INFO - TEST-INFO | started process 12866 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppcyxyW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:01 INFO - PROCESS | 12866 | -- checkerboarding starts below -- 22:28:01 INFO - PROCESS | 12866 | RENDERTRACE 0.102853 rect red 0 0 10 10 // viewport 22:28:01 INFO - PROCESS | 12866 | RENDERTRACE 100.923 rect brown 0 0 10 10 // page 22:28:01 INFO - PROCESS | 12866 | RENDERTRACE 100.941 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 22:28:01 INFO - PROCESS | 12866 | Checkerboarded for 1 frames (106.252 ms), 100 peak, 0 severity. 22:28:01 INFO - PROCESS | 12866 | 22:28:01 INFO - PROCESS | 12866 | (process:12928): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:28:12 INFO - PROCESS | 12866 | RSS: Main: 153419776 22:28:12 INFO - PROCESS | 12866 | 22:28:14 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46647/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:15 INFO - PROCESS | 12866 | RSS: Main: 157032448 22:28:15 INFO - PROCESS | 12866 | 22:28:17 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46647/tests/kraken/driver.html?testName=audio-dft) 22:28:18 INFO - PROCESS | 12866 | RSS: Main: 160862208 22:28:18 INFO - PROCESS | 12866 | 22:28:21 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46647/tests/kraken/driver.html?testName=audio-fft) 22:28:21 INFO - PROCESS | 12866 | RSS: Main: 154017792 22:28:21 INFO - PROCESS | 12866 | 22:28:24 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46647/tests/kraken/driver.html?testName=audio-oscillator) 22:28:24 INFO - PROCESS | 12866 | RSS: Main: 154800128 22:28:24 INFO - PROCESS | 12866 | 22:28:26 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46647/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:27 INFO - PROCESS | 12866 | RSS: Main: 155635712 22:28:27 INFO - PROCESS | 12866 | 22:28:30 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46647/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:31 INFO - PROCESS | 12866 | RSS: Main: 155992064 22:28:31 INFO - PROCESS | 12866 | 22:28:34 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46647/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:35 INFO - PROCESS | 12866 | RSS: Main: 156188672 22:28:35 INFO - PROCESS | 12866 | 22:28:37 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46647/tests/kraken/driver.html?testName=json-parse-financial) 22:28:38 INFO - PROCESS | 12866 | RSS: Main: 156221440 22:28:38 INFO - PROCESS | 12866 | 22:28:39 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46647/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:40 INFO - PROCESS | 12866 | RSS: Main: 156536832 22:28:40 INFO - PROCESS | 12866 | 22:28:42 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:28:42 INFO - PROCESS | 12866 | RSS: Main: 155930624 22:28:42 INFO - PROCESS | 12866 | 22:28:45 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:45 INFO - PROCESS | 12866 | RSS: Main: 156553216 22:28:45 INFO - PROCESS | 12866 | 22:28:48 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:48 INFO - PROCESS | 12866 | RSS: Main: 156565504 22:28:48 INFO - PROCESS | 12866 | 22:28:51 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:51 INFO - PROCESS | 12866 | RSS: Main: 155795456 22:28:51 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | Cycle 1(1): loaded http://localhost:46647/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46647/tests/kraken/driver.html?testName=ai-astar) 22:28:53 INFO - PROCESS | 12866 | RSS: Main: 155734016 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | __start_tp_report 22:28:53 INFO - PROCESS | 12866 | _x_x_mozilla_page_load 22:28:53 INFO - PROCESS | 12866 | _x_x_mozilla_page_load_details 22:28:53 INFO - PROCESS | 12866 | |i|pagename|runs| 22:28:53 INFO - PROCESS | 12866 | |0;ai-astar;112;105;110;110;110;123;110;111;112;110 22:28:53 INFO - PROCESS | 12866 | |1;audio-beat-detection;157;128;152;169;130;151;154;128;166;127 22:28:53 INFO - PROCESS | 12866 | |2;audio-dft;162;168;161;164;161;161;162;163;167;165 22:28:53 INFO - PROCESS | 12866 | |3;audio-fft;73;73;73;75;73;139;73;72;73;72 22:28:53 INFO - PROCESS | 12866 | |4;audio-oscillator;96;96;150;96;97;97;149;95;97;139 22:28:53 INFO - PROCESS | 12866 | |5;imaging-gaussian-blur;109;109;110;110;110;111;110;110;110;110 22:28:53 INFO - PROCESS | 12866 | |6;imaging-darkroom;147;147;146;147;155;148;146;147;148;147 22:28:53 INFO - PROCESS | 12866 | |7;imaging-desaturate;97;97;97;96;99;97;97;97;96;97 22:28:53 INFO - PROCESS | 12866 | |8;json-parse-financial;58;61;60;58;61;62;63;59;76;56 22:28:53 INFO - PROCESS | 12866 | |9;json-stringify-tinderbox;68;69;70;69;69;69;69;70;73;69 22:28:53 INFO - PROCESS | 12866 | |10;stanford-crypto-aes;82;81;83;82;88;81;82;88;85;81 22:28:53 INFO - PROCESS | 12866 | |11;stanford-crypto-ccm;121;115;116;112;118;121;115;114;124;131 22:28:53 INFO - PROCESS | 12866 | |12;stanford-crypto-pbkdf2;143;146;153;148;144;144;144;143;145;141 22:28:53 INFO - PROCESS | 12866 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;78;65;66;65;67 22:28:53 INFO - PROCESS | 12866 | __end_tp_report 22:28:53 INFO - PROCESS | 12866 | __start_cc_report 22:28:53 INFO - PROCESS | 12866 | _x_x_mozilla_cycle_collect,725 22:28:53 INFO - PROCESS | 12866 | __end_cc_report 22:28:53 INFO - PROCESS | 12866 | __startTimestamp1453271333764__endTimestamp 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | ------- Summary: start ------- 22:28:53 INFO - PROCESS | 12866 | Number of tests: 14 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#0] ai-astar Cycles:10 Average:111.30 Median:110.50 stddev:4.55 (4.1%) stddev-sans-first:4.82 22:28:53 INFO - PROCESS | 12866 | Values: 112.0 105.0 110.0 110.0 110.0 123.0 110.0 111.0 112.0 110.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:153.00 stddev:16.46 (10.8%) stddev-sans-first:16.99 22:28:53 INFO - PROCESS | 12866 | Values: 157.0 128.0 152.0 169.0 130.0 151.0 154.0 128.0 166.0 127.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:2.55 (1.6%) stddev-sans-first:2.65 22:28:53 INFO - PROCESS | 12866 | Values: 162.0 168.0 161.0 164.0 161.0 161.0 162.0 163.0 167.0 165.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#3] audio-fft Cycles:10 Average:79.60 Median:73.00 stddev:20.89 (28.6%) stddev-sans-first:22.02 22:28:53 INFO - PROCESS | 12866 | Values: 73.0 73.0 73.0 75.0 73.0 139.0 73.0 72.0 73.0 72.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#4] audio-oscillator Cycles:10 Average:111.20 Median:96.00 stddev:24.19 (25.2%) stddev-sans-first:25.03 22:28:53 INFO - PROCESS | 12866 | Values: 96.0 96.0 150.0 96.0 97.0 97.0 149.0 95.0 97.0 139.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 22:28:53 INFO - PROCESS | 12866 | Values: 109.0 109.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 110.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:147.00 stddev:2.62 (1.8%) stddev-sans-first:2.76 22:28:53 INFO - PROCESS | 12866 | Values: 147.0 147.0 146.0 147.0 155.0 148.0 146.0 147.0 148.0 147.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:97.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 22:28:53 INFO - PROCESS | 12866 | Values: 97.0 97.0 97.0 96.0 99.0 97.0 97.0 97.0 96.0 97.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:5.54 (9.1%) stddev-sans-first:5.74 22:28:53 INFO - PROCESS | 12866 | Values: 58.0 61.0 60.0 58.0 61.0 62.0 63.0 59.0 76.0 56.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:1.35 (2.0%) stddev-sans-first:1.32 22:28:53 INFO - PROCESS | 12866 | Values: 68.0 69.0 70.0 69.0 69.0 69.0 69.0 70.0 73.0 69.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:82.50 stddev:2.75 (3.3%) stddev-sans-first:2.88 22:28:53 INFO - PROCESS | 12866 | Values: 82.0 81.0 83.0 82.0 88.0 81.0 82.0 88.0 85.0 81.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#11] stanford-crypto-ccm Cycles:10 Average:118.70 Median:119.50 stddev:5.70 (4.8%) stddev-sans-first:5.98 22:28:53 INFO - PROCESS | 12866 | Values: 121.0 115.0 116.0 112.0 118.0 121.0 115.0 114.0 124.0 131.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.10 Median:144.50 stddev:3.35 (2.3%) stddev-sans-first:3.46 22:28:53 INFO - PROCESS | 12866 | Values: 143.0 146.0 153.0 148.0 144.0 144.0 144.0 143.0 145.0 141.0 22:28:53 INFO - PROCESS | 12866 | 22:28:53 INFO - PROCESS | 12866 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:3.75 (5.6%) stddev-sans-first:3.97 22:28:53 INFO - PROCESS | 12866 | Values: 67.0 67.0 67.0 67.0 67.0 78.0 65.0 66.0 65.0 67.0 22:28:53 INFO - PROCESS | 12866 | -------- Summary: end -------- 22:28:53 INFO - PROCESS | 12866 | 22:28:54 INFO - TEST-INFO | 12866: exit 0 22:28:54 INFO - TEST-OK | kraken | took 60181ms 22:28:54 INFO - TEST-START | v8_7 22:28:54 INFO - operating with platform_type : linux_ 22:28:54 INFO - Initialising browser for v8_7 test... 22:28:55 INFO - TEST-INFO | started process 13157 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTcxrtL/profile http://localhost:46647/getInfo.html) 22:28:58 INFO - PROCESS | 13157 | 22:28:58 INFO - PROCESS | 13157 | (process:13228): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:28:59 INFO - PROCESS | 13157 | __metrics Screen width/height:1600/1200 22:28:59 INFO - PROCESS | 13157 | colorDepth:24 22:28:59 INFO - PROCESS | 13157 | Browser inner width/height: 1024/697 22:28:59 INFO - PROCESS | 13157 | __metrics 22:28:59 INFO - PROCESS | 13157 | 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:29:00 INFO - TEST-INFO | 13157: exit 0 22:29:00 INFO - Browser initialized. 22:29:00 INFO - Running cycle 1/1 for v8_7 test... 22:29:00 INFO - TEST-INFO | started process 13277 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTcxrtL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:01 INFO - PROCESS | 13277 | -- checkerboarding starts below -- 22:29:01 INFO - PROCESS | 13277 | RENDERTRACE 0.036084 rect red 0 0 10 10 // viewport 22:29:01 INFO - PROCESS | 13277 | RENDERTRACE 57.1411 rect brown 0 0 10 10 // page 22:29:01 INFO - PROCESS | 13277 | RENDERTRACE 57.1652 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 22:29:01 INFO - PROCESS | 13277 | Checkerboarded for 1 frames (66.3635 ms), 100 peak, 1 severity. 22:29:01 INFO - PROCESS | 13277 | 22:29:01 INFO - PROCESS | 13277 | (process:13334): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:29:12 INFO - PROCESS | 13277 | RSS: Main: 152690688 22:29:12 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | Cycle 1(1): loaded http://localhost:46647/tests/v8_7/run.html (next: http://localhost:46647/tests/v8_7/run.html) 22:29:34 INFO - PROCESS | 13277 | RSS: Main: 153882624 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | __start_tp_report 22:29:34 INFO - PROCESS | 13277 | _x_x_mozilla_page_load 22:29:34 INFO - PROCESS | 13277 | _x_x_mozilla_page_load_details 22:29:34 INFO - PROCESS | 13277 | |i|pagename|runs| 22:29:34 INFO - PROCESS | 13277 | |0;Richards;168.63406408094434 22:29:34 INFO - PROCESS | 13277 | |1;DeltaBlue;188.71485185884129 22:29:34 INFO - PROCESS | 13277 | |2;Encrypt;279.72027972027973 22:29:34 INFO - PROCESS | 13277 | |3;Decrypt;5202.072538860104 22:29:34 INFO - PROCESS | 13277 | |4;RayTrace;1101.3215859030836 22:29:34 INFO - PROCESS | 13277 | |5;Earley;761.6146230007616 22:29:34 INFO - PROCESS | 13277 | |6;Boyer;12317.073170731708 22:29:34 INFO - PROCESS | 13277 | |7;RegExp;41897.95918367347 22:29:34 INFO - PROCESS | 13277 | |8;Splay;609.327680193822 22:29:34 INFO - PROCESS | 13277 | |9;NavierStokes;4830.917874396136 22:29:34 INFO - PROCESS | 13277 | __end_tp_report 22:29:34 INFO - PROCESS | 13277 | __startTimestamp1453271374855__endTimestamp 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | ------- Summary: start ------- 22:29:34 INFO - PROCESS | 13277 | Number of tests: 10 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#0] Richards Cycles:1 Average:168.63 Median:168.63 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 168.6 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#1] DeltaBlue Cycles:1 Average:188.71 Median:188.71 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 188.7 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 279.7 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 5202.1 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#4] RayTrace Cycles:1 Average:1101.32 Median:1101.32 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 1101.3 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#5] Earley Cycles:1 Average:761.61 Median:761.61 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 761.6 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#6] Boyer Cycles:1 Average:12317.07 Median:12317.07 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 12317.1 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#7] RegExp Cycles:1 Average:41897.96 Median:41897.96 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 41898.0 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#8] Splay Cycles:1 Average:609.33 Median:609.33 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 609.3 22:29:34 INFO - PROCESS | 13277 | 22:29:34 INFO - PROCESS | 13277 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 22:29:34 INFO - PROCESS | 13277 | Values: 4830.9 22:29:34 INFO - PROCESS | 13277 | -------- Summary: end -------- 22:29:34 INFO - PROCESS | 13277 | 22:29:35 INFO - TEST-INFO | 13277: exit 0 22:29:35 INFO - TEST-OK | v8_7 | took 40744ms 22:29:35 INFO - SUITE-END | took 1211s 22:29:35 INFO - Completed test suite (00:20:11) 22:29:35 INFO - Working with test: dromaeo_css 22:29:35 INFO - Generating results file: dromaeo_css 22:29:35 INFO - Working with test: kraken 22:29:35 INFO - Generating results file: kraken 22:29:35 INFO - javascript benchmark 22:29:35 INFO - Working with test: v8_7 22:29:35 INFO - Generating results file: v8_7 22:29:35 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:29:35 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 22:29:35 INFO - process_Request line: dromaeo_css 6876.36 graph.html#tests=[[72,63,43]] 22:29:35 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:29:35 INFO - process_Request line: kraken 1512.00 graph.html#tests=[[232,63,43]] 22:29:35 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:29:36 INFO - process_Request line: v8_7 20092.32 graph.html#tests=[[230,63,43]] 22:29:36 INFO - RETURN: dromaeo_css: 6876.36 22:29:36 INFO - RETURN: kraken: 1512.00 22:29:36 INFO - RETURN: v8_7: 20092.32 22:29:36 INFO - javascript benchmark 22:29:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3152.4483215437294, 3160.762942779291, 3224.3873664003845, 3228.564143840581, 3252.951205731913, 5172.431032586422, 5249.081410753117, 5252.737363131842, 5263.368395792506, 5280.3663560372715, 5259.132243179871, 5270.288511051007, 5296.9735151324285, 5351.946480535194, 5359.222912677669, 4826.444958829736, 4884.829030983917, 4932.605391568683, 4943.332650092246, 4970.179920313144, 4873.585745211662, 4926.211806111023, 4945.085159245533, 4945.802167913297, 4956.702597844141, 4759.16714574948, 4828.034393121355, 4830.985493046464, 4861.440934292566, 4900.191468407708, 4807.134715751164, 4874.878128046792, 4877.658563900528, 4888.584470320027, 4920.975395123037, 4795.136875362434, 4811.13399588074, 4812.855614331575, 4823.927641085386, 4872.512748725099, 4966.0, 5135.614828887811, 5141.794328226866, 5152.278680984665, 5204.219367094966, 5734.684592347419, 5736.684482353469, 5738.139279108125, 5748.770049198069, 5756.769729210827, 5739.684317362543, 5755.654660720328, 5767.567432442584, 5781.075027995501, 5787.971060144672, 5266.262723218752, 5343.625946183769, 5344.278522399465, 5368.731563421813, 5468.945310546923, 5756.539476841843, 5808.5353171747, 5829.621074630136, 5842.970785146132, 5852.297724273115, 5593.552515798727, 5606.24315717373, 5622.718864056781, 5637.8590535236945, 5637.943620563824, 5596.356419011784, 5631.662100273997, 5657.434256574392, 5682.40334764852, 5683.204351390808, 5543.307086614173, 5589.273394458694, 5625.212470254168, 5637.46444087811, 5640.605157638925, 5649.830505084854, 5670.177824215466, 5670.603057785998, 5687.118496633028, 5692.174634677948, 4356.411884395629, 4407.977960110243, 4408.25059739845, 4414.867553973386, 4442.489113751895, 3906.3554513505524, 3962.821673024721, 3979.925420136547, 3985.04358953849, 4002.2795896738867, 5265.368155821326, 5273.973630131824, 5281.102212623863, 5283.498067683564, 5296.576273898079, 5704.857378565486, 5705.372409035002, 5714.0, 5725.08398656213, 5726.914096288559, 5143.974280128576, 5152.562032227303, 5216.58267338612, 5259.973700131475, 5268.1834315681135, 4833.589144922651, 4833.589144922651, 4834.226523756182, 4840.6127509799135, 4872.3909511311085, 5878.382769809108, 5919.052951527821, 5933.614315069507, 5937.139114828326, 5947.226860508193, 5621.8594535136945, 5684.65892046462, 5699.088145896802, 5712.0860662293835, 5736.799212027559, 5856.882862342814, 5942.61373010753, 5960.5231581474245, 5989.221401217814, 6011.579189456696, 5577.191307260506, 5583.916241256466, 5596.832095037155, 5603.131514615241, 5611.298587676541, 5631.662100273997, 5675.091985282335, 5680.091185410314, 5692.686902220417, 5778.56660750437, 5470.480304371078, 5552.833414997556, 5583.609147359808, 5650.519705824887, 5664.951983882995, 5468.207109969112, 5530.115181570929, 5545.223668686468, 5559.694216817952, 5594.0, 5389.083855744454, 5448.618596698193, 5461.972690136524, 5472.671639701631, 5521.089020311764, 5490.396056433709, 5496.0931446310915, 5642.887142257214, 5654.6889921054735, 5694.0, 5436.293281873489, 5458.754356053908, 5465.234867118686, 5466.863328416822, 5470.5350045245], "unit": "score", "name": "dojo.html", "value": 5247.84858457024}, {"lowerIsBetter": false, "replicates": [548.945105489451, 551.5008916930179, 574.2477354665389, 576.0897781505222, 586.6011112443537, 37129.81435092821, 37139.0, 37540.62459375405, 37579.24841503172, 37678.43482347763, 38834.223315533745, 39053.609463905355, 39305.41041884374, 39349.21301573967, 39367.0, 8383.203595658402, 8497.61760720769, 8544.70093546726, 8585.527795971218, 8606.698765543208, 8244.83510329793, 8339.874901876477, 8340.207680270394, 8366.58167091643, 8404.07555168931, 8349.749507514784, 8372.748817535483, 8430.83138337233, 8432.45189062712, 8457.915420845775, 8310.667573297062, 8397.95801020994, 8409.369297302695, 8428.704995325133, 8472.576371181416, 8285.047219569766, 8366.121557236464, 8385.538795366252, 8404.24361807434, 8444.83110337796, 35899.4615080774, 36124.27751444956, 36327.27345453102, 36413.0, 36660.450093248626, 3377.9155521111925, 3378.408778463759, 3400.4899265110184, 3453.913652158691, 3597.8740744074094, 29072.0, 29379.118626441243, 29549.704502955126, 29593.85203073971, 29807.25481862949, 3427.1260828488826, 3476.3568739783223, 3517.929641407183, 3651.9634803652157, 3678.393065144248, 258531.0, 263292.0, 263958.0, 264296.0, 264902.0, 52572.0, 58203.0, 59209.70395148083, 59279.70360148171, 59284.0, 46221.30668039982, 63637.36362636407, 64754.67622661857, 65085.349146508874, 65185.0, 13159.802602960963, 13207.207567545978, 13282.203067815963, 13308.334583270798, 13327.866721332857, 11097.667069987914, 11179.552817887356, 11221.887781122085, 11287.887121128684, 11310.038646715122, 19107.808921910604, 19431.805681943282, 19527.804721952598, 19617.901910490356, 20192.39422817318, 7955.204479551999, 8220.671173153007, 8243.134470880592, 8300.2114799094, 8300.521655707204, 967.5065716484644, 968.7190714692802, 1002.0480543483574, 1006.9295149339476, 1127.9492422840992, 28194.577081343796, 28229.0, 28291.0, 28392.148235552966, 28557.714422855923, 1044.9216308776872, 1045.1259037646473, 1059.6979860739807, 1071.7320669832543, 1079.082779637302, 14512.492062777752, 14788.0, 14913.776293355608, 14948.402063917321, 14973.251337433085, 290213.0, 296348.0, 297282.0, 297608.0, 298744.0, 51328.23007654888, 51478.74260628673, 51633.483665162865, 51635.741821290656, 51720.48279517156, 1322.3388305847077, 1350.4935649131576, 1357.6605848537865, 1360.4966162519931, 1366.4943970730894, 27367.72632273652, 28082.438351232453, 28293.0, 28301.57547636746, 28609.570856436752, 45896.31155532603, 46073.0, 46240.07519849517, 46585.53414465812, 46600.0, 272838.0, 274697.0, 275184.0, 275269.0, 276502.0, 31709.20726981844, 31932.5210121853, 32113.0, 32212.0, 32224.0, 38008.61991380051, 39949.60050399459, 40839.0, 41435.585644144376, 41946.790266048476, 34659.653403466655, 34940.301193976484, 35445.0, 35598.64401355953, 35803.462948055276, 34595.654043459246, 34889.651103489654, 35105.47341789824, 35392.0, 35445.0, 33746.493802593475, 34159.82920085483, 34328.0, 34445.31109377849, 34453.138671533416, 31696.841515792272, 32016.81679282575, 32074.518882217253, 32350.0, 33084.0, 23015.19446819353, 23164.305070847902, 23177.3046808596, 23343.649845252676, 23369.532609347378], "unit": "score", "name": "ext.html", "value": 17947.187223842997}, {"lowerIsBetter": false, "replicates": [366.2199515032979, 367.2269871919609, 370.3130692565291, 372.21462713674157, 375.23639393833554, 13763.380647870845, 14307.499237526688, 15612.219389030546, 16356.263968121433, 17088.487345379643, 15502.767458488132, 15667.041619797525, 16283.674326513492, 16288.26702798374, 16324.83675163248, 5042.974785126078, 5392.406835248139, 5406.783728650869, 5408.107662235746, 5452.672839629635, 5080.542751152396, 5237.86905327368, 5246.658967167141, 5279.41926388097, 5310.097283461821, 5225.190095535179, 5250.133727934886, 5307.814226502073, 5711.114777209539, 5727.57043221758, 5430.59270554706, 5776.151631477929, 5855.23881895353, 5931.703414829263, 5933.673647949383, 5423.7830486780485, 5445.346558412976, 5445.53712934401, 5455.809046683386, 5477.260569823063, 11691.181617286793, 12100.394980250952, 12106.757864842742, 12124.454399552042, 12514.31171285579, 1971.9704204436946, 2025.5543780368444, 2057.177129148353, 2109.0614676468977, 2240.451089483071, 8812.07473215316, 8899.510526921016, 9231.907680923172, 10109.292349535535, 11980.460879260454, 1805.3681211575974, 1830.8718389712726, 1839.2734869726382, 1851.5556266496078, 1868.6169335286233, 54242.18636720452, 54264.186037209474, 54872.176917346274, 56284.718576406856, 56582.15126773102, 9837.409755414697, 9911.058449447291, 9938.652147174815, 9942.602295908227, 9995.350302230332, 12785.61643150707, 12873.098883078283, 12920.870791291967, 12943.741125177443, 12952.481900723866, 6827.180738311435, 6854.143232095988, 6951.65241737911, 7002.614856182857, 7066.152061752623, 6479.157709497735, 6589.967050164719, 6831.4534837212905, 6885.173779146535, 6900.068490753784, 3987.7806720630642, 4148.357004664282, 4195.853145139906, 4315.4605674290715, 4388.71473354231, 1821.3807305516189, 1842.2078506242444, 1844.2807305151002, 1854.0, 1866.9626607467774, 921.2721949659827, 933.710549729586, 976.1653786012998, 993.4982833668951, 1008.037324355239, 16036.358545658277, 16138.112403817902, 16182.595435114215, 16392.590185245226, 16652.0, 955.7992821507561, 961.024560071528, 990.9792913299314, 995.4922989275377, 1002.6390499420203, 9402.95298523503, 9494.810103798023, 9529.094736000068, 9602.0, 9687.757806054906, 367893.0, 373543.0, 378162.0, 519108.0, 527803.0, 69178.65410672914, 72966.0, 73913.0, 74226.0, 77338.0, 1101.0860985382274, 1120.7254222715487, 1124.3366413815888, 1135.7558125003163, 1138.772245550893, 15001.849981500482, 15046.305536705437, 15112.0, 15194.108772610924, 15580.454684086002, 31857.61067919869, 32184.517232242004, 33185.336293274486, 33286.33427331488, 33702.66297336995, 41894.37158442565, 42220.577794222896, 42259.0, 42832.5716742841, 45304.0, 6952.270011648704, 7104.0, 7111.111111111111, 7158.570485770871, 7321.828507438784, 8435.8734618982, 10164.13604843567, 10663.253572249869, 10929.453527323443, 10930.234883558393, 9382.03305473757, 9442.952785236304, 9570.760730981781, 9933.255005874444, 10038.598456061676, 9619.326647134912, 9735.464549449849, 9759.9024009759, 10070.295079344669, 10168.542415591464, 9874.654387096705, 9896.158826499828, 10143.7464063399, 10223.488825558841, 10328.741781455523, 7325.706971721072, 7409.777706668593, 7463.067116610424, 7473.0, 7504.1370242420635, 8093.716719914774, 10019.599216031569, 10027.448490333101, 10029.348092373972, 10082.848757268794], "unit": "score", "name": "jquery.html", "value": 7977.262494706114}, {"lowerIsBetter": false, "replicates": [1490.9105453672778, 1502.4516051641147, 1675.2796297592038, 1784.1079460269866, 1805.2147315917584, 7764.18476060014, 7779.688812447503, 7977.5612341321275, 7995.0, 8079.151689072636, 8042.356611471083, 8102.513849169054, 8184.0588332341795, 8186.959065204681, 8232.588370581478, 2317.640765681322, 2352.6353415220583, 2353.9882300588474, 2354.1878052072093, 2363.385519764865, 2341.496578235679, 2343.8476499027593, 2377.5482658294954, 2381.416552944522, 2385.0459816073535, 2261.276391554695, 2313.8611683299055, 2331.9300420987397, 2340.871252081135, 2356.634721618152, 2315.4674424882205, 2318.9564695886834, 2320.1995311617466, 2325.244295603927, 2340.1107579119825, 2360.3745007573007, 2374.78626923576, 2387.6299173628117, 2389.6654468374268, 2390.7011623547055, 7877.0, 7890.368770498347, 7902.723404680866, 7907.762767116996, 7917.2874441300555, 4825.9034819303415, 4953.6037117030555, 4959.925601115986, 4964.205727083649, 4977.129002424561, 3095.721385075354, 3123.453395655774, 3170.4134735074085, 3187.4103290891258, 3232.9030129096163, 894.3828758156851, 910.0944560162615, 930.5114814722257, 957.0525180071679, 985.1724551376806, 11197.60808371703, 11203.887961120367, 11282.0, 11297.209195356329, 11318.264312819723, 4293.119910418357, 4295.935560966588, 4347.7826108694435, 4366.104948485553, 4412.271975124133, 8778.385513014155, 8795.648174072965, 8800.339974501938, 8811.118888111137, 8830.86753698695, 4930.802767889244, 4948.960718249136, 4954.058728841508, 4955.702657840541, 4959.107360675041, 4531.184386810406, 4535.569120933572, 4542.477615074243, 4556.817727290938, 4557.384753058361, 3495.7378196635354, 3512.332656795201, 3523.94714079289, 3527.1534831640734, 3545.503629491873, 1037.1650821088945, 1067.1196263082988, 1092.8032954068344, 1112.0991996482874, 1113.2318700096907, 1104.2325583719398, 1123.9494222759995, 1195.5397172088808, 1289.6260084575556, 1366.8838148757268, 7341.669624866894, 7365.52124111931, 7377.704891804375, 7388.630568471555, 7492.887606685904, 1150.5857891159176, 1158.467105131632, 1162.3839365136491, 1169.1231576317762, 1180.4569897846916, 5764.625299355528, 5847.6783776891825, 5913.379095195029, 5929.644221346733, 5932.525397968152, 9331.16019558243, 9607.615695372248, 9612.279079069098, 9667.371620844622, 9743.123118919331, 9421.38760980534, 9429.528523573794, 9516.714498565054, 9532.52337383142, 9600.231981441468, 7612.200718924544, 7646.23537646242, 7656.885146722803, 7700.075990881131, 7722.034745656793, 7242.275772422716, 7344.559326440217, 7360.66876990526, 7386.704531818667, 7398.223186565333, 8503.574821259035, 8646.956765216133, 8674.349423793366, 8701.260392866678, 8749.781255468664, 8803.207711306013, 8921.776955576162, 8961.103889610988, 8992.55037248148, 9001.729948101567, 6799.626020568719, 6934.7226110955, 6938.722451102101, 6938.757143499954, 6955.130608673916, 7592.734254301073, 7620.5808680523105, 7715.57564333967, 7848.058233011847, 7868.567228802471, 6957.652117394211, 7179.820504487429, 7308.707651693872, 7486.064241969754, 7558.2441755823975, 7042.366187043191, 7103.2541583133025, 7114.181869085121, 7138.250483699345, 7265.346118849329, 7024.051753013277, 7054.753083642048, 7115.857682846417, 7115.857682846417, 7122.5726456412785, 6515.185601799775, 6517.674116294072, 6531.314212007671, 6573.901391479036, 6577.80266592003, 6380.202474690664, 6471.288158302677, 6507.707153178024, 6527.184101987252, 6603.669816509251], "unit": "score", "name": "mootools.html", "value": 4589.390336695381}, {"lowerIsBetter": false, "replicates": [335.5319329535297, 340.02413074476243, 341.28671077448143, 344.97067749241313, 355.3585777671303, 6175.919214137519, 6239.376062393764, 6327.778527751524, 6330.65181415022, 6332.208473940757, 6076.605020673653, 6193.1329613854095, 6211.223597050369, 6212.78255261066, 6218.4714299284615, 4721.291806229059, 4782.30656554801, 4788.832390866321, 4798.4001999750035, 4807.78364973577, 4800.42394912611, 4810.759462026902, 4811.759412029384, 4816.783244753994, 4816.951830481703, 4644.86065418038, 4735.550122738335, 4754.548317909793, 4765.142274390609, 4790.784414701329, 4732.621390288786, 4751.524847515256, 4752.429708434993, 4758.3100450434495, 4764.547368000034, 4183.0, 4184.121334519755, 4186.623203911632, 4196.454460920061, 4211.410402543646, 5910.34986151523, 5947.1376650385455, 5953.315368732608, 5962.970185149046, 5968.462838344526, 5455.454454554552, 5605.5515558755205, 5605.943940560583, 5609.775608975636, 5620.016497112989, 6637.767678131242, 6691.3308669133185, 6720.966395168041, 6723.025161351626, 6731.629760363178, 4617.584417402416, 4637.582617564435, 4657.394538709946, 4662.463816661059, 4676.672632915697, 6801.42187914023, 6891.345322194384, 6959.095317608729, 6960.0, 6965.651717414109, 4730.787114579852, 4764.071006153836, 4774.212254977959, 4774.809007639656, 4780.545848144421, 4818.253170758538, 4824.662273640882, 4826.975865120652, 4828.299896514985, 4846.781894814671, 5546.362168350611, 5551.25058117149, 5557.5831812614215, 5558.944410555842, 5561.860953476195, 6503.6097834130105, 6655.966720166368, 6681.265060843303, 6697.3637504437, 6703.497237707192, 1617.6683779825107, 1649.0682764238406, 1653.9503814885572, 1654.7435147552148, 1675.6062325353532, 1090.8091084060259, 1096.7203363142348, 1107.0645304717661, 1115.5147510832815, 1115.5872327238974, 6127.111568822585, 6164.291106522717, 6180.969095154495, 6190.473809726133, 6206.968965155145, 6694.564853284521, 6815.625140617313, 6823.147106611674, 6825.590464572042, 6846.281140480278, 6075.149479072933, 6173.59871608344, 6176.81469555914, 6182.072689096672, 6185.752569897244, 3685.3734865073, 3696.2607478504406, 3708.8331025103935, 3716.8141592920247, 3720.6465385788847, 7057.752978645723, 7058.6823592938445, 7097.361237488651, 7145.571265724073, 7146.856502959606, 6817.795466136024, 6926.064981227571, 6967.268436814163, 6975.337342952412, 6979.092717946736, 7104.147502299757, 7114.430845532345, 7120.074390329328, 7135.678894449762, 7138.785836424916, 6770.0, 6790.388865002174, 6794.286599907136, 6829.965850170717, 6837.658117094026, 6570.080188773671, 6677.0, 6706.597604143767, 6745.898811517733, 6752.628605426748, 7068.71725130989, 7068.9293107070325, 7096.290370963069, 7100.9289907100265, 7127.928720712726, 4704.435467743756, 4708.599769019672, 4711.175544279806, 4711.552402521755, 4718.150732867979, 6715.160604924385, 6779.593224406552, 6800.82997925036, 6804.76183333581, 6926.030355750098, 6704.765333213314, 6753.594784312957, 6754.797356079325, 6791.966040169767, 6792.049113124265, 6651.700673469609, 6678.699458524476, 6678.831204539283, 6699.933000670126, 6711.060451536887, 6522.412982831568, 6602.504812139205, 6628.569143005882, 6646.933530664632, 6648.301928297459, 6778.525503214728, 6781.389165171543, 6782.0505129280955, 6791.01530278097, 6792.184937807495, 6529.738810447719, 6559.6064236144075, 6570.080188773671, 6605.009248612747, 6611.966940165268], "unit": "score", "name": "prototype.html", "value": 5003.419031686403}, {"lowerIsBetter": false, "replicates": [736.3593673504053, 748.7341993592278, 777.4091690315361, 791.9366450683946, 810.8216192437661, 12948.935255323722, 13019.349032548358, 13150.079494435382, 13191.736165276701, 13212.339383030858, 12527.686807829808, 12544.623661290154, 12579.622611321653, 12673.746525069517, 12725.872741272584, 810.5987536169604, 814.8125931035866, 814.9837003259946, 821.4824660463929, 822.8230930349973, 816.0044745410619, 827.5613924619931, 828.349745449822, 828.5443006346516, 830.1947111302028, 857.9013413457438, 865.6364326982682, 866.9263112635402, 867.154524338771, 867.6139118092451, 857.4897935728231, 859.2438653984516, 863.011851430109, 865.7316231968047, 868.7741187291318, 630.6373835044867, 632.3076231526471, 636.1285039495874, 637.2194062273724, 640.0, 13153.802692959613, 13233.338333083308, 13243.072984891061, 13258.204507729568, 13303.40134693941, 12235.510579576807, 12256.877431225752, 12463.002959763196, 12618.116731828775, 12818.359082045861, 10189.0, 10220.897791022144, 10259.640912568098, 10350.223733219964, 10393.376397416103, 3442.5524681791208, 3505.4040813061843, 3522.4716292556063, 3625.4380571011534, 3638.781673099596, 44857.775711121234, 45107.09785804265, 45415.31877021847, 45548.08903821905, 45829.77085114553, 9693.563789629343, 9824.656137035312, 9885.159761420216, 9934.552945117486, 10012.649557265606, 17657.558561036076, 17861.28554857791, 17891.10544472797, 17893.7315940261, 17914.193861276275, 7287.599182044933, 7394.075740532434, 7423.257674232534, 7471.70113195477, 7539.73610923626, 4122.237386083584, 4123.773192474383, 4151.045259590311, 4175.728577642443, 4190.853320133841, 3053.694630536973, 3056.7401770849283, 3083.2137804859617, 3087.984560077185, 3094.5822313987323, 561.5788158880839, 565.0619970848372, 568.7724910035936, 604.4982664388547, 630.0989584893646, 598.9066471673756, 602.135934933372, 603.9295150684443, 632.4845251120322, 639.3382848751613, 7770.689172433039, 7840.647170877324, 7894.407919406068, 7916.129225785159, 7939.087004994384, 502.45734606624757, 549.5658429840381, 558.2491548866768, 579.9623024503394, 663.9701213445406, 3785.5268091488565, 3859.26673931952, 3934.881953541398, 4079.143379890197, 4109.239790638741, 52930.20604690933, 53512.46487535075, 54298.45701542934, 54533.0, 54662.72668636632, 46655.30017049747, 47555.0, 47618.0, 47734.0, 48054.0, 43415.348769768476, 43641.56358436438, 43756.09292745439, 43920.78039609845, 44616.0, 7928.405369597419, 8172.959135204286, 8198.754037378889, 8211.917880821355, 8235.21765432283, 32138.678613214503, 32333.191670208435, 32457.675423245466, 33518.0, 33936.83031584925, 37794.055148621504, 40257.0, 42342.78828605837, 42826.35760463658, 43296.13407731891, 10251.179905607683, 10442.895571044191, 10506.317089389166, 11081.612143574936, 11329.376884271443, 11551.711207219887, 11657.88342116602, 11753.647390578297, 12048.879511204776, 12065.517379304729, 10537.766871653164, 10823.296485728402, 10838.132949363875, 11152.832707509557, 11362.375069371265, 10427.497549836537, 10470.152720950784, 10481.713646889759, 10519.053285204061, 10536.209784266057, 11121.332720036824, 11284.435778210893, 11380.374079425712, 11393.259438136494, 11743.530258789895, 9147.496887671243, 9352.29857760657, 9505.334626576074, 9561.139497445263, 9756.46339451337, 8608.65565377378, 8712.778241548365, 9132.360734748503, 10041.497925103862, 10134.391936483835], "unit": "score", "name": "yui.html", "value": 6127.227718021684}], "name": "dromaeo_css", "value": 6876.361544459632}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 105.0, 110.0, 110.0, 110.0, 123.0, 110.0, 111.0, 112.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [157.0, 128.0, 152.0, 169.0, 130.0, 151.0, 154.0, 128.0, 166.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 146.2}, {"lowerIsBetter": true, "replicates": [162.0, 168.0, 161.0, 164.0, 161.0, 161.0, 162.0, 163.0, 167.0, 165.0], "unit": "score", "name": "audio-dft", "value": 163.4}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 75.0, 73.0, 139.0, 73.0, 72.0, 73.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 150.0, 96.0, 97.0, 97.0, 149.0, 95.0, 97.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 111.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 155.0, 148.0, 146.0, 147.0, 148.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.8}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 97.0, 96.0, 99.0, 97.0, 97.0, 97.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 97.0}, {"lowerIsBetter": true, "replicates": [58.0, 61.0, 60.0, 58.0, 61.0, 62.0, 63.0, 59.0, 76.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 61.4}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 70.0, 69.0, 69.0, 69.0, 69.0, 70.0, 73.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.5}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 83.0, 82.0, 88.0, 81.0, 82.0, 88.0, 85.0, 81.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.3}, {"lowerIsBetter": true, "replicates": [121.0, 115.0, 116.0, 112.0, 118.0, 121.0, 115.0, 114.0, 124.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.7}, {"lowerIsBetter": true, "replicates": [143.0, 146.0, 153.0, 148.0, 144.0, 144.0, 144.0, 143.0, 145.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.1}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 78.0, 65.0, 66.0, 65.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken", "value": 1512.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.63406408094434], "unit": "score", "name": "Richards", "value": 209.34085999999994}, {"lowerIsBetter": false, "replicates": [188.71485185884129], "unit": "score", "name": "DeltaBlue", "value": 350.35928200000006}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1101.3215859030836], "unit": "score", "name": "RayTrace", "value": 671.910012}, {"lowerIsBetter": false, "replicates": [761.6146230007616], "unit": "score", "name": "Earley", "value": 875.065919}, {"lowerIsBetter": false, "replicates": [12317.073170731708], "unit": "score", "name": "Boyer", "value": 54.108877227722786}, {"lowerIsBetter": false, "replicates": [41897.95918367347], "unit": "score", "name": "RegExp", "value": 21.742944471505123}, {"lowerIsBetter": false, "replicates": [609.327680193822], "unit": "score", "name": "Splay", "value": 133.73920576540755}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20092.320961559355}]} 22:29:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "20092.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1512.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6876.36"}}} 22:29:36 INFO - Return code: 0 22:29:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:29:36 INFO - # TBPL SUCCESS # 22:29:36 INFO - Running post-action listener: _resource_record_post_action 22:29:36 INFO - Running post-run listener: _resource_record_post_run 22:29:37 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 151552; Write bytes: 552173568; Read time: 264; Write time: 771176 22:29:37 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 8192; Write bytes: 236646400; Read time: 240; Write time: 371348 22:29:37 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 143360; Write bytes: 314503168; Read time: 24; Write time: 399788 22:29:37 INFO - Running post-run listener: _upload_blobber_files 22:29:37 INFO - Blob upload gear active. 22:29:37 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:29:37 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:29:37 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:29:37 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:29:37 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:29:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:38 INFO - (blobuploader) - INFO - Done attempting. 22:29:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:29:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:39 INFO - (blobuploader) - INFO - Done attempting. 22:29:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:39 INFO - Return code: 0 22:29:39 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:29:39 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b8995c58bc3c300a4257802c82d8dba9a4eeb57e26415cbc07a68bc35e10666dabf72916253a34a812e72887d5c29c9ec96b9e61fab4ec94fe193adcc95ad87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5b938fcfb590f3da940ecff1205f46e82382087ec805eadc8423097fec367837b64efc7fa9493dc33a48f6c9987e671f8576467f95c1356f6f76beab7760e5ae"} 22:29:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:29:39 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:29:39 INFO - Contents: 22:29:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b8995c58bc3c300a4257802c82d8dba9a4eeb57e26415cbc07a68bc35e10666dabf72916253a34a812e72887d5c29c9ec96b9e61fab4ec94fe193adcc95ad87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5b938fcfb590f3da940ecff1205f46e82382087ec805eadc8423097fec367837b64efc7fa9493dc33a48f6c9987e671f8576467f95c1356f6f76beab7760e5ae"} 22:29:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:39 INFO - Copying logs to upload dir... 22:29:39 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 22:29:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.429199 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-01-19 22:29:39.282080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-19 22:29:39.286339) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b8995c58bc3c300a4257802c82d8dba9a4eeb57e26415cbc07a68bc35e10666dabf72916253a34a812e72887d5c29c9ec96b9e61fab4ec94fe193adcc95ad87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5b938fcfb590f3da940ecff1205f46e82382087ec805eadc8423097fec367837b64efc7fa9493dc33a48f6c9987e671f8576467f95c1356f6f76beab7760e5ae"} build_url:https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/Fu9FKW9VS2iHNj5GM9gE9w/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6b8995c58bc3c300a4257802c82d8dba9a4eeb57e26415cbc07a68bc35e10666dabf72916253a34a812e72887d5c29c9ec96b9e61fab4ec94fe193adcc95ad87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5b938fcfb590f3da940ecff1205f46e82382087ec805eadc8423097fec367837b64efc7fa9493dc33a48f6c9987e671f8576467f95c1356f6f76beab7760e5ae"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-19 22:29:39.311780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:29:39.312147) ========= 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-1453255419.145715-1707805862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 22:29:39.393555) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-19 22:29:39.393876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-19 22:29:39.394313) ========= ========= Total master_lag: 0.34 =========