builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1453801045.98 results: success (0) buildid: 20160125070233 builduid: eb2ea0af6ac2494ca810680a4890723f revision: 3bc504ea394f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:25.980009) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:25.981135) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:25.981887) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004312 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.008712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.009073) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.023198) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.023860) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004562 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.078127) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.078832) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.079681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.080464) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-26 01:37:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.3M=0s 2016-01-26 01:37:26 (68.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.203427) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.203831) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022493 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.254201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:26.254596) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3bc504ea394f --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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-26 01:37:27,092 Setting DEBUG logging. 2016-01-26 01:37:27,092 attempt 1/10 2016-01-26 01:37:27,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3bc504ea394f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-26 01:37:27,352 unpacking tar archive at: mozilla-beta-3bc504ea394f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.877716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:27.150002) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:27.150904) ========= echo 3bc504ea394f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3bc504ea394f'] 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3bc504ea394f program finished with exit code 0 elapsedTime=0.004691 script_repo_revision: '3bc504ea394f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:27.205803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:27.206226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-26 01:37:27.216663) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-01-26 01:37:27.217019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:37:28 INFO - MultiFileLogger online at 20160126 01:37:28 in /builds/slave/test-pgo 01:37:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:37:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 01:37:28 INFO - {'append_to_log': False, 01:37:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 01:37:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:37:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 01:37:28 INFO - 'branch': 'Mozilla-Beta', 01:37:28 INFO - 'buildbot_json_path': 'buildprops.json', 01:37:28 INFO - 'config_files': ('talos/linux_config.py',), 01:37:28 INFO - 'default_actions': ('clobber', 01:37:28 INFO - 'read-buildbot-config', 01:37:28 INFO - 'download-and-extract', 01:37:28 INFO - 'populate-webroot', 01:37:28 INFO - 'create-virtualenv', 01:37:28 INFO - 'install', 01:37:28 INFO - 'run-tests'), 01:37:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:37:28 INFO - 'download_minidump_stackwalk': True, 01:37:28 INFO - 'download_symbols': 'ondemand', 01:37:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:37:28 INFO - 'tooltool.py': '/tools/tooltool.py', 01:37:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:37:28 INFO - '/tools/misc-python/virtualenv.py')}, 01:37:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:37:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:37:28 INFO - 'installer_path': 'installer.exe', 01:37:28 INFO - 'log_level': 'info', 01:37:28 INFO - 'log_name': 'talos', 01:37:28 INFO - 'log_to_console': True, 01:37:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:37:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:37:28 INFO - 'opt_config_files': (), 01:37:28 INFO - 'pip_index': False, 01:37:28 INFO - 'sps_profile': False, 01:37:28 INFO - 'sps_profile_interval': 0, 01:37:28 INFO - 'suite': 'dromaeojs', 01:37:28 INFO - 'system_bits': '32', 01:37:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:37:28 INFO - 'title': 'talos-linux64-ix-034', 01:37:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:37:28 INFO - 'use_talos_json': True, 01:37:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 01:37:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:37:28 INFO - 'work_dir': 'build'} 01:37:28 INFO - ##### 01:37:28 INFO - ##### Running clobber step. 01:37:28 INFO - ##### 01:37:28 INFO - Running pre-action listener: _resource_record_pre_action 01:37:28 INFO - Running main action method: clobber 01:37:28 INFO - rmtree: /builds/slave/test-pgo/build 01:37:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 01:37:28 INFO - Running post-action listener: _resource_record_post_action 01:37:28 INFO - ##### 01:37:28 INFO - ##### Running read-buildbot-config step. 01:37:28 INFO - ##### 01:37:28 INFO - Running pre-action listener: _resource_record_pre_action 01:37:28 INFO - Running main action method: read_buildbot_config 01:37:28 INFO - Using buildbot properties: 01:37:28 INFO - { 01:37:28 INFO - "properties": { 01:37:28 INFO - "buildnumber": 62, 01:37:28 INFO - "product": "firefox", 01:37:28 INFO - "script_repo_revision": "production", 01:37:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:37:28 INFO - "repository": "", 01:37:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:37:28 INFO - "buildid": "20160125070233", 01:37:28 INFO - "slavename": "talos-linux64-ix-034", 01:37:28 INFO - "pgo_build": "True", 01:37:28 INFO - "basedir": "/builds/slave/test-pgo", 01:37:28 INFO - "project": "", 01:37:28 INFO - "platform": "ubuntu64_hw", 01:37:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:37:28 INFO - "slavebuilddir": "test", 01:37:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:37:28 INFO - "branch": "mozilla-beta", 01:37:28 INFO - "repo_path": "releases/mozilla-beta", 01:37:28 INFO - "stage_platform": "linux64-pgo", 01:37:28 INFO - "builduid": "eb2ea0af6ac2494ca810680a4890723f", 01:37:28 INFO - "revision": "3bc504ea394f" 01:37:28 INFO - }, 01:37:28 INFO - "sourcestamp": { 01:37:28 INFO - "repository": "", 01:37:28 INFO - "hasPatch": false, 01:37:28 INFO - "project": "", 01:37:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:37:28 INFO - "changes": [ 01:37:28 INFO - { 01:37:28 INFO - "category": null, 01:37:28 INFO - "files": [ 01:37:28 INFO - { 01:37:28 INFO - "url": null, 01:37:28 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2" 01:37:28 INFO - } 01:37:28 INFO - ], 01:37:28 INFO - "repository": "", 01:37:28 INFO - "rev": "3bc504ea394f", 01:37:28 INFO - "who": "sendchange", 01:37:28 INFO - "when": 1453769833, 01:37:28 INFO - "number": 7514634, 01:37:28 INFO - "comments": "Bug 1241916 - Adds gn locale to browser/locales/shipped-locales. r=delphine a=sylvestre", 01:37:28 INFO - "project": "", 01:37:28 INFO - "at": "Mon 25 Jan 2016 16:57:13", 01:37:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 01:37:28 INFO - "revlink": "", 01:37:28 INFO - "properties": [ 01:37:28 INFO - [ 01:37:28 INFO - "buildid", 01:37:28 INFO - "20160125070233", 01:37:28 INFO - "Change" 01:37:28 INFO - ], 01:37:28 INFO - [ 01:37:28 INFO - "builduid", 01:37:28 INFO - "eb2ea0af6ac2494ca810680a4890723f", 01:37:28 INFO - "Change" 01:37:28 INFO - ], 01:37:28 INFO - [ 01:37:28 INFO - "pgo_build", 01:37:28 INFO - "True", 01:37:28 INFO - "Change" 01:37:28 INFO - ] 01:37:28 INFO - ], 01:37:28 INFO - "revision": "3bc504ea394f" 01:37:28 INFO - } 01:37:28 INFO - ], 01:37:28 INFO - "revision": "3bc504ea394f" 01:37:28 INFO - } 01:37:28 INFO - } 01:37:28 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2. 01:37:28 INFO - Running post-action listener: _resource_record_post_action 01:37:28 INFO - ##### 01:37:28 INFO - ##### Running download-and-extract step. 01:37:28 INFO - ##### 01:37:28 INFO - Running pre-action listener: _resource_record_pre_action 01:37:28 INFO - Running main action method: download_and_extract 01:37:28 INFO - mkdir: /builds/slave/test-pgo/build/tests 01:37:28 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')]} 01:37:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json 01:37:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 01:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 01:37:28 INFO - Downloaded 1270 bytes. 01:37:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 01:37:28 INFO - Using the following test package requirements: 01:37:28 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:37:28 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:37:28 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'jsshell-linux-x86_64.zip'], 01:37:28 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:37:28 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:37:28 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 01:37:28 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 01:37:28 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:37:28 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 01:37:28 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 01:37:28 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:37:28 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:37:28 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')]} 01:37:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:37:29 INFO - Downloaded 21580477 bytes. 01:37:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:37:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:29 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:37:30 INFO - Return code: 0 01:37:30 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:37:30 INFO - Downloaded 21580477 bytes. 01:37:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 01:37:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 01:37:30 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:37:31 INFO - Return code: 0 01:37:31 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')]} 01:37:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:37:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:37:32 INFO - Downloaded 11132038 bytes. 01:37:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 01:37:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 01:37:32 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:37:32 INFO - Return code: 0 01:37:32 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')]} 01:37:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:37:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:37:33 INFO - Downloaded 57947831 bytes. 01:37:33 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:37:33 INFO - mkdir: /builds/slave/test-pgo/properties 01:37:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 01:37:33 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 01:37:33 INFO - Contents: 01:37:33 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 01:37:33 INFO - Running post-action listener: _resource_record_post_action 01:37:33 INFO - Running post-action listener: set_extra_try_arguments 01:37:33 INFO - ##### 01:37:33 INFO - ##### Running populate-webroot step. 01:37:33 INFO - ##### 01:37:33 INFO - Running pre-action listener: _resource_record_pre_action 01:37:33 INFO - Running main action method: populate_webroot 01:37:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:37:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:37:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:37:33 INFO - '--tppagecycles', 01:37:33 INFO - '7'], 01:37:33 INFO - 'tests': ['tsvgm']}}, 01:37:33 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:37:33 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:37:33 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:37:33 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:37:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'tests': ['damp', 'tps']}, 01:37:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['damp', 'tps']}, 01:37:33 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:37:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:37:33 INFO - 'other': {'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'other_l64': {'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'other_nol64': {'tests': ['a11yr', 01:37:33 INFO - 'ts_paint', 01:37:33 INFO - 'tpaint', 01:37:33 INFO - 'sessionrestore', 01:37:33 INFO - 'sessionrestore_no_auto_restore']}, 01:37:33 INFO - 'svgr': {'tests': ['tsvgx', 01:37:33 INFO - 'tsvgr_opacity', 01:37:33 INFO - 'tart', 01:37:33 INFO - 'tscrollx', 01:37:33 INFO - 'cart']}, 01:37:33 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['tsvgx', 01:37:33 INFO - 'tsvgr_opacity', 01:37:33 INFO - 'tart', 01:37:33 INFO - 'tscrollx', 01:37:33 INFO - 'cart']}, 01:37:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'tests': ['tp5o']}, 01:37:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'talos_options': ['--e10s'], 01:37:33 INFO - 'tests': ['tp5o']}, 01:37:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'talos_options': ['--xperf_path', 01:37:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:33 INFO - 'tests': ['tp5n']}, 01:37:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:37:33 INFO - 'talos_options': ['--e10s', 01:37:33 INFO - '--xperf_path', 01:37:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:33 INFO - 'tests': ['tp5n']}}, 01:37:33 INFO - 'talos.zip': {'path': '', 01:37:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:37:33 INFO - Running post-action listener: _resource_record_post_action 01:37:33 INFO - ##### 01:37:33 INFO - ##### Running create-virtualenv step. 01:37:33 INFO - ##### 01:37:33 INFO - Running pre-action listener: _resource_record_pre_action 01:37:33 INFO - Running main action method: create_virtualenv 01:37:33 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 01:37:33 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 01:37:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 01:37:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:37:33 INFO - Using real prefix '/usr' 01:37:33 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 01:37:33 INFO - Installing distribute.............................................................................................................................................................................................done. 01:37:35 INFO - Installing pip.................done. 01:37:35 INFO - Return code: 0 01:37:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 01:37: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')]} 01:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:35 INFO - Using env: {'DISPLAY': ':0', 01:37:35 INFO - 'HOME': '/home/cltbld', 01:37:35 INFO - 'LANG': 'en_US.UTF-8', 01:37:35 INFO - 'LANGUAGE': 'en_US:en', 01:37:35 INFO - 'LOGNAME': 'cltbld', 01:37:35 INFO - 'MAIL': '/var/mail/cltbld', 01:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:35 INFO - 'MOZ_NO_REMOTE': '1', 01:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:35 INFO - 'NO_EM_RESTART': '1', 01:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:35 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:35 INFO - 'SHELL': '/bin/bash', 01:37:35 INFO - 'SHLVL': '1', 01:37:35 INFO - 'TERM': 'linux', 01:37:35 INFO - 'TMOUT': '86400', 01:37:35 INFO - 'USER': 'cltbld', 01:37:35 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:35 INFO - '_': '/tools/buildbot/bin/python'} 01:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:36 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 01:37:36 INFO - Cleaning up... 01:37:36 INFO - Return code: 0 01:37:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 01:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:36 INFO - Using env: {'DISPLAY': ':0', 01:37:36 INFO - 'HOME': '/home/cltbld', 01:37:36 INFO - 'LANG': 'en_US.UTF-8', 01:37:36 INFO - 'LANGUAGE': 'en_US:en', 01:37:36 INFO - 'LOGNAME': 'cltbld', 01:37:36 INFO - 'MAIL': '/var/mail/cltbld', 01:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:36 INFO - 'MOZ_NO_REMOTE': '1', 01:37:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:36 INFO - 'NO_EM_RESTART': '1', 01:37:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:36 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:36 INFO - 'SHELL': '/bin/bash', 01:37:36 INFO - 'SHLVL': '1', 01:37:36 INFO - 'TERM': 'linux', 01:37:36 INFO - 'TMOUT': '86400', 01:37:36 INFO - 'USER': 'cltbld', 01:37:36 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:36 INFO - '_': '/tools/buildbot/bin/python'} 01:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:36 INFO - Downloading/unpacking psutil>=0.7.1 01:37:36 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 01:37:36 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 01:37:36 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 01:37:36 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 01:37:36 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 01:37:36 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 01:37:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 01:37:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:38 INFO - Installing collected packages: psutil 01:37:38 INFO - Running setup.py install for psutil 01:37:38 INFO - building 'psutil._psutil_linux' extension 01:37:38 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 01:37:38 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 01:37:38 INFO - building 'psutil._psutil_posix' extension 01:37:38 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 01:37:38 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 01:37:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:38 INFO - Successfully installed psutil 01:37:38 INFO - Cleaning up... 01:37:38 INFO - Return code: 0 01:37:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 01:37: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')]} 01:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:38 INFO - Using env: {'DISPLAY': ':0', 01:37:38 INFO - 'HOME': '/home/cltbld', 01:37:38 INFO - 'LANG': 'en_US.UTF-8', 01:37:38 INFO - 'LANGUAGE': 'en_US:en', 01:37:38 INFO - 'LOGNAME': 'cltbld', 01:37:38 INFO - 'MAIL': '/var/mail/cltbld', 01:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:38 INFO - 'MOZ_NO_REMOTE': '1', 01:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:38 INFO - 'NO_EM_RESTART': '1', 01:37:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:38 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:38 INFO - 'SHELL': '/bin/bash', 01:37:38 INFO - 'SHLVL': '1', 01:37:38 INFO - 'TERM': 'linux', 01:37:38 INFO - 'TMOUT': '86400', 01:37:38 INFO - 'USER': 'cltbld', 01:37:38 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:38 INFO - '_': '/tools/buildbot/bin/python'} 01:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:37:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:37:40 INFO - Installing collected packages: mozsystemmonitor 01:37:40 INFO - Running setup.py install for mozsystemmonitor 01:37:40 INFO - Successfully installed mozsystemmonitor 01:37:40 INFO - Cleaning up... 01:37:40 INFO - Return code: 0 01:37:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 01:37: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')]} 01:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:40 INFO - Using env: {'DISPLAY': ':0', 01:37:40 INFO - 'HOME': '/home/cltbld', 01:37:40 INFO - 'LANG': 'en_US.UTF-8', 01:37:40 INFO - 'LANGUAGE': 'en_US:en', 01:37:40 INFO - 'LOGNAME': 'cltbld', 01:37:40 INFO - 'MAIL': '/var/mail/cltbld', 01:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:40 INFO - 'MOZ_NO_REMOTE': '1', 01:37:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:40 INFO - 'NO_EM_RESTART': '1', 01:37:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:40 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:40 INFO - 'SHELL': '/bin/bash', 01:37:40 INFO - 'SHLVL': '1', 01:37:40 INFO - 'TERM': 'linux', 01:37:40 INFO - 'TMOUT': '86400', 01:37:40 INFO - 'USER': 'cltbld', 01:37:40 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:40 INFO - '_': '/tools/buildbot/bin/python'} 01:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:41 INFO - Downloading/unpacking blobuploader==1.2.4 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:42 INFO - Downloading blobuploader-1.2.4.tar.gz 01:37:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:37:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:37:42 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 01:37:42 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 01:37:42 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 01:37:42 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 01:37:42 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 01:37:42 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 01:37:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 01:37:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:37:43 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 01:37:43 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 01:37:43 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 01:37:43 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 01:37:43 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 01:37:43 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 01:37:43 INFO - Downloading docopt-0.6.1.tar.gz 01:37:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 01:37:43 INFO - Installing collected packages: blobuploader, requests, docopt 01:37:43 INFO - Running setup.py install for blobuploader 01:37:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:37:43 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 01:37:43 INFO - Running setup.py install for requests 01:37:44 INFO - Running setup.py install for docopt 01:37:44 INFO - Successfully installed blobuploader requests docopt 01:37:44 INFO - Cleaning up... 01:37:44 INFO - Return code: 0 01:37:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 01:37:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 01:37:44 INFO - Using env: {'DISPLAY': ':0', 01:37:44 INFO - 'HOME': '/home/cltbld', 01:37:44 INFO - 'LANG': 'en_US.UTF-8', 01:37:44 INFO - 'LANGUAGE': 'en_US:en', 01:37:44 INFO - 'LOGNAME': 'cltbld', 01:37:44 INFO - 'MAIL': '/var/mail/cltbld', 01:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:44 INFO - 'MOZ_NO_REMOTE': '1', 01:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:44 INFO - 'NO_EM_RESTART': '1', 01:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:44 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:44 INFO - 'SHELL': '/bin/bash', 01:37:44 INFO - 'SHLVL': '1', 01:37:44 INFO - 'TERM': 'linux', 01:37:44 INFO - 'TMOUT': '86400', 01:37:44 INFO - 'USER': 'cltbld', 01:37:44 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:44 INFO - '_': '/tools/buildbot/bin/python'} 01:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:37:44 INFO - Running setup.py (path:/tmp/pip-TPRilq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:37:44 INFO - Running setup.py (path:/tmp/pip-iMrc03-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:37:44 INFO - Running setup.py (path:/tmp/pip-8a38li-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:37:44 INFO - Running setup.py (path:/tmp/pip-zDiGe2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:37:44 INFO - Running setup.py (path:/tmp/pip-SMYLwO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:37:44 INFO - Running setup.py (path:/tmp/pip-aqDNTa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:37:44 INFO - Running setup.py (path:/tmp/pip-L8t5KS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:37:44 INFO - Running setup.py (path:/tmp/pip-fzQFwJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:37:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:37:44 INFO - Running setup.py (path:/tmp/pip-27VCUq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:37:45 INFO - Running setup.py (path:/tmp/pip-nHjME8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:37:45 INFO - Running setup.py (path:/tmp/pip-7Ct2jk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:37:45 INFO - Running setup.py (path:/tmp/pip-a9AVyS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:37:45 INFO - Running setup.py (path:/tmp/pip-f8scGi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:37:45 INFO - Running setup.py (path:/tmp/pip-_ikcMg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:37:45 INFO - Running setup.py (path:/tmp/pip-ewrEeE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:37:45 INFO - Running setup.py (path:/tmp/pip-7PslNP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:37:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:37:45 INFO - Running setup.py (path:/tmp/pip-T0tsFU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:37:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:45 INFO - Running setup.py install for manifestparser 01:37:45 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 01:37:45 INFO - Running setup.py install for mozcrash 01:37:45 INFO - Running setup.py install for mozdebug 01:37:45 INFO - Running setup.py install for mozdevice 01:37:46 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozfile 01:37:46 INFO - Running setup.py install for mozhttpd 01:37:46 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozinfo 01:37:46 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozInstall 01:37:46 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozleak 01:37:46 INFO - Running setup.py install for mozlog 01:37:46 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for moznetwork 01:37:46 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozprocess 01:37:46 INFO - Running setup.py install for mozprofile 01:37:46 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 01:37:46 INFO - Running setup.py install for mozrunner 01:37:47 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 01:37:47 INFO - Running setup.py install for mozscreenshot 01:37:47 INFO - Running setup.py install for moztest 01:37:47 INFO - Running setup.py install for mozversion 01:37:47 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 01:37:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:47 INFO - Cleaning up... 01:37:47 INFO - Return code: 0 01:37:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:37:47 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')]} 01:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:47 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')]} 01:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:47 INFO - Using env: {'DISPLAY': ':0', 01:37:47 INFO - 'HOME': '/home/cltbld', 01:37:47 INFO - 'LANG': 'en_US.UTF-8', 01:37:47 INFO - 'LANGUAGE': 'en_US:en', 01:37:47 INFO - 'LOGNAME': 'cltbld', 01:37:47 INFO - 'MAIL': '/var/mail/cltbld', 01:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:47 INFO - 'MOZ_NO_REMOTE': '1', 01:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:47 INFO - 'NO_EM_RESTART': '1', 01:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:47 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:47 INFO - 'SHELL': '/bin/bash', 01:37:47 INFO - 'SHLVL': '1', 01:37:47 INFO - 'TERM': 'linux', 01:37:47 INFO - 'TMOUT': '86400', 01:37:47 INFO - 'USER': 'cltbld', 01:37:47 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:47 INFO - '_': '/tools/buildbot/bin/python'} 01:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:37:47 INFO - Running setup.py (path:/tmp/pip-R_b8p1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 01:37:47 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)) 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:37:47 INFO - Running setup.py (path:/tmp/pip-MN1r4_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 01:37:47 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)) 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:37:47 INFO - Running setup.py (path:/tmp/pip-VUHLmo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 01:37:47 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)) 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:37:47 INFO - Running setup.py (path:/tmp/pip-leuvHf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 01:37:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 01:37:47 INFO - Running setup.py (path:/tmp/pip-KrM2O_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 01:37:47 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)) 01:37:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:37:47 INFO - Running setup.py (path:/tmp/pip-GSMoct-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:37:48 INFO - Running setup.py (path:/tmp/pip-PgHL5a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:37:48 INFO - Running setup.py (path:/tmp/pip-FxTjB3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 01:37:48 INFO - Running setup.py (path:/tmp/pip-_gNqdx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 01:37:48 INFO - Running setup.py (path:/tmp/pip-GVfnNu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:37:48 INFO - Running setup.py (path:/tmp/pip-W9kgrg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:37:48 INFO - Running setup.py (path:/tmp/pip-qasYKM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:37:48 INFO - Running setup.py (path:/tmp/pip-Tip41_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 01:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:37:48 INFO - Running setup.py (path:/tmp/pip-Ca6Pem-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:37:48 INFO - Running setup.py (path:/tmp/pip-tp5fxe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 01:37:48 INFO - Running setup.py (path:/tmp/pip-YH5JFH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 01:37:48 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)) 01:37:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 01:37:48 INFO - Running setup.py (path:/tmp/pip-GRLSgt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 01:37:48 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)) 01:37:48 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)) 01:37:48 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)) 01:37:48 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)) 01:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 01:37:48 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 01:37:48 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 01:37:48 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 01:37:48 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 01:37:48 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 01:37:48 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 01:37:50 INFO - Downloading blessings-1.6.tar.gz 01:37:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 01:37:50 INFO - Installing collected packages: blessings 01:37:50 INFO - Running setup.py install for blessings 01:37:50 INFO - Successfully installed blessings 01:37:50 INFO - Cleaning up... 01:37:50 INFO - Return code: 0 01:37:50 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 01:37:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:37:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:37:51 INFO - Reading from file tmpfile_stdout 01:37:51 INFO - Current package versions: 01:37:51 INFO - argparse == 1.2.1 01:37:51 INFO - blessings == 1.6 01:37:51 INFO - blobuploader == 1.2.4 01:37:51 INFO - docopt == 0.6.1 01:37:51 INFO - manifestparser == 1.1 01:37:51 INFO - mozInstall == 1.12 01:37:51 INFO - mozcrash == 0.16 01:37:51 INFO - mozdebug == 0.1 01:37:51 INFO - mozdevice == 0.47 01:37:51 INFO - mozfile == 1.2 01:37:51 INFO - mozhttpd == 0.7 01:37:51 INFO - mozinfo == 0.9 01:37:51 INFO - mozleak == 0.1 01:37:51 INFO - mozlog == 3.1 01:37:51 INFO - moznetwork == 0.27 01:37:51 INFO - mozprocess == 0.22 01:37:51 INFO - mozprofile == 0.27 01:37:51 INFO - mozrunner == 6.11 01:37:51 INFO - mozscreenshot == 0.1 01:37:51 INFO - mozsystemmonitor == 0.0 01:37:51 INFO - moztest == 0.7 01:37:51 INFO - mozversion == 1.4 01:37:51 INFO - psutil == 3.1.1 01:37:51 INFO - requests == 1.2.3 01:37:51 INFO - wsgiref == 0.1.2 01:37:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 01:37: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')]} 01:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:51 INFO - Using env: {'DISPLAY': ':0', 01:37:51 INFO - 'HOME': '/home/cltbld', 01:37:51 INFO - 'LANG': 'en_US.UTF-8', 01:37:51 INFO - 'LANGUAGE': 'en_US:en', 01:37:51 INFO - 'LOGNAME': 'cltbld', 01:37:51 INFO - 'MAIL': '/var/mail/cltbld', 01:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:51 INFO - 'MOZ_NO_REMOTE': '1', 01:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:51 INFO - 'NO_EM_RESTART': '1', 01:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:51 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:51 INFO - 'SHELL': '/bin/bash', 01:37:51 INFO - 'SHLVL': '1', 01:37:51 INFO - 'TERM': 'linux', 01:37:51 INFO - 'TMOUT': '86400', 01:37:51 INFO - 'USER': 'cltbld', 01:37:51 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:51 INFO - '_': '/tools/buildbot/bin/python'} 01:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:51 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 1)) 01:37:51 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 2)) 01:37:51 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 3)) 01:37:51 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 4)) 01:37:51 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 5)) 01:37:51 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 6)) 01:37:51 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 7)) 01:37:51 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 8)) 01:37:51 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:37:51 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 3)) 01:37:51 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.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 01:37:51 INFO - Cleaning up... 01:37:51 INFO - Return code: 0 01:37:51 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 01:37: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')]} 01:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:51 INFO - Using env: {'DISPLAY': ':0', 01:37:51 INFO - 'HOME': '/home/cltbld', 01:37:51 INFO - 'LANG': 'en_US.UTF-8', 01:37:51 INFO - 'LANGUAGE': 'en_US:en', 01:37:51 INFO - 'LOGNAME': 'cltbld', 01:37:51 INFO - 'MAIL': '/var/mail/cltbld', 01:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:51 INFO - 'MOZ_NO_REMOTE': '1', 01:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:51 INFO - 'NO_EM_RESTART': '1', 01:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:51 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:51 INFO - 'SHELL': '/bin/bash', 01:37:51 INFO - 'SHLVL': '1', 01:37:51 INFO - 'TERM': 'linux', 01:37:51 INFO - 'TMOUT': '86400', 01:37:51 INFO - 'USER': 'cltbld', 01:37:51 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:51 INFO - '_': '/tools/buildbot/bin/python'} 01:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:51 INFO - Downloading/unpacking jsonschema 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:37:53 INFO - Downloading/unpacking functools32 (from jsonschema) 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37:53 INFO - Downloading functools32-3.2.3-2.tar.gz 01:37:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 01:37:53 INFO - warning: no files found matching '*.txt' 01:37:53 INFO - no previously-included directories found matching 'build' 01:37:53 INFO - no previously-included directories found matching 'dist' 01:37:53 INFO - no previously-included directories found matching '.git*' 01:37:53 INFO - Installing collected packages: jsonschema, functools32 01:37:53 INFO - Running setup.py install for functools32 01:37:53 INFO - warning: no files found matching '*.txt' 01:37:53 INFO - no previously-included directories found matching 'build' 01:37:53 INFO - no previously-included directories found matching 'dist' 01:37:53 INFO - no previously-included directories found matching '.git*' 01:37:53 INFO - Successfully installed jsonschema functools32 01:37:53 INFO - Cleaning up... 01:37:53 INFO - Return code: 0 01:37:53 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 01:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7f9fa7af8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1137600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, '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-1453801046.94394-1204304814', '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 01:37:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 01:37:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 01:37:53 INFO - Using env: {'DISPLAY': ':0', 01:37:53 INFO - 'HOME': '/home/cltbld', 01:37:53 INFO - 'LANG': 'en_US.UTF-8', 01:37:53 INFO - 'LANGUAGE': 'en_US:en', 01:37:53 INFO - 'LOGNAME': 'cltbld', 01:37:53 INFO - 'MAIL': '/var/mail/cltbld', 01:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:53 INFO - 'MOZ_NO_REMOTE': '1', 01:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:53 INFO - 'NO_EM_RESTART': '1', 01:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:37:53 INFO - 'PWD': '/builds/slave/test-pgo', 01:37:53 INFO - 'SHELL': '/bin/bash', 01:37:53 INFO - 'SHLVL': '1', 01:37:53 INFO - 'TERM': 'linux', 01:37:53 INFO - 'TMOUT': '86400', 01:37:53 INFO - 'USER': 'cltbld', 01:37:53 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:53 INFO - '_': '/tools/buildbot/bin/python'} 01:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:53 INFO - Downloading/unpacking flake8 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:37:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:37:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 01:37:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:37:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:37:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:37:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:37:56 INFO - no previously-included directories found matching 'docs/_build' 01:37:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:37:56 INFO - Running setup.py install for pep8 01:37:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:37:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:37:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:37:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:37:56 INFO - no previously-included directories found matching 'docs/_build' 01:37:56 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 01:37:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:37:56 INFO - Cleaning up... 01:37:56 INFO - Return code: 0 01:37:56 INFO - Running post-action listener: _resource_record_post_action 01:37:56 INFO - Running post-action listener: _start_resource_monitoring 01:37:56 INFO - Starting resource monitoring. 01:37:56 INFO - ##### 01:37:56 INFO - ##### Running install step. 01:37:56 INFO - ##### 01:37:56 INFO - Running pre-action listener: _resource_record_pre_action 01:37:56 INFO - Running main action method: install 01:37:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 01:37:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 01:37:56 INFO - Reading from file tmpfile_stdout 01:37:56 INFO - Detecting whether we're running mozinstall >=1.0... 01:37:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 01:37:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 01:37:57 INFO - Reading from file tmpfile_stdout 01:37:57 INFO - Output received: 01:37:57 INFO - Usage: mozinstall [options] installer 01:37:57 INFO - Options: 01:37:57 INFO - -h, --help show this help message and exit 01:37:57 INFO - -d DEST, --destination=DEST 01:37:57 INFO - Directory to install application into. [default: 01:37:57 INFO - "/builds/slave/test-pgo"] 01:37:57 INFO - --app=APP Application being installed. [default: firefox] 01:37:57 INFO - mkdir: /builds/slave/test-pgo/build/application 01:37:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 01:37:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 01:38:11 INFO - Reading from file tmpfile_stdout 01:38:11 INFO - Output received: 01:38:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 01:38:11 INFO - Running post-action listener: _resource_record_post_action 01:38:11 INFO - ##### 01:38:11 INFO - ##### Running run-tests step. 01:38:11 INFO - ##### 01:38:11 INFO - Running pre-action listener: _resource_record_pre_action 01:38:11 INFO - Running main action method: run_tests 01:38:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 01:38:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 01:38:11 INFO - Python 2.7.3 01:38:11 INFO - Return code: 0 01:38:11 INFO - grabbing minidump binary from tooltool 01:38:11 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')]} 01:38:11 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 0x11c6350>, '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 0x11c7250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c76e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 01:38:11 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 01:38:11 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 01:38:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:38:11 INFO - Return code: 0 01:38:11 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 01:38:11 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 01:38:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 01:38:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 01:38:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 01:38:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 01:38:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 01:38:12 INFO - Return code: 0 01:38:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 01:38:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost 01:38:12 INFO - Using env: {'DISPLAY': ':0', 01:38:12 INFO - 'HOME': '/home/cltbld', 01:38:12 INFO - 'LANG': 'en_US.UTF-8', 01:38:12 INFO - 'LANGUAGE': 'en_US:en', 01:38:12 INFO - 'LOGNAME': 'cltbld', 01:38:12 INFO - 'MAIL': '/var/mail/cltbld', 01:38:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:38:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 01:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:12 INFO - 'MOZ_NO_REMOTE': '1', 01:38:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 01:38:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:12 INFO - 'NO_EM_RESTART': '1', 01:38:12 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 01:38:12 INFO - 'PWD': '/builds/slave/test-pgo', 01:38:12 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 01:38:12 INFO - 'SHELL': '/bin/bash', 01:38:12 INFO - 'SHLVL': '1', 01:38:12 INFO - 'TERM': 'linux', 01:38:12 INFO - 'TMOUT': '86400', 01:38:12 INFO - 'USER': 'cltbld', 01:38:12 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453801046.94394-1204304814', 01:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:12 INFO - '_': '/tools/buildbot/bin/python'} 01:38:12 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost'] with output_timeout 3600 01:38:12 INFO - mozversion INFO | application_buildid: 20160125070233 01:38:12 INFO - mozversion INFO | application_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:38:12 INFO - mozversion INFO | application_display_name: Nightly 01:38:12 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:38:12 INFO - mozversion INFO | application_name: Firefox 01:38:12 INFO - mozversion INFO | application_remotingname: firefox 01:38:12 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:38:12 INFO - mozversion INFO | application_vendor: Mozilla 01:38:12 INFO - mozversion INFO | application_version: 45.0 01:38:12 INFO - mozversion INFO | platform_buildid: 20160125070233 01:38:12 INFO - mozversion INFO | platform_changeset: 3bc504ea394fe54d1dbb3afc86534b25c36432bd 01:38:12 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:38:12 INFO - mozversion INFO | platform_version: 45.0 01:38:12 INFO - 2016-01-26 01:38:12,896 DEBUG : using testdate: 1453801092 01:38:12 INFO - 2016-01-26 01:38:12,896 DEBUG : actual date: 1453801092 01:38:12 INFO - 2016-01-26 01:38:12,903 INFO : starting webserver on 'localhost:44650' 01:38:12 INFO - 2016-01-26 01:38:12,904 INFO : Starting test suite talos-linux64-ix-034 01:38:12 INFO - 2016-01-26 01:38:12,904 INFO : Starting test dromaeo_css 01:38:12 INFO - 2016-01-26 01:38:12,904 DEBUG : operating with platform_type : linux_ 01:38:12 INFO - 2016-01-26 01:38:12,905 INFO : Initialising browser for dromaeo_css test... 01:38:12 INFO - 2016-01-26 01:38:12,920 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYLv9K7/profile http://localhost:44650/getInfo.html 01:38:16 INFO - 2016-01-26 01:38:16,841 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:38:16 INFO - 2016-01-26 01:38:16,842 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:38:16 INFO - 2016-01-26 01:38:16,842 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:38:16 INFO - 2016-01-26 01:38:16,842 DEBUG : BROWSER_OUTPUT: __metrics 01:38:17 INFO - 2016-01-26 01:38:17,202 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:38:18 INFO - 2016-01-26 01:38:18,039 INFO : Browser initialized. 01:38:18 INFO - 2016-01-26 01:38:18,039 INFO : Running cycle 1/1 for dromaeo_css test... 01:38:18 INFO - 2016-01-26 01:38:18,040 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYLv9K7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:38:29 INFO - 2016-01-26 01:38:29,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 134414336 01:38:29 INFO - 2016-01-26 01:38:29,807 DEBUG : BROWSER_OUTPUT: 01:39:20 INFO - 2016-01-26 01:39:20,699 DEBUG : BROWSER_OUTPUT: 1453801160695 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] 01:41:29 INFO - 2016-01-26 01:41:29,187 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44650/tests/dromaeo/cssquery-ext.html) 01:41:30 INFO - 2016-01-26 01:41:30,212 DEBUG : BROWSER_OUTPUT: RSS: Main: 241639424 01:41:30 INFO - 2016-01-26 01:41:30,213 DEBUG : BROWSER_OUTPUT: 01:44:32 INFO - 2016-01-26 01:44:32,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-ext.html (next: http://localhost:44650/tests/dromaeo/cssquery-jquery.html) 01:44:32 INFO - 2016-01-26 01:44:32,828 DEBUG : BROWSER_OUTPUT: RSS: Main: 217554944 01:44:32 INFO - 2016-01-26 01:44:32,828 DEBUG : BROWSER_OUTPUT: 01:47:34 INFO - 2016-01-26 01:47:34,602 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44650/tests/dromaeo/cssquery-mootools.html) 01:47:35 INFO - 2016-01-26 01:47:35,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 211451904 01:47:35 INFO - 2016-01-26 01:47:35,168 DEBUG : BROWSER_OUTPUT: 01:50:36 INFO - 2016-01-26 01:50:36,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44650/tests/dromaeo/cssquery-prototype.html) 01:50:37 INFO - 2016-01-26 01:50:37,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 199471104 01:50:37 INFO - 2016-01-26 01:50:37,471 DEBUG : BROWSER_OUTPUT: 01:53:39 INFO - 2016-01-26 01:53:39,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44650/tests/dromaeo/cssquery-yui.html) 01:53:40 INFO - 2016-01-26 01:53:40,406 DEBUG : BROWSER_OUTPUT: RSS: Main: 199241728 01:53:40 INFO - 2016-01-26 01:53:40,406 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,542 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/dromaeo/cssquery-yui.html (next: http://localhost:44650/tests/dromaeo/cssquery-dojo.html) 01:56:42 INFO - 2016-01-26 01:56:42,792 DEBUG : BROWSER_OUTPUT: RSS: Main: 242204672 01:56:42 INFO - 2016-01-26 01:56:42,792 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,793 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:56:42 INFO - 2016-01-26 01:56:42,793 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:56:42 INFO - 2016-01-26 01:56:42,793 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:56:42 INFO - 2016-01-26 01:56:42,793 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:56:42 INFO - 2016-01-26 01:56:42,794 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3287.6712328767126;3407.148212946763;3434.004138799748;3437.3469040882233;3451.2062225688082;5613.242212301338;5666.660000399979;5678.318601767794;5681.233033540481;5692.459216374448;5667.744951477183;5725.770969161239;5726.513246374053;5727.484526392624;5750.137479378095;5046.82336118236;5111.872203194931;5161.200013997827;5192.71440070796;5247.134222853225;5047.116754567955;5176.12005958986;5185.092608793465;5239.030779305841;5255.737213139347;5040.97479512602;5103.872403189913;5109.821156259532;5120.871978200556;5195.818146364878;5206.349206349207;5257.579393648537;5260.473952604747;5267.841964741083;5298.125809241471;4979.352684151074;5036.949630503685;5058.544730974193;5148.330717006766;5152.896942061176;5494.395616482184;5518.392976772552;5527.419620939824;5533.280673512458;5541.5289700375515;6137.9079313810325;6161.691915404212;6178.351273116348;6180.969095154495;6186.659733714644;5966.552508561832;6025.517958563304;6114.327423983358;6119.502553941423;6210.720517576675;5716.428357164292;5721.256236689203;5721.370649228582;5724.112762521816;5800.941990580082;6182.103594978703;6184.938150618526;6195.876082478325;6195.907061394083;6198.566100373021;5645.1532270159805;5819.127130930395;5999.880002400015;6095.329513753483;6122.846928826726;6111.266648002268;6117.694115294217;6137.877242455215;6140.293866205444;6146.877062458726;5823.097419899922;6018.789342372996;6068.059450785136;6069.726862291119;6072.726727297282;5994.430529099728;6038.788642397495;6163.599366041193;6166.845828854225;6172.105044768484;4668.906621867543;4670.159371313128;4670.883227919261;4689;4702.341672165831;4081;4164.083901541656;4183.372494125844;4191.958080419217;4224.683148763794;5676.148577713294;5777.277840269966;5797.88404231913;5804.767809287666;5828.358880523139;6136.509079273647;6150.630962142196;6155.815325540241;6156.815295541141;6157.7229024694;5578.107502799533;5612.691301978349;5753.626014309057;5754.223179870748;5808.273965754281;5357.919631205535;5374.838754837361;5376.677399356051;5382.4886635769535;5392.892142157135;6341.302456729756;6365.649889256136;6369.585976911579;6371.9681401593625;6376.171097757262;5784.913226301524;5937.34689184198;6068.605540639845;6167.352427994996;6183.84540386476;6270.435660790551;6308.810735677937;6313.936860631335;6322.557420980487;6323.114763932961;6065.878682426415;6067.666278354733;6068.666223357758;6068.726907289094;6079.908801367895;6015.187949626919;6092.725827337869;6130.448259656652;6257.4681152102585;6263.78076767311;5997.880042399215;6006.392967735456;6006.729697163551;6014.789482368096;6049.274087109575;5727.570432217517;5982.0727787193055;5982.4017598239825;5986.07215881539;5992.580519363777;5710.8001219957105;5816.82549523515;5820.126980952721;5820.650760954356;5848.883022339444;5906.2026626406605;6016.458518733335;6047.123167140828;6096.603720758137;6103.633781972918;5630.183623374506;5703.486686198262;5915.112733090071;5931;5955.612885162451 01:56:42 INFO - 2016-01-26 01:56:42,796 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.4331355271834;620.1411045701703;629.2889035390009;629.389492192573;632.1402892066791;33839;42800.785996070015;42862.99999999996;42982;43035.35446968298;46933.29600055993;47372;47413;47455.76272118643;47543;7790.9610451947665;7870.803229919241;9100.226480749145;9169.449833010041;9259.537023148816;10902.236843420964;11146.331220126778;11164.776704465905;11187.32876027437;11191;10861.891381086167;10901.400422976732;10910;10994.835077473845;10995.615153469631;10799.082078023339;10808.675739727822;10830.72923176919;10851.565937362497;10871.130309575254;10760.78478430435;10878.184136189817;10900.018998290112;10900.454977251105;10903.34579925207;42428;42712.35931461031;42745.1450970982;42834.78582607067;42905;3681.6134305897763;4041.2523683118716;4100.038156023451;4292.313229883203;4314.158739045887;32287.51568726471;32518.83740581282;32715.18212044694;32720.67279327224;32805.671943280504;3974.602539746031;4023.5372932112596;4090.468239128924;4119.608637179464;4290.3135498320435;304615;306153;306318;307943.9999999978;308913;59731.10403343954;60805;66652.33347666558;67155.32844671588;68774.65612672004;61416.07875881865;61591.38408615856;61892.381076189566;61897;62002;17514.29942802299;17799;17804.643907121786;17805.198766055557;17805.554861128316;13728.51950181759;13791.241481718602;13821.447142114404;13848.515301964382;14085.929570352288;21062.684059739116;21441.89279053595;21449.14203431845;21482.570348593254;21555.56888862214;7683.615819209017;7909.209079092045;7940.523568585788;7953.5227886327;8034.67861285542;937.3625934364698;982.0326977926736;994.9403035817874;999.1706883286854;1102.239454776202;30087;30460.390792184033;30713.38573228523;30938.69061309403;30952.535711964338;1119.5130118398522;1147.8507793986896;1156.1444531046964;1201.747632997063;1262.4697626996683;14074.2962851857;14237.64405889861;14706.264686765619;16431.780843609617;17073.231704573325;348600;352726;352975;353128;353177;56057.71971140118;56273.718631406584;56305.71847140738;56344;56400.15399769007;1436.3895344478556;1446.8625480579328;1467.1123969997998;1479.186447453896;1484.6288427893028;30711.232219193804;30906.227344316572;30953.690463095983;31193;31612.683873161895;49598.75200623974;49763;50109.74945125251;50459;50462.49537504578;339923;340401;341868;342825;342843;30429.239269018453;30772.230694232825;31869.20326991844;32769.34461310808;34166.487502686985;43441.56558434375;43748;43958.340624890014;44121.338179926686;44273.778631107925;36847.815760921025;37102.44346334753;37744.811275943444;38429.231415372094;39711.40432893451;36894.44658330181;38078.238435231695;38469;38764.6123538761;38906.6109338903;38136.61863381442;38458.80770596129;38624.613753863225;38840.41739373855;39123.608763911994;32846.68613255443;33297.50053749147;33715.15712107119;33976.66023339735;34261;25851.741482585687;26258.21225363242;26319.342016448976;26561.867190663925;26812.731872681023 01:56:42 INFO - 2016-01-26 01:56:42,799 DEBUG : BROWSER_OUTPUT: |2;jquery.html;392.72313019321376;393.0841140143466;393.4452422084861;394.08965290179685;396.8055652730163;14851.331690073946;16623.584410389714;16866.24101915414;18817;18818;11841.703957401069;12071.879281207186;12080.516779328838;17891.552711182227;18304.176312065956;5024.371953505812;5183.766730497136;5184.11869982104;5187.922181167285;5194.376674799029;4006.83972641094;4103.589641035887;5160.741962901859;5546.057170281062;5693.345265294482;5618.044932361488;5623.718814059301;5628.915566266509;5641.210230567724;5667.8583035424035;5466.34403871536;6196.349383314755;6197.628142311476;6205.038219076006;6209.6895155242055;5400.756965936543;5489.929463754569;5495.395506494282;5515.944840551583;5529.004779139753;14005.529419410917;14074.22591757453;18070.006149661764;22351;22578.209762658393;2251.853629514076;2308.7806658367426;2341.953160936772;2342.051469154995;2351.3651314145254;9782.608695652165;9979.30144889858;10034;10063.798724025552;10133.898661013369;1791.758112654801;1894.8484121270264;1906.494778883597;1944.4069558784565;1947.7175809507685;58947.70526147385;66580.3341966579;67215;69541;70061.29938700599;10780.568777248822;11075.16936229786;11176.720581985353;11177.944110279397;11289.20975531705;14945.40218391274;15046.097234165985;15078.396864125532;15084.094954302776;15084.24578771057;8661.181151958033;8712.259457946146;8726.956365218133;8776.692815751418;8783.472991620523;8624.051354351142;8654.610542525601;8695.217430431292;8699;8854.86717699235;4748.928766068511;4790.688605240648;4862.5380588844;4924.8768780780765;4940.407151141886;1752.9850178720844;1797.5912277548148;1855.9072046397896;1878.3238034307637;1926.9710954335696;941.3363578677016;1036.3056751976044;1047.9633212837666;1086.8152414089623;1095.2552264460244;16220.270087846075;16508.6698266034;16866.83133168677;17049.573760656083;17175;871.8038441350645;941.4680705401427;984.178211193647;994.6916455898695;997.9251556133319;9666.275029372826;9842.704718858446;9955.950220248853;9995.868241712757;10346.482675866177;496932;496950;499340;509094;755933;87022;92076.53961730149;92583;93480.53259733658;94992;509.9796008159706;521.4889408379809;521.6426747677853;523.2177894048422;524.1534921102392;10741.677749667522;10818.404987725751;15357;15472.922635387202;16287.67424651524;37775.433368500046;38429.6157038426;38827.6117238824;38859;39117.608823911396;49944.50055499544;50458.2431263524;51389.7430512845;52740;52960.47039529555;9671.274654401088;9814.263930205121;10049.346792458467;10069.748256293651;10078.344907580984;15960.521184364488;17317;17686.20412081434;18282.360117395827;18390.632187356445;15583.22083895778;15592.298346574658;15684.529464116094;15919.602009949844;16071.437499687454;14867.18230497333;15259.389624414898;15378.538643840702;15486.767698484757;15794;12667.746645067231;15453.072815631098;15524.379024839332;16150.353985840904;16353.100579468244;10677.839832402675;10784;10787.406692631981;10792.62225822124;10818.188635852186;15560.533184004045;15738.842611573738;15877.841221587636;15972.760408594113;16349.673006539564 01:56:42 INFO - 2016-01-26 01:56:42,800 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1514.3034204266037;1517.4916403004993;1683.7053515634761;1803.8917664940104;1824.5712257619457;7328.853422931552;7482.513636613617;7669.50148240364;7874.842503149941;7896.881546776795;7709.575973321465;7916.0500739911295;7986.2013798620255;7997.200279972;8001.319887809544;2292.3008482412856;2329.010170677464;2330.8718020508863;2334.206369834256;2336.1122773346187;1984.8015198480182;2021.4339984804278;2311.826613004023;2326.5463234669246;2390.1634427950253;2239.854409463388;2249.6063188941953;2254.9098036078535;2260.728712554496;2260.7739226077424;2329.4409341758023;2354.1525050981804;2358.8584684918874;2358.870262135582;2359.1978727232736;2329.464223228667;2378.143868207444;2381.2380038387723;2382.1424287256573;2384.749601291874;8142.43002989791;8186.2632363087605;8194.836103277961;8202.548859812709;8222.342212622976;4836.347093142416;4884.438289596671;4885.389326334208;4936.901261974776;4942.777575009133;2987.865546050433;3019.7886147969652;3034.5903303054024;3041.285297954979;3100.751939844807;997.3517213811008;1006.6677996261217;1007.1640538353149;1024.4212020208558;1057.9365238085738;11276.097912167006;11278.661640150809;11293.096552275798;11299.717507062307;11309.49107290174;4242.830286788556;4245.448091748053;4263.509696384894;4270.658347332206;4340.348947657877;9505.192058674951;9531.47576883278;9534.761630959143;9558.28312876537;9577.425354478613;4843.95156048435;4844.346013288232;4868.610511159099;4873.561379475864;4883.755812209375;4400.361947517592;4409.713368631029;4420.84527041552;4450.421445212167;4457.420535330386;3803.9919421353366;3914.3149948758855;3939.1727737174942;3945;3946.1515774108702;1140.7034171115404;1200.1418985425462;1224.3388570172185;1279.5137847617846;1290.8709129087017;1107.8670559532907;1172.1326218598176;1173.7887180307455;1353.8849197818295;1431.8568143185598;7773.261540153676;7803.765887023398;7890.7632771015815;7892.60536973149;7896.486728362638;1144.5135817277624;1160.8548931383577;1168.2172944127456;1173.167051393503;1174.9236299640495;6259.96870015647;6324.083007963912;6340.714667839866;6342.334054924258;6344.302126766052;9515.476648784383;9623.182029527568;9724.124828765445;9734;9748.171405430476;9441.480718560408;9532.237421006303;9649.662261820942;9651.71044868655;9656.613735450645;7700.768976930701;7761.185075567097;7819.765407037798;7836.138024817265;7837.020372453558;7448.9627551861895;7487.438442116754;7509.286617771303;7527.096748390229;7590.886136708064;8280.420370574002;8514.574271286534;8604.82790344177;8624.051354350891;8628.913710862811;8505.192006759347;8751.868721969047;8791.648334066565;8803.559822008745;8809.691660791841;7094.9290507094265;7194.136703595602;7195.13658361;7197.568145911262;7202.603856787927;6543.312952139957;7201.675924583511;7800.258975397328;7843.7254696085365;7866.449348545547;6612.70242839083;7015.894761578683;7401.259874012771;7441.032665753418;7519.962400187964;7168.820779480555;7267.127944646676;7294.307040831112;7299.087614048244;7404.814879628052;7397.852042959218;7413.036305280279;7421.2207718190975;7429.962850185714;7430.665620047219;6922.930770692228;7005.2644472329675;7045.26024767392;7050.788476345718;7059.611721355374;6881.621510816753;6912.758053468105;6922.169339679271;6926.099607051051;6929.411000065051 01:56:42 INFO - 2016-01-26 01:56:42,800 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.58574260985955;357.7603005985989;362.16340254013215;363.08321488242194;365.7348422393763;6035.185249991261;6121.173641558388;6150.784722534712;6179.814605561829;6214.4096310850455;5921.667624784422;6096.542759293048;6108.450239478446;6116.908246376297;6127.448529632344;4790.568848803607;4792.784324705397;4796.472388037313;4798.904021919559;4807.951920480786;4780.760961951906;4789.784459699322;4807.759612019402;4809.879753006167;4812.422509298889;4555.316702494619;4655.557722016403;4679.9298010529865;4704.764761761899;4762.880927976811;4666.533346665306;4703.600193983515;4707.340972263886;4707.599854012413;4708.505606911276;3930.76415415076;3992.800359982018;4124.876253712393;4126.896827579305;4133.0700592366775;5750.568707346965;5807.332156801979;5843.883122337572;5859.1797148399255;5870.765169393219;5459.481349271813;5463.31708536433;5465.508104270595;5466.070767969455;5470.070088085035;6410.8076757697345;6497.285298617148;6498.0252962056065;6504.3170467100745;6507.837304067389;4526.388937493429;4533.433320834895;4539.7049191802425;4548.5906268436;4552.56750608695;6668.399844014062;6751.42612877906;6751.966240168767;6753.45972322213;6755.729770809162;4664.55686709762;4667.556582124693;4669.34629151919;4670.509596492388;4676.18166820805;4619.422572178478;4731.266653668687;4739.312799644102;4742.454617719007;4743.786529606107;5415.593830462731;5417.431169727122;5423.837284881381;5423.972880135653;5426.701531415731;6534.215894092739;6544.051112588648;6553.803385898431;6554.80335589933;6562.77030303937;1641.8358164183726;1643.0306119389618;1650.4553497345846;1659.6348803263504;1660.10354408618;1106.3085571517802;1107.9113670906308;1112.4159816096615;1113.4989254835357;1134.8467956825723;6330.556861019684;6350.936490635127;6355;6361.650109244036;6363.522735794834;6661.2006559213205;6707.128073350434;6709.228438729511;6712.664366781642;6720.697568609425;6183.258009038854;6341.841453963688;6342.5560210785725;6345.238571371373;6347.746090156435;3613.0425437259146;3653.1962968146963;3655.7258205634685;3658.0306218852024;3659.798711070917;6679.899801502981;6790.083338749305;6876.209235937831;6906.412954898789;6977.2673869243;6929.480288978347;6943.722251109899;6946.0622815920215;6974.37230649244;6985.301469853076;6801.863962720718;7003.194632617314;7004.2995700430565;7016.403605693573;7018.789436316919;6722.563033402813;6738.6967586459705;6745.52781305304;6800.149981252343;6800.217974932946;6722.563033402813;6724.899126513008;6726.865462690621;6726.966365168143;6734.966325168343;6850.554713943578;7025.68384422692;7033.331833475811;7044.577325360495;7049.9647501762165;4772.689775164603;4808.567228949271;4817.205161148371;4819.397575303087;4819.7108173509705;6706.429953454011;6711.597304161766;6716.664166791738;6720.059191713066;6724.49566282521;6410.8076757697345;6521.315261897433;6523.706433210422;6526.282108968105;6530.9020364693615;6490.221173459215;6500.934990650033;6505.121808555785;6507.349265073455;6511.413972742476;6407.327230640715;6416.197975253093;6421.646809425526;6423.036544518172;6425;6439.710213040331;6473.287938326686;6516.478681705357;6525.3800888915475;6652.234992975869;6462.515311351762;6489.837754056186;6493.415592596689;6493.577917435352;6501.674916254263 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: |5;yui.html;885.2917665867305;891.678995561598;932.053965225296;933.0855761353873;936.3352020065753;12455.065870059747;12797.552085677014;12856.228626282405;12887.033472489555;12898.74202515949;13691.17852928825;13776.173429594231;13799.379027943742;13802.516911908086;13804.723905521909;767.3784234769827;779.8167430653792;780.0132831967566;780.8555417247799;916.2624087609472;899.6806133822497;907.9591418386156;921.0237148622448;924.8474001789697;925.8842644669417;932.6129656192672;954.4702690007043;956.9377990430635;957.5499515227832;958.3148049144895;806.2582424169777;811.7645882694012;950.083169741203;962.7593101724569;964.3008818606525;706.7773651299824;707.2891743797502;716.4948711158621;717.4117223876424;718.5401343140394;13535.255560944144;13687.24720140392;13688.315584220749;13693.863061369359;13696.383662735201;11614.593489227836;12217.205881617756;12276.877231227663;12329.69175770604;12414.81377779334;11033;11070.059044981186;11072.501737421755;11089.55641774328;11155.944220278847;3585.175409655794;3612.9277414451826;3620.1854582718943;3641.6540428659237;3695.926081478355;47520.28719569209;47865.52134478645;47953.76023119862;48413;48464.7576762114;10289.176865850714;10311.381317120997;10322.277440579237;10399.428031458343;10521.789564208826;19612.509687257934;19842.801571984382;19851.900740496494;19854.602907942048;19856.10647520865;8082.757517274491;8136.471129376571;8247.298979586802;8299.792505187419;8330.375576762593;4438.689291749611;4463.843765468193;4509.1658043262105;4563.771811409416;4568.1548913451115;3135.5766971459016;3150.464421048427;3151.9212019699235;3164.841757912095;3169.1918560767317;532.3638252288478;537.4464301769184;547.8000529806592;551.8537587539305;554.5563549160655;540.4568408749182;659.973601055962;708.3553965891015;713.454207531239;729.7008226627057;8833.469991800512;8856.601452934505;8886.24466920306;9008.234300084436;9038.231750301298;534.7994502061727;586.5923183387505;662.486572905992;702.2100137345486;705.6965504832971;4111.48606424197;4120.814563344657;4140.482439695038;4315.676324275629;4635.490096089427;56652;64135.679321604024;64513;65037.349626504074;65773;51783;52512.212316815276;54572;54689.453105468434;54809.17786233209;48569.5143048565;48683.75658121687;48950.510494894595;49086.50913490819;50510.242346364095;9274.907250927405;9474.526273686426;9562.760930976781;9721.4653194075;9788.314817962959;36481.27037459289;37000.62999369972;37518.24963500769;37625;37829.81085094557;42657.36013959731;42998;48178.27732583944;48483;48597.5140248593;11203.271787334123;11374.146938979775;11438.370889601152;11492.195546311677;11811.468483918072;11388.487518061873;11818.231814932002;12171.75656486883;12191.634250972485;12307.26156430617;10464.215183861394;10690.144788417063;10715.732106697395;10794.244402891722;11322.433878306216;11381.146414019147;11688.240264382788;11710.355930423908;11896.940515297714;11968;9016.864747028669;9847.950760246153;9945.453000085065;10382;11548.94225528867;10392.740181495523;10446.05985461312;10570.682879513626;10695.358278503003;11018.559257629606;9241.907580924104;9547.379420337656;9599.760005999906;9617.182539483942;9886.703398898044 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,787 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: __startTimestamp1453802202791__endTimestamp 01:56:42 INFO - 2016-01-26 01:56:42,802 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5662.28 Median:5820.13 stddev:636.49 (10.9%) stddev-sans-first:610.57 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: Values: 3287.7 3407.1 3434.0 3437.3 3451.2 5613.2 5666.7 5678.3 5681.2 5692.5 5667.7 5725.8 5726.5 5727.5 5750.1 5046.8 5111.9 5161.2 5192.7 5247.1 5047.1 5176.1 5185.1 5239.0 5255.7 5041.0 5103.9 5109.8 5120.9 5195.8 5206.3 5257.6 5260.5 5267.8 5298.1 4979.4 5036.9 5058.5 5148.3 5152.9 5494.4 5518.4 5527.4 5533.3 5541.5 6137.9 6161.7 6178.4 6181.0 6186.7 5966.6 6025.5 6114.3 6119.5 6210.7 5716.4 5721.3 5721.4 5724.1 5800.9 6182.1 6184.9 6195.9 6195.9 6198.6 5645.2 5819.1 5999.9 6095.3 6122.8 6111.3 6117.7 6137.9 6140.3 6146.9 5823.1 6018.8 6068.1 6069.7 6072.7 5994.4 6038.8 6163.6 6166.8 6172.1 4668.9 4670.2 4670.9 4689.0 4702.3 4081.0 4164.1 4183.4 4192.0 4224.7 5676.1 5777.3 5797.9 5804.8 5828.4 6136.5 6150.6 6155.8 6156.8 6157.7 5578.1 5612.7 5753.6 5754.2 5808.3 5357.9 5374.8 5376.7 5382.5 5392.9 6341.3 6365.6 6369.6 6372.0 6376.2 5784.9 5937.3 6068.6 6167.4 6183.8 6270.4 6308.8 6313.9 6322.6 6323.1 6065.9 6067.7 6068.7 6068.7 6079.9 6015.2 6092.7 6130.4 6257.5 6263.8 5997.9 6006.4 6006.7 6014.8 6049.3 5727.6 5982.1 5982.4 5986.1 5992.6 5710.8 5816.8 5820.1 5820.7 5848.9 5906.2 6016.5 6047.1 6096.6 6103.6 5630.2 5703.5 5915.1 5931.0 5955.6 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,803 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51401.94 Median:342834.00 stddev:87343.92 (25.5%) stddev-sans-first:87505.58 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: Values: 578.4 620.1 629.3 629.4 632.1 33839.0 42800.8 42863.0 42982.0 43035.4 46933.3 47372.0 47413.0 47455.8 47543.0 7791.0 7870.8 9100.2 9169.4 9259.5 10902.2 11146.3 11164.8 11187.3 11191.0 10861.9 10901.4 10910.0 10994.8 10995.6 10799.1 10808.7 10830.7 10851.6 10871.1 10760.8 10878.2 10900.0 10900.5 10903.3 42428.0 42712.4 42745.1 42834.8 42905.0 3681.6 4041.3 4100.0 4292.3 4314.2 32287.5 32518.8 32715.2 32720.7 32805.7 3974.6 4023.5 4090.5 4119.6 4290.3 304615.0 306153.0 306318.0 307944.0 308913.0 59731.1 60805.0 66652.3 67155.3 68774.7 61416.1 61591.4 61892.4 61897.0 62002.0 17514.3 17799.0 17804.6 17805.2 17805.6 13728.5 13791.2 13821.4 13848.5 14085.9 21062.7 21441.9 21449.1 21482.6 21555.6 7683.6 7909.2 7940.5 7953.5 8034.7 937.4 982.0 994.9 999.2 1102.2 30087.0 30460.4 30713.4 30938.7 30952.5 1119.5 1147.9 1156.1 1201.7 1262.5 14074.3 14237.6 14706.3 16431.8 17073.2 348600.0 352726.0 352975.0 353128.0 353177.0 56057.7 56273.7 56305.7 56344.0 56400.2 1436.4 1446.9 1467.1 1479.2 1484.6 30711.2 30906.2 30953.7 31193.0 31612.7 49598.8 49763.0 50109.7 50459.0 50462.5 339923.0 340401.0 341868.0 342825.0 342843.0 30429.2 30772.2 31869.2 32769.3 34166.5 43441.6 43748.0 43958.3 44121.3 44273.8 36847.8 37102.4 37744.8 38429.2 39711.4 36894.4 38078.2 38469.0 38764.6 38906.6 38136.6 38458.8 38624.6 38840.4 39123.6 32846.7 33297.5 33715.2 33976.7 34261.0 25851.7 26258.2 26319.3 26561.9 26812.7 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30158.25 Median:20063.40 stddev:91968.36 (458.4%) stddev-sans-first:92199.20 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: Values: 392.7 393.1 393.4 394.1 396.8 14851.3 16623.6 16866.2 18817.0 18818.0 11841.7 12071.9 12080.5 17891.6 18304.2 5024.4 5183.8 5184.1 5187.9 5194.4 4006.8 4103.6 5160.7 5546.1 5693.3 5618.0 5623.7 5628.9 5641.2 5667.9 5466.3 6196.3 6197.6 6205.0 6209.7 5400.8 5489.9 5495.4 5515.9 5529.0 14005.5 14074.2 18070.0 22351.0 22578.2 2251.9 2308.8 2342.0 2342.1 2351.4 9782.6 9979.3 10034.0 10063.8 10133.9 1791.8 1894.8 1906.5 1944.4 1947.7 58947.7 66580.3 67215.0 69541.0 70061.3 10780.6 11075.2 11176.7 11177.9 11289.2 14945.4 15046.1 15078.4 15084.1 15084.2 8661.2 8712.3 8727.0 8776.7 8783.5 8624.1 8654.6 8695.2 8699.0 8854.9 4748.9 4790.7 4862.5 4924.9 4940.4 1753.0 1797.6 1855.9 1878.3 1927.0 941.3 1036.3 1048.0 1086.8 1095.3 16220.3 16508.7 16866.8 17049.6 17175.0 871.8 941.5 984.2 994.7 997.9 9666.3 9842.7 9956.0 9995.9 10346.5 496932.0 496950.0 499340.0 509094.0 755933.0 87022.0 92076.5 92583.0 93480.5 94992.0 510.0 521.5 521.6 523.2 524.2 10741.7 10818.4 15357.0 15472.9 16287.7 37775.4 38429.6 38827.6 38859.0 39117.6 49944.5 50458.2 51389.7 52740.0 52960.5 9671.3 9814.3 10049.3 10069.7 10078.3 15960.5 17317.0 17686.2 18282.4 18390.6 15583.2 15592.3 15684.5 15919.6 16071.4 14867.2 15259.4 15378.5 15486.8 15794.0 12667.7 15453.1 15524.4 16150.4 16353.1 10677.8 10784.0 10787.4 10792.6 10818.2 15560.5 15738.8 15877.8 15972.8 16349.7 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,805 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5674.86 Median:6747.16 stddev:2942.95 (43.6%) stddev-sans-first:2934.59 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: Values: 1514.3 1517.5 1683.7 1803.9 1824.6 7328.9 7482.5 7669.5 7874.8 7896.9 7709.6 7916.1 7986.2 7997.2 8001.3 2292.3 2329.0 2330.9 2334.2 2336.1 1984.8 2021.4 2311.8 2326.5 2390.2 2239.9 2249.6 2254.9 2260.7 2260.8 2329.4 2354.2 2358.9 2358.9 2359.2 2329.5 2378.1 2381.2 2382.1 2384.7 8142.4 8186.3 8194.8 8202.5 8222.3 4836.3 4884.4 4885.4 4936.9 4942.8 2987.9 3019.8 3034.6 3041.3 3100.8 997.4 1006.7 1007.2 1024.4 1057.9 11276.1 11278.7 11293.1 11299.7 11309.5 4242.8 4245.4 4263.5 4270.7 4340.3 9505.2 9531.5 9534.8 9558.3 9577.4 4844.0 4844.3 4868.6 4873.6 4883.8 4400.4 4409.7 4420.8 4450.4 4457.4 3804.0 3914.3 3939.2 3945.0 3946.2 1140.7 1200.1 1224.3 1279.5 1290.9 1107.9 1172.1 1173.8 1353.9 1431.9 7773.3 7803.8 7890.8 7892.6 7896.5 1144.5 1160.9 1168.2 1173.2 1174.9 6260.0 6324.1 6340.7 6342.3 6344.3 9515.5 9623.2 9724.1 9734.0 9748.2 9441.5 9532.2 9649.7 9651.7 9656.6 7700.8 7761.2 7819.8 7836.1 7837.0 7449.0 7487.4 7509.3 7527.1 7590.9 8280.4 8514.6 8604.8 8624.1 8628.9 8505.2 8751.9 8791.6 8803.6 8809.7 7094.9 7194.1 7195.1 7197.6 7202.6 6543.3 7201.7 7800.3 7843.7 7866.4 6612.7 7015.9 7401.3 7441.0 7520.0 7168.8 7267.1 7294.3 7299.1 7404.8 7397.9 7413.0 7421.2 7430.0 7430.7 6922.9 7005.3 7045.3 7050.8 7059.6 6881.6 6912.8 6922.2 6926.1 6929.4 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5472.51 Median:6272.48 stddev:1638.27 (26.1%) stddev-sans-first:1597.21 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: Values: 355.6 357.8 362.2 363.1 365.7 6035.2 6121.2 6150.8 6179.8 6214.4 5921.7 6096.5 6108.5 6116.9 6127.4 4790.6 4792.8 4796.5 4798.9 4808.0 4780.8 4789.8 4807.8 4809.9 4812.4 4555.3 4655.6 4679.9 4704.8 4762.9 4666.5 4703.6 4707.3 4707.6 4708.5 3930.8 3992.8 4124.9 4126.9 4133.1 5750.6 5807.3 5843.9 5859.2 5870.8 5459.5 5463.3 5465.5 5466.1 5470.1 6410.8 6497.3 6498.0 6504.3 6507.8 4526.4 4533.4 4539.7 4548.6 4552.6 6668.4 6751.4 6752.0 6753.5 6755.7 4664.6 4667.6 4669.3 4670.5 4676.2 4619.4 4731.3 4739.3 4742.5 4743.8 5415.6 5417.4 5423.8 5424.0 5426.7 6534.2 6544.1 6553.8 6554.8 6562.8 1641.8 1643.0 1650.5 1659.6 1660.1 1106.3 1107.9 1112.4 1113.5 1134.8 6330.6 6350.9 6355.0 6361.7 6363.5 6661.2 6707.1 6709.2 6712.7 6720.7 6183.3 6341.8 6342.6 6345.2 6347.7 3613.0 3653.2 3655.7 3658.0 3659.8 6679.9 6790.1 6876.2 6906.4 6977.3 6929.5 6943.7 6946.1 6974.4 6985.3 6801.9 7003.2 7004.3 7016.4 7018.8 6722.6 6738.7 6745.5 6800.1 6800.2 6722.6 6724.9 6726.9 6727.0 6735.0 6850.6 7025.7 7033.3 7044.6 7050.0 4772.7 4808.6 4817.2 4819.4 4819.7 6706.4 6711.6 6716.7 6720.1 6724.5 6410.8 6521.3 6523.7 6526.3 6530.9 6490.2 6500.9 6505.1 6507.3 6511.4 6407.3 6416.2 6421.6 6423.0 6425.0 6439.7 6473.3 6516.5 6525.4 6652.2 6462.5 6489.8 6493.4 6493.6 6501.7 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: 01:56:42 INFO - 2016-01-26 01:56:42,807 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14357.02 Median:47909.64 stddev:16863.82 (35.2%) stddev-sans-first:16880.79 01:56:42 INFO - 2016-01-26 01:56:42,809 DEBUG : BROWSER_OUTPUT: Values: 885.3 891.7 932.1 933.1 936.3 12455.1 12797.6 12856.2 12887.0 12898.7 13691.2 13776.2 13799.4 13802.5 13804.7 767.4 779.8 780.0 780.9 916.3 899.7 908.0 921.0 924.8 925.9 932.6 954.5 956.9 957.5 958.3 806.3 811.8 950.1 962.8 964.3 706.8 707.3 716.5 717.4 718.5 13535.3 13687.2 13688.3 13693.9 13696.4 11614.6 12217.2 12276.9 12329.7 12414.8 11033.0 11070.1 11072.5 11089.6 11155.9 3585.2 3612.9 3620.2 3641.7 3695.9 47520.3 47865.5 47953.8 48413.0 48464.8 10289.2 10311.4 10322.3 10399.4 10521.8 19612.5 19842.8 19851.9 19854.6 19856.1 8082.8 8136.5 8247.3 8299.8 8330.4 4438.7 4463.8 4509.2 4563.8 4568.2 3135.6 3150.5 3151.9 3164.8 3169.2 532.4 537.4 547.8 551.9 554.6 540.5 660.0 708.4 713.5 729.7 8833.5 8856.6 8886.2 9008.2 9038.2 534.8 586.6 662.5 702.2 705.7 4111.5 4120.8 4140.5 4315.7 4635.5 56652.0 64135.7 64513.0 65037.3 65773.0 51783.0 52512.2 54572.0 54689.5 54809.2 48569.5 48683.8 48950.5 49086.5 50510.2 9274.9 9474.5 9562.8 9721.5 9788.3 36481.3 37000.6 37518.2 37625.0 37829.8 42657.4 42998.0 48178.3 48483.0 48597.5 11203.3 11374.1 11438.4 11492.2 11811.5 11388.5 11818.2 12171.8 12191.6 12307.3 10464.2 10690.1 10715.7 10794.2 11322.4 11381.1 11688.2 11710.4 11896.9 11968.0 9016.9 9848.0 9945.5 10382.0 11548.9 10392.7 10446.1 10570.7 10695.4 11018.6 9241.9 9547.4 9599.8 9617.2 9886.7 01:56:42 INFO - 2016-01-26 01:56:42,809 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:56:42 INFO - 2016-01-26 01:56:42,809 DEBUG : BROWSER_OUTPUT: 01:56:43 INFO - 2016-01-26 01:56:43,344 INFO : Browser exited with error code: 0 01:56:43 INFO - 2016-01-26 01:56:43,367 INFO : Completed test dromaeo_css (00:18:30) 01:56:43 INFO - 2016-01-26 01:56:43,367 INFO : Starting test kraken 01:56:43 INFO - 2016-01-26 01:56:43,367 DEBUG : operating with platform_type : linux_ 01:56:43 INFO - 2016-01-26 01:56:43,367 INFO : Initialising browser for kraken test... 01:56:43 INFO - 2016-01-26 01:56:43,373 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp94CuuM/profile http://localhost:44650/getInfo.html 01:56:47 INFO - 2016-01-26 01:56:47,417 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:56:47 INFO - 2016-01-26 01:56:47,418 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:56:47 INFO - 2016-01-26 01:56:47,418 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:56:47 INFO - 2016-01-26 01:56:47,418 DEBUG : BROWSER_OUTPUT: __metrics 01:56:47 INFO - 2016-01-26 01:56:47,561 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:56:48 INFO - 2016-01-26 01:56:48,294 INFO : Browser initialized. 01:56:48 INFO - 2016-01-26 01:56:48,294 INFO : Running cycle 1/1 for kraken test... 01:56:48 INFO - 2016-01-26 01:56:48,294 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp94CuuM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:57:00 INFO - 2016-01-26 01:57:00,110 DEBUG : BROWSER_OUTPUT: RSS: Main: 134488064 01:57:00 INFO - 2016-01-26 01:57:00,110 DEBUG : BROWSER_OUTPUT: 01:57:02 INFO - 2016-01-26 01:57:02,551 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44650/tests/kraken/driver.html?testName=audio-beat-detection) 01:57:03 INFO - 2016-01-26 01:57:03,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 166420480 01:57:03 INFO - 2016-01-26 01:57:03,117 DEBUG : BROWSER_OUTPUT: 01:57:05 INFO - 2016-01-26 01:57:05,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44650/tests/kraken/driver.html?testName=audio-dft) 01:57:06 INFO - 2016-01-26 01:57:06,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 164429824 01:57:06 INFO - 2016-01-26 01:57:06,242 DEBUG : BROWSER_OUTPUT: 01:57:09 INFO - 2016-01-26 01:57:09,021 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44650/tests/kraken/driver.html?testName=audio-fft) 01:57:09 INFO - 2016-01-26 01:57:09,587 DEBUG : BROWSER_OUTPUT: RSS: Main: 162250752 01:57:09 INFO - 2016-01-26 01:57:09,587 DEBUG : BROWSER_OUTPUT: 01:57:11 INFO - 2016-01-26 01:57:11,572 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44650/tests/kraken/driver.html?testName=audio-oscillator) 01:57:12 INFO - 2016-01-26 01:57:12,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 160821248 01:57:12 INFO - 2016-01-26 01:57:12,134 DEBUG : BROWSER_OUTPUT: 01:57:14 INFO - 2016-01-26 01:57:14,328 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44650/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:57:14 INFO - 2016-01-26 01:57:14,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 161001472 01:57:14 INFO - 2016-01-26 01:57:14,876 DEBUG : BROWSER_OUTPUT: 01:57:17 INFO - 2016-01-26 01:57:17,735 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44650/tests/kraken/driver.html?testName=imaging-darkroom) 01:57:18 INFO - 2016-01-26 01:57:18,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 160296960 01:57:18 INFO - 2016-01-26 01:57:18,302 DEBUG : BROWSER_OUTPUT: 01:57:21 INFO - 2016-01-26 01:57:21,644 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44650/tests/kraken/driver.html?testName=imaging-desaturate) 01:57:22 INFO - 2016-01-26 01:57:22,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 159973376 01:57:22 INFO - 2016-01-26 01:57:22,205 DEBUG : BROWSER_OUTPUT: 01:57:24 INFO - 2016-01-26 01:57:24,890 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44650/tests/kraken/driver.html?testName=json-parse-financial) 01:57:25 INFO - 2016-01-26 01:57:25,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 160104448 01:57:25 INFO - 2016-01-26 01:57:25,457 DEBUG : BROWSER_OUTPUT: 01:57:27 INFO - 2016-01-26 01:57:27,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44650/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:57:27 INFO - 2016-01-26 01:57:27,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 168689664 01:57:27 INFO - 2016-01-26 01:57:27,568 DEBUG : BROWSER_OUTPUT: 01:57:29 INFO - 2016-01-26 01:57:29,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:57:29 INFO - 2016-01-26 01:57:29,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 165588992 01:57:29 INFO - 2016-01-26 01:57:29,931 DEBUG : BROWSER_OUTPUT: 01:57:31 INFO - 2016-01-26 01:57:31,935 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:57:32 INFO - 2016-01-26 01:57:32,502 DEBUG : BROWSER_OUTPUT: RSS: Main: 171429888 01:57:32 INFO - 2016-01-26 01:57:32,502 DEBUG : BROWSER_OUTPUT: 01:57:34 INFO - 2016-01-26 01:57:34,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:57:35 INFO - 2016-01-26 01:57:35,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 176996352 01:57:35 INFO - 2016-01-26 01:57:35,428 DEBUG : BROWSER_OUTPUT: 01:57:37 INFO - 2016-01-26 01:57:37,947 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:57:38 INFO - 2016-01-26 01:57:38,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 175591424 01:57:38 INFO - 2016-01-26 01:57:38,509 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,179 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44650/tests/kraken/driver.html?testName=ai-astar) 01:57:40 INFO - 2016-01-26 01:57:40,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 232517632 01:57:40 INFO - 2016-01-26 01:57:40,429 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,429 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:57:40 INFO - 2016-01-26 01:57:40,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:57:40 INFO - 2016-01-26 01:57:40,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;104;113;110;109;99;110;108;109 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;126;146;169;126;154;151;121;164;123 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;160;160;159;159;161;160;163 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;72;156;72;74;70;71;161;72 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;91;139;91;93;92;147;92;93;142 01:57:40 INFO - 2016-01-26 01:57:40,430 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;109;110;109;109;109;109 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;145;145;145;145;146 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;96;96;95;97;95 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;56;56;55;56;61;58;56;53;54 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;65;64;65;64;64;64;64 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;80;89;82;83;82;81;81;83;81 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;117;123;118;110;120;111;113;122;115 01:57:40 INFO - 2016-01-26 01:57:40,431 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;158;145;143;148;139;149;143;143;149 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;71;64;65;65;65;66;67;64 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,862 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: __startTimestamp1453802260427__endTimestamp 01:57:40 INFO - 2016-01-26 01:57:40,432 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.00 Median:109.50 stddev:3.86 (3.5%) stddev-sans-first:4.08 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 104.0 113.0 110.0 109.0 99.0 110.0 108.0 109.0 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.50 Median:148.50 stddev:17.56 (11.8%) stddev-sans-first:18.60 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: Values: 145.0 126.0 146.0 169.0 126.0 154.0 151.0 121.0 164.0 123.0 01:57:40 INFO - 2016-01-26 01:57:40,436 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 160.0 160.0 159.0 159.0 161.0 160.0 163.0 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.00 Median:71.50 stddev:36.66 (51.3%) stddev-sans-first:38.30 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 72.0 156.0 72.0 74.0 70.0 71.0 161.0 72.0 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.20 Median:92.00 stddev:24.56 (26.7%) stddev-sans-first:25.42 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: Values: 92.0 91.0 139.0 91.0 93.0 92.0 147.0 92.0 93.0 142.0 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,437 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 97.0 95.0 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:2.28 (4.1%) stddev-sans-first:2.32 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 54.0 56.0 56.0 55.0 56.0 61.0 58.0 56.0 53.0 54.0 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 65.0 64.0 65.0 64.0 64.0 64.0 64.0 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:2.73 (3.3%) stddev-sans-first:2.65 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 79.0 80.0 89.0 82.0 83.0 82.0 81.0 81.0 83.0 81.0 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.50 Median:117.50 stddev:4.40 (3.7%) stddev-sans-first:4.67 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 123.0 118.0 110.0 120.0 111.0 113.0 122.0 115.0 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.40 Median:147.50 stddev:5.19 (3.5%) stddev-sans-first:5.50 01:57:40 INFO - 2016-01-26 01:57:40,438 DEBUG : BROWSER_OUTPUT: Values: 147.0 158.0 145.0 143.0 148.0 139.0 149.0 143.0 143.0 149.0 01:57:40 INFO - 2016-01-26 01:57:40,439 DEBUG : BROWSER_OUTPUT: 01:57:40 INFO - 2016-01-26 01:57:40,439 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.00 stddev:2.12 (3.3%) stddev-sans-first:2.17 01:57:40 INFO - 2016-01-26 01:57:40,439 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 71.0 64.0 65.0 65.0 65.0 66.0 67.0 64.0 01:57:40 INFO - 2016-01-26 01:57:40,439 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:57:40 INFO - 2016-01-26 01:57:40,439 DEBUG : BROWSER_OUTPUT: 01:57:41 INFO - 2016-01-26 01:57:41,100 INFO : Browser exited with error code: 0 01:57:41 INFO - 2016-01-26 01:57:41,148 INFO : Completed test kraken (00:00:57) 01:57:41 INFO - 2016-01-26 01:57:41,148 INFO : Starting test v8_7 01:57:41 INFO - 2016-01-26 01:57:41,148 DEBUG : operating with platform_type : linux_ 01:57:41 INFO - 2016-01-26 01:57:41,148 INFO : Initialising browser for v8_7 test... 01:57:41 INFO - 2016-01-26 01:57:41,154 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmLN1KS/profile http://localhost:44650/getInfo.html 01:57:45 INFO - 2016-01-26 01:57:45,151 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:57:45 INFO - 2016-01-26 01:57:45,152 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:57:45 INFO - 2016-01-26 01:57:45,152 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:57:45 INFO - 2016-01-26 01:57:45,152 DEBUG : BROWSER_OUTPUT: __metrics 01:57:45 INFO - 2016-01-26 01:57:45,313 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:57:46 INFO - 2016-01-26 01:57:46,075 INFO : Browser initialized. 01:57:46 INFO - 2016-01-26 01:57:46,076 INFO : Running cycle 1/1 for v8_7 test... 01:57:46 INFO - 2016-01-26 01:57:46,076 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmLN1KS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:57:57 INFO - 2016-01-26 01:57:57,864 DEBUG : BROWSER_OUTPUT: RSS: Main: 134402048 01:57:57 INFO - 2016-01-26 01:57:57,864 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44650/tests/v8_7/run.html (next: http://localhost:44650/tests/v8_7/run.html) 01:58:19 INFO - 2016-01-26 01:58:19,458 DEBUG : BROWSER_OUTPUT: RSS: Main: 422825984 01:58:19 INFO - 2016-01-26 01:58:19,458 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,458 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: |0;Richards;168.18028927009755 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.58840288252054 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 01:58:19 INFO - 2016-01-26 01:58:19,459 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5154.639175257732 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |4;RayTrace;933.7068160597572 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |5;Earley;538.5029617662897 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |6;Boyer;10589.473684210527 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |7;RegExp;24095.238095238095 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |8;Splay;525.4860746190226 01:58:19 INFO - 2016-01-26 01:58:19,460 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: __startTimestamp1453802299457__endTimestamp 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:58:19 INFO - 2016-01-26 01:58:19,461 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,462 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.18 Median:168.18 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,462 DEBUG : BROWSER_OUTPUT: Values: 168.2 01:58:19 INFO - 2016-01-26 01:58:19,463 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,463 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.59 Median:167.59 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,463 DEBUG : BROWSER_OUTPUT: Values: 167.6 01:58:19 INFO - 2016-01-26 01:58:19,463 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: Values: 278.4 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5154.64 Median:5154.64 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: Values: 5154.6 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,464 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:933.71 Median:933.71 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: Values: 933.7 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:538.50 Median:538.50 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: Values: 538.5 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10589.47 Median:10589.47 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: Values: 10589.5 01:58:19 INFO - 2016-01-26 01:58:19,465 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24095.24 Median:24095.24 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: Values: 24095.2 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:525.49 Median:525.49 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: Values: 525.5 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: 01:58:19 INFO - 2016-01-26 01:58:19,466 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 01:58:19 INFO - 2016-01-26 01:58:19,467 DEBUG : BROWSER_OUTPUT: Values: 4822.1 01:58:19 INFO - 2016-01-26 01:58:19,467 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:58:19 INFO - 2016-01-26 01:58:19,467 DEBUG : BROWSER_OUTPUT: 01:58:20 INFO - 2016-01-26 01:58:20,164 INFO : Browser exited with error code: 0 01:58:20 INFO - 2016-01-26 01:58:20,180 INFO : Completed test v8_7 (00:00:39) 01:58:20 INFO - 2016-01-26 01:58:20,515 INFO : Completed test suite (00:20:07) 01:58:20 INFO - 2016-01-26 01:58:20,515 DEBUG : Working with test: dromaeo_css 01:58:20 INFO - 2016-01-26 01:58:20,515 DEBUG : Generating results file: dromaeo_css 01:58:20 INFO - 2016-01-26 01:58:20,516 DEBUG : Working with test: kraken 01:58:20 INFO - 2016-01-26 01:58:20,516 DEBUG : Generating results file: kraken 01:58:20 INFO - 2016-01-26 01:58:20,517 INFO : javascript benchmark 01:58:20 INFO - 2016-01-26 01:58:20,517 DEBUG : Working with test: v8_7 01:58:20 INFO - 2016-01-26 01:58:20,517 DEBUG : Generating results file: v8_7 01:58:20 INFO - 2016-01-26 01:58:20,517 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:58:20 INFO - 2016-01-26 01:58:20,567 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 01:58:20 INFO - 2016-01-26 01:58:20,567 DEBUG : process_Request line: dromaeo_css 7342.51 graph.html#tests=[[72,53,35]] 01:58:20 INFO - 2016-01-26 01:58:20,567 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:58:20 INFO - 2016-01-26 01:58:20,595 DEBUG : process_Request line: kraken 1487.60 graph.html#tests=[[232,53,35]] 01:58:20 INFO - 2016-01-26 01:58:20,595 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:58:20 INFO - 2016-01-26 01:58:20,626 DEBUG : process_Request line: v8_7 23308.04 graph.html#tests=[[230,53,35]] 01:58:20 INFO - 2016-01-26 01:58:20,628 INFO : javascript benchmark 01:58:20 INFO - 2016-01-26 01:58:20,631 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3287.6712328767126, 3407.148212946763, 3434.004138799748, 3437.3469040882233, 3451.2062225688082, 5613.242212301338, 5666.660000399979, 5678.318601767794, 5681.233033540481, 5692.459216374448, 5667.744951477183, 5725.770969161239, 5726.513246374053, 5727.484526392624, 5750.137479378095, 5046.82336118236, 5111.872203194931, 5161.200013997827, 5192.71440070796, 5247.134222853225, 5047.116754567955, 5176.12005958986, 5185.092608793465, 5239.030779305841, 5255.737213139347, 5040.97479512602, 5103.872403189913, 5109.821156259532, 5120.871978200556, 5195.818146364878, 5206.349206349207, 5257.579393648537, 5260.473952604747, 5267.841964741083, 5298.125809241471, 4979.352684151074, 5036.949630503685, 5058.544730974193, 5148.330717006766, 5152.896942061176, 5494.395616482184, 5518.392976772552, 5527.419620939824, 5533.280673512458, 5541.5289700375515, 6137.9079313810325, 6161.691915404212, 6178.351273116348, 6180.969095154495, 6186.659733714644, 5966.552508561832, 6025.517958563304, 6114.327423983358, 6119.502553941423, 6210.720517576675, 5716.428357164292, 5721.256236689203, 5721.370649228582, 5724.112762521816, 5800.941990580082, 6182.103594978703, 6184.938150618526, 6195.876082478325, 6195.907061394083, 6198.566100373021, 5645.1532270159805, 5819.127130930395, 5999.880002400015, 6095.329513753483, 6122.846928826726, 6111.266648002268, 6117.694115294217, 6137.877242455215, 6140.293866205444, 6146.877062458726, 5823.097419899922, 6018.789342372996, 6068.059450785136, 6069.726862291119, 6072.726727297282, 5994.430529099728, 6038.788642397495, 6163.599366041193, 6166.845828854225, 6172.105044768484, 4668.906621867543, 4670.159371313128, 4670.883227919261, 4689.0, 4702.341672165831, 4081.0, 4164.083901541656, 4183.372494125844, 4191.958080419217, 4224.683148763794, 5676.148577713294, 5777.277840269966, 5797.88404231913, 5804.767809287666, 5828.358880523139, 6136.509079273647, 6150.630962142196, 6155.815325540241, 6156.815295541141, 6157.7229024694, 5578.107502799533, 5612.691301978349, 5753.626014309057, 5754.223179870748, 5808.273965754281, 5357.919631205535, 5374.838754837361, 5376.677399356051, 5382.4886635769535, 5392.892142157135, 6341.302456729756, 6365.649889256136, 6369.585976911579, 6371.9681401593625, 6376.171097757262, 5784.913226301524, 5937.34689184198, 6068.605540639845, 6167.352427994996, 6183.84540386476, 6270.435660790551, 6308.810735677937, 6313.936860631335, 6322.557420980487, 6323.114763932961, 6065.878682426415, 6067.666278354733, 6068.666223357758, 6068.726907289094, 6079.908801367895, 6015.187949626919, 6092.725827337869, 6130.448259656652, 6257.4681152102585, 6263.78076767311, 5997.880042399215, 6006.392967735456, 6006.729697163551, 6014.789482368096, 6049.274087109575, 5727.570432217517, 5982.0727787193055, 5982.4017598239825, 5986.07215881539, 5992.580519363777, 5710.8001219957105, 5816.82549523515, 5820.126980952721, 5820.650760954356, 5848.883022339444, 5906.2026626406605, 6016.458518733335, 6047.123167140828, 6096.603720758137, 6103.633781972918, 5630.183623374506, 5703.486686198262, 5915.112733090071, 5931.0, 5955.612885162451], "unit": "ms", "name": "dojo.html", "value": 5620.679789064039}, {"lowerIsBetter": false, "replicates": [578.4331355271834, 620.1411045701703, 629.2889035390009, 629.389492192573, 632.1402892066791, 33839.0, 42800.785996070015, 42862.99999999996, 42982.0, 43035.35446968298, 46933.29600055993, 47372.0, 47413.0, 47455.76272118643, 47543.0, 7790.9610451947665, 7870.803229919241, 9100.226480749145, 9169.449833010041, 9259.537023148816, 10902.236843420964, 11146.331220126778, 11164.776704465905, 11187.32876027437, 11191.0, 10861.891381086167, 10901.400422976732, 10910.0, 10994.835077473845, 10995.615153469631, 10799.082078023339, 10808.675739727822, 10830.72923176919, 10851.565937362497, 10871.130309575254, 10760.78478430435, 10878.184136189817, 10900.018998290112, 10900.454977251105, 10903.34579925207, 42428.0, 42712.35931461031, 42745.1450970982, 42834.78582607067, 42905.0, 3681.6134305897763, 4041.2523683118716, 4100.038156023451, 4292.313229883203, 4314.158739045887, 32287.51568726471, 32518.83740581282, 32715.18212044694, 32720.67279327224, 32805.671943280504, 3974.602539746031, 4023.5372932112596, 4090.468239128924, 4119.608637179464, 4290.3135498320435, 304615.0, 306153.0, 306318.0, 307943.9999999978, 308913.0, 59731.10403343954, 60805.0, 66652.33347666558, 67155.32844671588, 68774.65612672004, 61416.07875881865, 61591.38408615856, 61892.381076189566, 61897.0, 62002.0, 17514.29942802299, 17799.0, 17804.643907121786, 17805.198766055557, 17805.554861128316, 13728.51950181759, 13791.241481718602, 13821.447142114404, 13848.515301964382, 14085.929570352288, 21062.684059739116, 21441.89279053595, 21449.14203431845, 21482.570348593254, 21555.56888862214, 7683.615819209017, 7909.209079092045, 7940.523568585788, 7953.5227886327, 8034.67861285542, 937.3625934364698, 982.0326977926736, 994.9403035817874, 999.1706883286854, 1102.239454776202, 30087.0, 30460.390792184033, 30713.38573228523, 30938.69061309403, 30952.535711964338, 1119.5130118398522, 1147.8507793986896, 1156.1444531046964, 1201.747632997063, 1262.4697626996683, 14074.2962851857, 14237.64405889861, 14706.264686765619, 16431.780843609617, 17073.231704573325, 348600.0, 352726.0, 352975.0, 353128.0, 353177.0, 56057.71971140118, 56273.718631406584, 56305.71847140738, 56344.0, 56400.15399769007, 1436.3895344478556, 1446.8625480579328, 1467.1123969997998, 1479.186447453896, 1484.6288427893028, 30711.232219193804, 30906.227344316572, 30953.690463095983, 31193.0, 31612.683873161895, 49598.75200623974, 49763.0, 50109.74945125251, 50459.0, 50462.49537504578, 339923.0, 340401.0, 341868.0, 342825.0, 342843.0, 30429.239269018453, 30772.230694232825, 31869.20326991844, 32769.34461310808, 34166.487502686985, 43441.56558434375, 43748.0, 43958.340624890014, 44121.338179926686, 44273.778631107925, 36847.815760921025, 37102.44346334753, 37744.811275943444, 38429.231415372094, 39711.40432893451, 36894.44658330181, 38078.238435231695, 38469.0, 38764.6123538761, 38906.6109338903, 38136.61863381442, 38458.80770596129, 38624.613753863225, 38840.41739373855, 39123.608763911994, 32846.68613255443, 33297.50053749147, 33715.15712107119, 33976.66023339735, 34261.0, 25851.741482585687, 26258.21225363242, 26319.342016448976, 26561.867190663925, 26812.731872681023], "unit": "ms", "name": "ext.html", "value": 20240.49337713417}, {"lowerIsBetter": false, "replicates": [392.72313019321376, 393.0841140143466, 393.4452422084861, 394.08965290179685, 396.8055652730163, 14851.331690073946, 16623.584410389714, 16866.24101915414, 18817.0, 18818.0, 11841.703957401069, 12071.879281207186, 12080.516779328838, 17891.552711182227, 18304.176312065956, 5024.371953505812, 5183.766730497136, 5184.11869982104, 5187.922181167285, 5194.376674799029, 4006.83972641094, 4103.589641035887, 5160.741962901859, 5546.057170281062, 5693.345265294482, 5618.044932361488, 5623.718814059301, 5628.915566266509, 5641.210230567724, 5667.8583035424035, 5466.34403871536, 6196.349383314755, 6197.628142311476, 6205.038219076006, 6209.6895155242055, 5400.756965936543, 5489.929463754569, 5495.395506494282, 5515.944840551583, 5529.004779139753, 14005.529419410917, 14074.22591757453, 18070.006149661764, 22351.0, 22578.209762658393, 2251.853629514076, 2308.7806658367426, 2341.953160936772, 2342.051469154995, 2351.3651314145254, 9782.608695652165, 9979.30144889858, 10034.0, 10063.798724025552, 10133.898661013369, 1791.758112654801, 1894.8484121270264, 1906.494778883597, 1944.4069558784565, 1947.7175809507685, 58947.70526147385, 66580.3341966579, 67215.0, 69541.0, 70061.29938700599, 10780.568777248822, 11075.16936229786, 11176.720581985353, 11177.944110279397, 11289.20975531705, 14945.40218391274, 15046.097234165985, 15078.396864125532, 15084.094954302776, 15084.24578771057, 8661.181151958033, 8712.259457946146, 8726.956365218133, 8776.692815751418, 8783.472991620523, 8624.051354351142, 8654.610542525601, 8695.217430431292, 8699.0, 8854.86717699235, 4748.928766068511, 4790.688605240648, 4862.5380588844, 4924.8768780780765, 4940.407151141886, 1752.9850178720844, 1797.5912277548148, 1855.9072046397896, 1878.3238034307637, 1926.9710954335696, 941.3363578677016, 1036.3056751976044, 1047.9633212837666, 1086.8152414089623, 1095.2552264460244, 16220.270087846075, 16508.6698266034, 16866.83133168677, 17049.573760656083, 17175.0, 871.8038441350645, 941.4680705401427, 984.178211193647, 994.6916455898695, 997.9251556133319, 9666.275029372826, 9842.704718858446, 9955.950220248853, 9995.868241712757, 10346.482675866177, 496932.0, 496950.0, 499340.0, 509094.0, 755933.0, 87022.0, 92076.53961730149, 92583.0, 93480.53259733658, 94992.0, 509.9796008159706, 521.4889408379809, 521.6426747677853, 523.2177894048422, 524.1534921102392, 10741.677749667522, 10818.404987725751, 15357.0, 15472.922635387202, 16287.67424651524, 37775.433368500046, 38429.6157038426, 38827.6117238824, 38859.0, 39117.608823911396, 49944.50055499544, 50458.2431263524, 51389.7430512845, 52740.0, 52960.47039529555, 9671.274654401088, 9814.263930205121, 10049.346792458467, 10069.748256293651, 10078.344907580984, 15960.521184364488, 17317.0, 17686.20412081434, 18282.360117395827, 18390.632187356445, 15583.22083895778, 15592.298346574658, 15684.529464116094, 15919.602009949844, 16071.437499687454, 14867.18230497333, 15259.389624414898, 15378.538643840702, 15486.767698484757, 15794.0, 12667.746645067231, 15453.072815631098, 15524.379024839332, 16150.353985840904, 16353.100579468244, 10677.839832402675, 10784.0, 10787.406692631981, 10792.62225822124, 10818.188635852186, 15560.533184004045, 15738.842611573738, 15877.841221587636, 15972.760408594113, 16349.673006539564], "unit": "ms", "name": "jquery.html", "value": 9126.52911712752}, {"lowerIsBetter": false, "replicates": [1514.3034204266037, 1517.4916403004993, 1683.7053515634761, 1803.8917664940104, 1824.5712257619457, 7328.853422931552, 7482.513636613617, 7669.50148240364, 7874.842503149941, 7896.881546776795, 7709.575973321465, 7916.0500739911295, 7986.2013798620255, 7997.200279972, 8001.319887809544, 2292.3008482412856, 2329.010170677464, 2330.8718020508863, 2334.206369834256, 2336.1122773346187, 1984.8015198480182, 2021.4339984804278, 2311.826613004023, 2326.5463234669246, 2390.1634427950253, 2239.854409463388, 2249.6063188941953, 2254.9098036078535, 2260.728712554496, 2260.7739226077424, 2329.4409341758023, 2354.1525050981804, 2358.8584684918874, 2358.870262135582, 2359.1978727232736, 2329.464223228667, 2378.143868207444, 2381.2380038387723, 2382.1424287256573, 2384.749601291874, 8142.43002989791, 8186.2632363087605, 8194.836103277961, 8202.548859812709, 8222.342212622976, 4836.347093142416, 4884.438289596671, 4885.389326334208, 4936.901261974776, 4942.777575009133, 2987.865546050433, 3019.7886147969652, 3034.5903303054024, 3041.285297954979, 3100.751939844807, 997.3517213811008, 1006.6677996261217, 1007.1640538353149, 1024.4212020208558, 1057.9365238085738, 11276.097912167006, 11278.661640150809, 11293.096552275798, 11299.717507062307, 11309.49107290174, 4242.830286788556, 4245.448091748053, 4263.509696384894, 4270.658347332206, 4340.348947657877, 9505.192058674951, 9531.47576883278, 9534.761630959143, 9558.28312876537, 9577.425354478613, 4843.95156048435, 4844.346013288232, 4868.610511159099, 4873.561379475864, 4883.755812209375, 4400.361947517592, 4409.713368631029, 4420.84527041552, 4450.421445212167, 4457.420535330386, 3803.9919421353366, 3914.3149948758855, 3939.1727737174942, 3945.0, 3946.1515774108702, 1140.7034171115404, 1200.1418985425462, 1224.3388570172185, 1279.5137847617846, 1290.8709129087017, 1107.8670559532907, 1172.1326218598176, 1173.7887180307455, 1353.8849197818295, 1431.8568143185598, 7773.261540153676, 7803.765887023398, 7890.7632771015815, 7892.60536973149, 7896.486728362638, 1144.5135817277624, 1160.8548931383577, 1168.2172944127456, 1173.167051393503, 1174.9236299640495, 6259.96870015647, 6324.083007963912, 6340.714667839866, 6342.334054924258, 6344.302126766052, 9515.476648784383, 9623.182029527568, 9724.124828765445, 9734.0, 9748.171405430476, 9441.480718560408, 9532.237421006303, 9649.662261820942, 9651.71044868655, 9656.613735450645, 7700.768976930701, 7761.185075567097, 7819.765407037798, 7836.138024817265, 7837.020372453558, 7448.9627551861895, 7487.438442116754, 7509.286617771303, 7527.096748390229, 7590.886136708064, 8280.420370574002, 8514.574271286534, 8604.82790344177, 8624.051354350891, 8628.913710862811, 8505.192006759347, 8751.868721969047, 8791.648334066565, 8803.559822008745, 8809.691660791841, 7094.9290507094265, 7194.136703595602, 7195.13658361, 7197.568145911262, 7202.603856787927, 6543.312952139957, 7201.675924583511, 7800.258975397328, 7843.7254696085365, 7866.449348545547, 6612.70242839083, 7015.894761578683, 7401.259874012771, 7441.032665753418, 7519.962400187964, 7168.820779480555, 7267.127944646676, 7294.307040831112, 7299.087614048244, 7404.814879628052, 7397.852042959218, 7413.036305280279, 7421.2207718190975, 7429.962850185714, 7430.665620047219, 6922.930770692228, 7005.2644472329675, 7045.26024767392, 7050.788476345718, 7059.611721355374, 6881.621510816753, 6912.758053468105, 6922.169339679271, 6926.099607051051, 6929.411000065051], "unit": "ms", "name": "mootools.html", "value": 4657.924012325389}, {"lowerIsBetter": false, "replicates": [355.58574260985955, 357.7603005985989, 362.16340254013215, 363.08321488242194, 365.7348422393763, 6035.185249991261, 6121.173641558388, 6150.784722534712, 6179.814605561829, 6214.4096310850455, 5921.667624784422, 6096.542759293048, 6108.450239478446, 6116.908246376297, 6127.448529632344, 4790.568848803607, 4792.784324705397, 4796.472388037313, 4798.904021919559, 4807.951920480786, 4780.760961951906, 4789.784459699322, 4807.759612019402, 4809.879753006167, 4812.422509298889, 4555.316702494619, 4655.557722016403, 4679.9298010529865, 4704.764761761899, 4762.880927976811, 4666.533346665306, 4703.600193983515, 4707.340972263886, 4707.599854012413, 4708.505606911276, 3930.76415415076, 3992.800359982018, 4124.876253712393, 4126.896827579305, 4133.0700592366775, 5750.568707346965, 5807.332156801979, 5843.883122337572, 5859.1797148399255, 5870.765169393219, 5459.481349271813, 5463.31708536433, 5465.508104270595, 5466.070767969455, 5470.070088085035, 6410.8076757697345, 6497.285298617148, 6498.0252962056065, 6504.3170467100745, 6507.837304067389, 4526.388937493429, 4533.433320834895, 4539.7049191802425, 4548.5906268436, 4552.56750608695, 6668.399844014062, 6751.42612877906, 6751.966240168767, 6753.45972322213, 6755.729770809162, 4664.55686709762, 4667.556582124693, 4669.34629151919, 4670.509596492388, 4676.18166820805, 4619.422572178478, 4731.266653668687, 4739.312799644102, 4742.454617719007, 4743.786529606107, 5415.593830462731, 5417.431169727122, 5423.837284881381, 5423.972880135653, 5426.701531415731, 6534.215894092739, 6544.051112588648, 6553.803385898431, 6554.80335589933, 6562.77030303937, 1641.8358164183726, 1643.0306119389618, 1650.4553497345846, 1659.6348803263504, 1660.10354408618, 1106.3085571517802, 1107.9113670906308, 1112.4159816096615, 1113.4989254835357, 1134.8467956825723, 6330.556861019684, 6350.936490635127, 6355.0, 6361.650109244036, 6363.522735794834, 6661.2006559213205, 6707.128073350434, 6709.228438729511, 6712.664366781642, 6720.697568609425, 6183.258009038854, 6341.841453963688, 6342.5560210785725, 6345.238571371373, 6347.746090156435, 3613.0425437259146, 3653.1962968146963, 3655.7258205634685, 3658.0306218852024, 3659.798711070917, 6679.899801502981, 6790.083338749305, 6876.209235937831, 6906.412954898789, 6977.2673869243, 6929.480288978347, 6943.722251109899, 6946.0622815920215, 6974.37230649244, 6985.301469853076, 6801.863962720718, 7003.194632617314, 7004.2995700430565, 7016.403605693573, 7018.789436316919, 6722.563033402813, 6738.6967586459705, 6745.52781305304, 6800.149981252343, 6800.217974932946, 6722.563033402813, 6724.899126513008, 6726.865462690621, 6726.966365168143, 6734.966325168343, 6850.554713943578, 7025.68384422692, 7033.331833475811, 7044.577325360495, 7049.9647501762165, 4772.689775164603, 4808.567228949271, 4817.205161148371, 4819.397575303087, 4819.7108173509705, 6706.429953454011, 6711.597304161766, 6716.664166791738, 6720.059191713066, 6724.49566282521, 6410.8076757697345, 6521.315261897433, 6523.706433210422, 6526.282108968105, 6530.9020364693615, 6490.221173459215, 6500.934990650033, 6505.121808555785, 6507.349265073455, 6511.413972742476, 6407.327230640715, 6416.197975253093, 6421.646809425526, 6423.036544518172, 6425.0, 6439.710213040331, 6473.287938326686, 6516.478681705357, 6525.3800888915475, 6652.234992975869, 6462.515311351762, 6489.837754056186, 6493.415592596689, 6493.577917435352, 6501.674916254263], "unit": "ms", "name": "prototype.html", "value": 4943.603916922954}, {"lowerIsBetter": false, "replicates": [885.2917665867305, 891.678995561598, 932.053965225296, 933.0855761353873, 936.3352020065753, 12455.065870059747, 12797.552085677014, 12856.228626282405, 12887.033472489555, 12898.74202515949, 13691.17852928825, 13776.173429594231, 13799.379027943742, 13802.516911908086, 13804.723905521909, 767.3784234769827, 779.8167430653792, 780.0132831967566, 780.8555417247799, 916.2624087609472, 899.6806133822497, 907.9591418386156, 921.0237148622448, 924.8474001789697, 925.8842644669417, 932.6129656192672, 954.4702690007043, 956.9377990430635, 957.5499515227832, 958.3148049144895, 806.2582424169777, 811.7645882694012, 950.083169741203, 962.7593101724569, 964.3008818606525, 706.7773651299824, 707.2891743797502, 716.4948711158621, 717.4117223876424, 718.5401343140394, 13535.255560944144, 13687.24720140392, 13688.315584220749, 13693.863061369359, 13696.383662735201, 11614.593489227836, 12217.205881617756, 12276.877231227663, 12329.69175770604, 12414.81377779334, 11033.0, 11070.059044981186, 11072.501737421755, 11089.55641774328, 11155.944220278847, 3585.175409655794, 3612.9277414451826, 3620.1854582718943, 3641.6540428659237, 3695.926081478355, 47520.28719569209, 47865.52134478645, 47953.76023119862, 48413.0, 48464.7576762114, 10289.176865850714, 10311.381317120997, 10322.277440579237, 10399.428031458343, 10521.789564208826, 19612.509687257934, 19842.801571984382, 19851.900740496494, 19854.602907942048, 19856.10647520865, 8082.757517274491, 8136.471129376571, 8247.298979586802, 8299.792505187419, 8330.375576762593, 4438.689291749611, 4463.843765468193, 4509.1658043262105, 4563.771811409416, 4568.1548913451115, 3135.5766971459016, 3150.464421048427, 3151.9212019699235, 3164.841757912095, 3169.1918560767317, 532.3638252288478, 537.4464301769184, 547.8000529806592, 551.8537587539305, 554.5563549160655, 540.4568408749182, 659.973601055962, 708.3553965891015, 713.454207531239, 729.7008226627057, 8833.469991800512, 8856.601452934505, 8886.24466920306, 9008.234300084436, 9038.231750301298, 534.7994502061727, 586.5923183387505, 662.486572905992, 702.2100137345486, 705.6965504832971, 4111.48606424197, 4120.814563344657, 4140.482439695038, 4315.676324275629, 4635.490096089427, 56652.0, 64135.679321604024, 64513.0, 65037.349626504074, 65773.0, 51783.0, 52512.212316815276, 54572.0, 54689.453105468434, 54809.17786233209, 48569.5143048565, 48683.75658121687, 48950.510494894595, 49086.50913490819, 50510.242346364095, 9274.907250927405, 9474.526273686426, 9562.760930976781, 9721.4653194075, 9788.314817962959, 36481.27037459289, 37000.62999369972, 37518.24963500769, 37625.0, 37829.81085094557, 42657.36013959731, 42998.0, 48178.27732583944, 48483.0, 48597.5140248593, 11203.271787334123, 11374.146938979775, 11438.370889601152, 11492.195546311677, 11811.468483918072, 11388.487518061873, 11818.231814932002, 12171.75656486883, 12191.634250972485, 12307.26156430617, 10464.215183861394, 10690.144788417063, 10715.732106697395, 10794.244402891722, 11322.433878306216, 11381.146414019147, 11688.240264382788, 11710.355930423908, 11896.940515297714, 11968.0, 9016.864747028669, 9847.950760246153, 9945.453000085065, 10382.0, 11548.94225528867, 10392.740181495523, 10446.05985461312, 10570.682879513626, 10695.358278503003, 11018.559257629606, 9241.907580924104, 9547.379420337656, 9599.760005999906, 9617.182539483942, 9886.703398898044], "unit": "ms", "name": "yui.html", "value": 6553.589328239475}], "name": "dromaeo_css", "value": 7342.516653454754}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 104.0, 113.0, 110.0, 109.0, 99.0, 110.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [145.0, 126.0, 146.0, 169.0, 126.0, 154.0, 151.0, 121.0, 164.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.5}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 160.0, 160.0, 159.0, 159.0, 161.0, 160.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 72.0, 156.0, 72.0, 74.0, 70.0, 71.0, 161.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 89.0}, {"lowerIsBetter": true, "replicates": [92.0, 91.0, 139.0, 91.0, 93.0, 92.0, 147.0, 92.0, 93.0, 142.0], "unit": "ms", "name": "audio-oscillator", "value": 107.2}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 97.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [54.0, 56.0, 56.0, 55.0, 56.0, 61.0, 58.0, 56.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [79.0, 80.0, 89.0, 82.0, 83.0, 82.0, 81.0, 81.0, 83.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.1}, {"lowerIsBetter": true, "replicates": [116.0, 117.0, 123.0, 118.0, 110.0, 120.0, 111.0, 113.0, 122.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.5}, {"lowerIsBetter": true, "replicates": [147.0, 158.0, 145.0, 143.0, 148.0, 139.0, 149.0, 143.0, 143.0, 149.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.4}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 71.0, 64.0, 65.0, 65.0, 65.0, 66.0, 67.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1487.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.18028927009755], "unit": "score", "name": "Richards", "value": 209.90569199999996}, {"lowerIsBetter": false, "replicates": [167.58840288252054], "unit": "score", "name": "DeltaBlue", "value": 394.526106}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5154.639175257732], "unit": "score", "name": "Decrypt", "value": 51.63911399999997}, {"lowerIsBetter": false, "replicates": [933.7068160597572], "unit": "score", "name": "RayTrace", "value": 792.5282190000003}, {"lowerIsBetter": false, "replicates": [538.5029617662897], "unit": "score", "name": "Earley", "value": 1237.621791}, {"lowerIsBetter": false, "replicates": [10589.473684210527], "unit": "score", "name": "Boyer", "value": 62.93636679920471}, {"lowerIsBetter": false, "replicates": [24095.238095238095], "unit": "score", "name": "RegExp", "value": 37.80767786561265}, {"lowerIsBetter": false, "replicates": [525.4860746190226], "unit": "score", "name": "Splay", "value": 155.077373}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23308.04402078525}]} 01:58:20 INFO - RETURN: dromaeo_css: 7342.51 01:58:20 INFO - RETURN: kraken: 1487.60 01:58:20 INFO - RETURN: v8_7: 23308.04 01:58:20 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23308.04"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1487.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7342.51"}}} 01:58:20 INFO - Return code: 0 01:58:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 01:58:20 INFO - # TBPL SUCCESS # 01:58:20 INFO - Running post-action listener: _resource_record_post_action 01:58:20 INFO - Running post-run listener: _resource_record_post_run 01:58:21 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 0; Write bytes: 504885248; Read time: 0; Write time: 607604 01:58:21 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 239415296; Read time: 0; Write time: 452260 01:58:21 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 0; Write bytes: 264871936; Read time: 0; Write time: 154548 01:58:21 INFO - Running post-run listener: _upload_blobber_files 01:58:21 INFO - Blob upload gear active. 01:58:21 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:58:21 INFO - Copying logs to upload dir... 01:58:21 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.845368 ========= master_lag: 0.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-01-26 01:58:21.535043) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:58:21.542686) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.008956 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453734153/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-26 01:58:21.572643) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:58:21.573002) ========= 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-1453801046.94394-1204304814 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004666 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-26 01:58:21.623178) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:58:21.626562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-26 01:58:21.626893) ========= ========= Total master_lag: 0.72 =========