builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-016 starttime: 1450375177.63 results: success (0) buildid: 20151217072309 builduid: 23de7403ed1646b4a075de106e17f9d1 revision: 0711218a018d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.634124) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.634925) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.635224) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006321 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.661414) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.661712) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.710503) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.710752) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.772672) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.772938) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.773282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:37.773560) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 09:59:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.3M=0s 2015-12-17 09:59:38 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.291593 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.078086) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.078411) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017291 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.127758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.128165) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 09:59:38,422 Setting DEBUG logging. 2015-12-17 09:59:38,422 attempt 1/10 2015-12-17 09:59:38,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0711218a018d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 09:59:38,674 unpacking tar archive at: mozilla-central-0711218a018d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.532098 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.673255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.673569) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.681709) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.681973) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 09:59:38.682604) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-17 09:59:38.682881) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:59:39 INFO - MultiFileLogger online at 20151217 09:59:39 in /builds/slave/test-pgo 09:59:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:59:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:59:39 INFO - {'append_to_log': False, 09:59:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:59:39 INFO - 'blob_upload_branch': 'Firefox', 09:59:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:59:39 INFO - 'branch': 'Firefox', 09:59:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:39 INFO - 'config_files': ('talos/linux_config.py',), 09:59:39 INFO - 'default_actions': ('clobber', 09:59:39 INFO - 'read-buildbot-config', 09:59:39 INFO - 'download-and-extract', 09:59:39 INFO - 'populate-webroot', 09:59:39 INFO - 'create-virtualenv', 09:59:39 INFO - 'install', 09:59:39 INFO - 'run-tests'), 09:59:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:39 INFO - 'download_minidump_stackwalk': True, 09:59:39 INFO - 'download_symbols': 'ondemand', 09:59:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:39 INFO - 'installer_path': 'installer.exe', 09:59:39 INFO - 'log_level': 'info', 09:59:39 INFO - 'log_name': 'talos', 09:59:39 INFO - 'log_to_console': True, 09:59:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:59:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:59:39 INFO - 'opt_config_files': (), 09:59:39 INFO - 'pip_index': False, 09:59:39 INFO - 'sps_profile': False, 09:59:39 INFO - 'sps_profile_interval': 0, 09:59:39 INFO - 'suite': 'dromaeojs-e10s', 09:59:39 INFO - 'system_bits': '64', 09:59:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:39 INFO - 'title': 'talos-linux64-ix-016', 09:59:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:39 INFO - 'use_talos_json': True, 09:59:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:59:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:39 INFO - 'work_dir': 'build'} 09:59:39 INFO - ##### 09:59:39 INFO - ##### Running clobber step. 09:59:39 INFO - ##### 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: clobber 09:59:39 INFO - rmtree: /builds/slave/test-pgo/build 09:59:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:59:39 INFO - Running post-action listener: _resource_record_post_action 09:59:39 INFO - ##### 09:59:39 INFO - ##### Running read-buildbot-config step. 09:59:39 INFO - ##### 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: read_buildbot_config 09:59:39 INFO - Using buildbot properties: 09:59:39 INFO - { 09:59:39 INFO - "project": "", 09:59:39 INFO - "product": "firefox", 09:59:39 INFO - "script_repo_revision": "production", 09:59:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:59:39 INFO - "repository": "", 09:59:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:59:39 INFO - "buildid": "20151217072309", 09:59:39 INFO - "stage_platform": "linux64-pgo", 09:59:39 INFO - "basedir": "/builds/slave/test-pgo", 09:59:39 INFO - "nightly_build": "True", 09:59:39 INFO - "buildnumber": 584, 09:59:39 INFO - "slavename": "talos-linux64-ix-016", 09:59:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:59:39 INFO - "platform": "ubuntu64_hw", 09:59:39 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:59:39 INFO - "branch": "mozilla-central", 09:59:39 INFO - "repo_path": "mozilla-central", 09:59:39 INFO - "revision": "0711218a018d", 09:59:39 INFO - "pgo_build": "True", 09:59:39 INFO - "builduid": "23de7403ed1646b4a075de106e17f9d1", 09:59:39 INFO - "slavebuilddir": "test" 09:59:39 INFO - } 09:59:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 09:59:39 INFO - Running post-action listener: _resource_record_post_action 09:59:39 INFO - ##### 09:59:39 INFO - ##### Running download-and-extract step. 09:59:39 INFO - ##### 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: download_and_extract 09:59:39 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:39 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json 09:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json 09:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:59:48 INFO - Downloaded 1302 bytes. 09:59:48 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:59:48 INFO - Using the following test package requirements: 09:59:48 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:48 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:59:48 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'jsshell-linux-x86_64.zip'], 09:59:48 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:59:48 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:48 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:59:48 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:59:48 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:59:48 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 09:59:48 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 09:59:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:59:48 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:48 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:59:52 INFO - Downloaded 23120898 bytes. 09:59:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:59:53 INFO - Return code: 0 09:59:53 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:59: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')]} 09:59:53 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:59:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:59:54 INFO - Downloaded 23120898 bytes. 09:59:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 09:59:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:59:56 INFO - Return code: 0 09:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:56 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:59:57 INFO - Downloaded 11132039 bytes. 09:59:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:59:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 09:59:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:59:57 INFO - Return code: 0 09:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:59:57 INFO - https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:59:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:59:58 INFO - Downloaded 54646150 bytes. 09:59:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:58 INFO - mkdir: /builds/slave/test-pgo/properties 09:59:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:59:58 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:59:58 INFO - Contents: 09:59:58 INFO - build_url:https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 09:59:58 INFO - Running post-action listener: _resource_record_post_action 09:59:58 INFO - Running post-action listener: set_extra_try_arguments 09:59:58 INFO - ##### 09:59:58 INFO - ##### Running populate-webroot step. 09:59:58 INFO - ##### 09:59:58 INFO - Running pre-action listener: _resource_record_pre_action 09:59:58 INFO - Running main action method: populate_webroot 09:59:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:58 INFO - '--tppagecycles', 09:59:58 INFO - '7'], 09:59:58 INFO - 'tests': ['tsvgm']}}, 09:59:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:59:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:59:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:59:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'tests': ['damp', 'tps']}, 09:59:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['damp', 'tps']}, 09:59:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:59:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:58 INFO - 'other': {'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'other_l64': {'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'other_nol64': {'tests': ['a11yr', 09:59:58 INFO - 'ts_paint', 09:59:58 INFO - 'tpaint', 09:59:58 INFO - 'sessionrestore', 09:59:58 INFO - 'sessionrestore_no_auto_restore']}, 09:59:58 INFO - 'svgr': {'tests': ['tsvgx', 09:59:58 INFO - 'tsvgr_opacity', 09:59:58 INFO - 'tart', 09:59:58 INFO - 'tscrollx', 09:59:58 INFO - 'cart']}, 09:59:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['tsvgx', 09:59:58 INFO - 'tsvgr_opacity', 09:59:58 INFO - 'tart', 09:59:58 INFO - 'tscrollx', 09:59:58 INFO - 'cart']}, 09:59:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'tests': ['tp5o']}, 09:59:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'talos_options': ['--e10s'], 09:59:58 INFO - 'tests': ['tp5o']}, 09:59:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'talos_options': ['--xperf_path', 09:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:58 INFO - 'tests': ['tp5n']}, 09:59:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:58 INFO - 'talos_options': ['--e10s', 09:59:58 INFO - '--xperf_path', 09:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:58 INFO - 'tests': ['tp5n']}}, 09:59:58 INFO - 'talos.zip': {'path': '', 09:59:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:58 INFO - Running post-action listener: _resource_record_post_action 09:59:58 INFO - ##### 09:59:58 INFO - ##### Running create-virtualenv step. 09:59:58 INFO - ##### 09:59:58 INFO - Running pre-action listener: _resource_record_pre_action 09:59:58 INFO - Running main action method: create_virtualenv 09:59:58 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:59:58 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 09:59:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:59:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:58 INFO - Using real prefix '/usr' 09:59:58 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 10:00:01 INFO - Installing pip.................done. 10:00:01 INFO - Return code: 0 10:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:00:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:00:01 INFO - Using env: {'DISPLAY': ':0', 10:00:01 INFO - 'HOME': '/home/cltbld', 10:00:01 INFO - 'LANG': 'en_US.UTF-8', 10:00:01 INFO - 'LANGUAGE': 'en_US:en', 10:00:01 INFO - 'LOGNAME': 'cltbld', 10:00:01 INFO - 'MAIL': '/var/mail/cltbld', 10:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:01 INFO - 'MOZ_NO_REMOTE': '1', 10:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:01 INFO - 'NO_EM_RESTART': '1', 10:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:01 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:01 INFO - 'SHELL': '/bin/bash', 10:00:01 INFO - 'SHLVL': '1', 10:00:01 INFO - 'TERM': 'linux', 10:00:01 INFO - 'TMOUT': '86400', 10:00:01 INFO - 'USER': 'cltbld', 10:00:01 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:01 INFO - '_': '/tools/buildbot/bin/python'} 10:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:01 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 10:00:01 INFO - Cleaning up... 10:00:01 INFO - Return code: 0 10:00:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 10:00:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:00:01 INFO - Using env: {'DISPLAY': ':0', 10:00:01 INFO - 'HOME': '/home/cltbld', 10:00:01 INFO - 'LANG': 'en_US.UTF-8', 10:00:01 INFO - 'LANGUAGE': 'en_US:en', 10:00:01 INFO - 'LOGNAME': 'cltbld', 10:00:01 INFO - 'MAIL': '/var/mail/cltbld', 10:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:01 INFO - 'MOZ_NO_REMOTE': '1', 10:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:01 INFO - 'NO_EM_RESTART': '1', 10:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:01 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:01 INFO - 'SHELL': '/bin/bash', 10:00:01 INFO - 'SHLVL': '1', 10:00:01 INFO - 'TERM': 'linux', 10:00:01 INFO - 'TMOUT': '86400', 10:00:01 INFO - 'USER': 'cltbld', 10:00:01 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:01 INFO - '_': '/tools/buildbot/bin/python'} 10:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:01 INFO - Downloading/unpacking psutil>=0.7.1 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:03 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 10:00:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 10:00:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:00:03 INFO - Installing collected packages: psutil 10:00:03 INFO - Running setup.py install for psutil 10:00:03 INFO - building 'psutil._psutil_linux' extension 10:00:03 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 10:00:03 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 10:00:03 INFO - building 'psutil._psutil_posix' extension 10:00:03 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 10:00:03 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 10:00:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:00:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:00:03 INFO - Successfully installed psutil 10:00:03 INFO - Cleaning up... 10:00:03 INFO - Return code: 0 10:00:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:00:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:00:03 INFO - Using env: {'DISPLAY': ':0', 10:00:03 INFO - 'HOME': '/home/cltbld', 10:00:03 INFO - 'LANG': 'en_US.UTF-8', 10:00:03 INFO - 'LANGUAGE': 'en_US:en', 10:00:03 INFO - 'LOGNAME': 'cltbld', 10:00:03 INFO - 'MAIL': '/var/mail/cltbld', 10:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:03 INFO - 'MOZ_NO_REMOTE': '1', 10:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:03 INFO - 'NO_EM_RESTART': '1', 10:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:03 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:03 INFO - 'SHELL': '/bin/bash', 10:00:03 INFO - 'SHLVL': '1', 10:00:03 INFO - 'TERM': 'linux', 10:00:03 INFO - 'TMOUT': '86400', 10:00:03 INFO - 'USER': 'cltbld', 10:00:03 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:03 INFO - '_': '/tools/buildbot/bin/python'} 10:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:00:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 10:00:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:00:06 INFO - Installing collected packages: mozsystemmonitor 10:00:06 INFO - Running setup.py install for mozsystemmonitor 10:00:06 INFO - Successfully installed mozsystemmonitor 10:00:06 INFO - Cleaning up... 10:00:06 INFO - Return code: 0 10:00:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 10:00:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:00:06 INFO - Using env: {'DISPLAY': ':0', 10:00:06 INFO - 'HOME': '/home/cltbld', 10:00:06 INFO - 'LANG': 'en_US.UTF-8', 10:00:06 INFO - 'LANGUAGE': 'en_US:en', 10:00:06 INFO - 'LOGNAME': 'cltbld', 10:00:06 INFO - 'MAIL': '/var/mail/cltbld', 10:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:06 INFO - 'MOZ_NO_REMOTE': '1', 10:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:06 INFO - 'NO_EM_RESTART': '1', 10:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:06 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:06 INFO - 'SHELL': '/bin/bash', 10:00:06 INFO - 'SHLVL': '1', 10:00:06 INFO - 'TERM': 'linux', 10:00:06 INFO - 'TMOUT': '86400', 10:00:06 INFO - 'USER': 'cltbld', 10:00:06 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:06 INFO - '_': '/tools/buildbot/bin/python'} 10:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:06 INFO - Downloading/unpacking blobuploader==1.2.4 10:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 10:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:00:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 10:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:00:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 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 10:00:09 INFO - Downloading docopt-0.6.1.tar.gz 10:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 10:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:00:09 INFO - Installing collected packages: blobuploader, requests, docopt 10:00:09 INFO - Running setup.py install for blobuploader 10:00:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:00:09 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:00:09 INFO - Running setup.py install for requests 10:00:10 INFO - Running setup.py install for docopt 10:00:10 INFO - Successfully installed blobuploader requests docopt 10:00:10 INFO - Cleaning up... 10:00:10 INFO - Return code: 0 10:00:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:00:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:10 INFO - Using env: {'DISPLAY': ':0', 10:00:10 INFO - 'HOME': '/home/cltbld', 10:00:10 INFO - 'LANG': 'en_US.UTF-8', 10:00:10 INFO - 'LANGUAGE': 'en_US:en', 10:00:10 INFO - 'LOGNAME': 'cltbld', 10:00:10 INFO - 'MAIL': '/var/mail/cltbld', 10:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:10 INFO - 'MOZ_NO_REMOTE': '1', 10:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:10 INFO - 'NO_EM_RESTART': '1', 10:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:10 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:10 INFO - 'SHELL': '/bin/bash', 10:00:10 INFO - 'SHLVL': '1', 10:00:10 INFO - 'TERM': 'linux', 10:00:10 INFO - 'TMOUT': '86400', 10:00:10 INFO - 'USER': 'cltbld', 10:00:10 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:10 INFO - '_': '/tools/buildbot/bin/python'} 10:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:10 INFO - Running setup.py (path:/tmp/pip-NGYxlC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:10 INFO - Running setup.py (path:/tmp/pip-PYJAfO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:10 INFO - Running setup.py (path:/tmp/pip-N5x6fj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:10 INFO - Running setup.py (path:/tmp/pip-cGyXQ1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:10 INFO - Running setup.py (path:/tmp/pip-6_FufH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:10 INFO - Running setup.py (path:/tmp/pip-RZCO9s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:11 INFO - Running setup.py (path:/tmp/pip-gACza7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:11 INFO - Running setup.py (path:/tmp/pip-ndF9WX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:11 INFO - Running setup.py (path:/tmp/pip-Oij_Vt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:11 INFO - Running setup.py (path:/tmp/pip-fOpT_W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:11 INFO - Running setup.py (path:/tmp/pip-tP1_s0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:11 INFO - Running setup.py (path:/tmp/pip-go5MoQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:11 INFO - Running setup.py (path:/tmp/pip-GcATmr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:11 INFO - Running setup.py (path:/tmp/pip-tvRbj2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:11 INFO - Running setup.py (path:/tmp/pip-jdUQ1R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:11 INFO - Running setup.py (path:/tmp/pip-EcqqOm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:11 INFO - Running setup.py (path:/tmp/pip-UEcf9K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:00:11 INFO - Running setup.py install for manifestparser 10:00:11 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:00:11 INFO - Running setup.py install for mozcrash 10:00:11 INFO - Running setup.py install for mozdebug 10:00:12 INFO - Running setup.py install for mozdevice 10:00:12 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for mozfile 10:00:12 INFO - Running setup.py install for mozhttpd 10:00:12 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for mozinfo 10:00:12 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for mozInstall 10:00:12 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for mozleak 10:00:12 INFO - Running setup.py install for mozlog 10:00:12 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for moznetwork 10:00:12 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:00:12 INFO - Running setup.py install for mozprocess 10:00:13 INFO - Running setup.py install for mozprofile 10:00:13 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:00:13 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:00:13 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:00:13 INFO - Running setup.py install for mozrunner 10:00:13 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:00:13 INFO - Running setup.py install for mozscreenshot 10:00:13 INFO - Running setup.py install for moztest 10:00:13 INFO - Running setup.py install for mozversion 10:00:13 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:00:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:00:13 INFO - Cleaning up... 10:00:13 INFO - Return code: 0 10:00:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:13 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')]} 10:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:13 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')]} 10:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:13 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:00:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:13 INFO - Using env: {'DISPLAY': ':0', 10:00:13 INFO - 'HOME': '/home/cltbld', 10:00:13 INFO - 'LANG': 'en_US.UTF-8', 10:00:13 INFO - 'LANGUAGE': 'en_US:en', 10:00:13 INFO - 'LOGNAME': 'cltbld', 10:00:13 INFO - 'MAIL': '/var/mail/cltbld', 10:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:13 INFO - 'MOZ_NO_REMOTE': '1', 10:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:13 INFO - 'NO_EM_RESTART': '1', 10:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:13 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:13 INFO - 'SHELL': '/bin/bash', 10:00:13 INFO - 'SHLVL': '1', 10:00:13 INFO - 'TERM': 'linux', 10:00:13 INFO - 'TMOUT': '86400', 10:00:13 INFO - 'USER': 'cltbld', 10:00:13 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:13 INFO - '_': '/tools/buildbot/bin/python'} 10:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:13 INFO - Running setup.py (path:/tmp/pip-VzTqOq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:00:13 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)) 10:00:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:13 INFO - Running setup.py (path:/tmp/pip-Lorf9_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:00:13 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)) 10:00:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:13 INFO - Running setup.py (path:/tmp/pip-LKo2Jd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:00:13 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)) 10:00:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:13 INFO - Running setup.py (path:/tmp/pip-YupOEV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:14 INFO - Running setup.py (path:/tmp/pip-guRvLA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:14 INFO - Running setup.py (path:/tmp/pip-afeT3Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:14 INFO - Running setup.py (path:/tmp/pip-QCv8F9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:14 INFO - Running setup.py (path:/tmp/pip-Sg2zvh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:14 INFO - Running setup.py (path:/tmp/pip-MjhGWd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:14 INFO - Running setup.py (path:/tmp/pip-IA0yEX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:14 INFO - Running setup.py (path:/tmp/pip-aaNnuv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:14 INFO - Running setup.py (path:/tmp/pip-tYFbbi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:14 INFO - Running setup.py (path:/tmp/pip-QCL2M4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:14 INFO - Running setup.py (path:/tmp/pip-0IkbL5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:14 INFO - Running setup.py (path:/tmp/pip-2dwFA7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:14 INFO - Running setup.py (path:/tmp/pip-AG33nY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:14 INFO - Running setup.py (path:/tmp/pip-Uw8dFP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:00:14 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)) 10:00:14 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)) 10:00:14 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)) 10:00:14 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)) 10:00:14 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)) 10:00:14 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)) 10:00:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:00:15 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 10:00:15 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 10:00:15 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 10:00:15 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 10:00:15 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 10:00:15 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 10:00:16 INFO - Downloading blessings-1.5.1.tar.gz 10:00:16 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 10:00:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:00:16 INFO - Installing collected packages: blessings 10:00:16 INFO - Running setup.py install for blessings 10:00:16 INFO - Successfully installed blessings 10:00:16 INFO - Cleaning up... 10:00:16 INFO - Return code: 0 10:00:16 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:00:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:00:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:00:17 INFO - Reading from file tmpfile_stdout 10:00:17 INFO - Current package versions: 10:00:17 INFO - argparse == 1.2.1 10:00:17 INFO - blessings == 1.5.1 10:00:17 INFO - blobuploader == 1.2.4 10:00:17 INFO - docopt == 0.6.1 10:00:17 INFO - manifestparser == 1.1 10:00:17 INFO - mozInstall == 1.12 10:00:17 INFO - mozcrash == 0.16 10:00:17 INFO - mozdebug == 0.1 10:00:17 INFO - mozdevice == 0.47 10:00:17 INFO - mozfile == 1.2 10:00:17 INFO - mozhttpd == 0.7 10:00:17 INFO - mozinfo == 0.9 10:00:17 INFO - mozleak == 0.1 10:00:17 INFO - mozlog == 3.1 10:00:17 INFO - moznetwork == 0.27 10:00:17 INFO - mozprocess == 0.22 10:00:17 INFO - mozprofile == 0.27 10:00:17 INFO - mozrunner == 6.11 10:00:17 INFO - mozscreenshot == 0.1 10:00:17 INFO - mozsystemmonitor == 0.0 10:00:17 INFO - moztest == 0.7 10:00:17 INFO - mozversion == 1.4 10:00:17 INFO - psutil == 3.1.1 10:00:17 INFO - requests == 1.2.3 10:00:17 INFO - wsgiref == 0.1.2 10:00:17 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 10:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:00:17 INFO - Using env: {'DISPLAY': ':0', 10:00:17 INFO - 'HOME': '/home/cltbld', 10:00:17 INFO - 'LANG': 'en_US.UTF-8', 10:00:17 INFO - 'LANGUAGE': 'en_US:en', 10:00:17 INFO - 'LOGNAME': 'cltbld', 10:00:17 INFO - 'MAIL': '/var/mail/cltbld', 10:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:17 INFO - 'MOZ_NO_REMOTE': '1', 10:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:17 INFO - 'NO_EM_RESTART': '1', 10:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:17 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:17 INFO - 'SHELL': '/bin/bash', 10:00:17 INFO - 'SHLVL': '1', 10:00:17 INFO - 'TERM': 'linux', 10:00:17 INFO - 'TMOUT': '86400', 10:00:17 INFO - 'USER': 'cltbld', 10:00:17 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:17 INFO - '_': '/tools/buildbot/bin/python'} 10:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 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)) 10:00:17 INFO - Cleaning up... 10:00:17 INFO - Return code: 0 10:00:17 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:00:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:00:17 INFO - Using env: {'DISPLAY': ':0', 10:00:17 INFO - 'HOME': '/home/cltbld', 10:00:17 INFO - 'LANG': 'en_US.UTF-8', 10:00:17 INFO - 'LANGUAGE': 'en_US:en', 10:00:17 INFO - 'LOGNAME': 'cltbld', 10:00:17 INFO - 'MAIL': '/var/mail/cltbld', 10:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:17 INFO - 'MOZ_NO_REMOTE': '1', 10:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:17 INFO - 'NO_EM_RESTART': '1', 10:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:17 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:17 INFO - 'SHELL': '/bin/bash', 10:00:17 INFO - 'SHLVL': '1', 10:00:17 INFO - 'TERM': 'linux', 10:00:17 INFO - 'TMOUT': '86400', 10:00:17 INFO - 'USER': 'cltbld', 10:00:17 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:17 INFO - '_': '/tools/buildbot/bin/python'} 10:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:17 INFO - Downloading/unpacking jsonschema 10:00:17 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 10:00:17 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 10:00:17 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 10:00:17 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 10:00:17 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 10:00:17 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 10:00:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:00:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 10:00:19 INFO - Downloading/unpacking functools32 (from jsonschema) 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 INFO - Downloading functools32-3.2.3-2.tar.gz 10:00:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 10:00:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:00:19 INFO - warning: no files found matching '*.txt' 10:00:19 INFO - no previously-included directories found matching 'build' 10:00:19 INFO - no previously-included directories found matching 'dist' 10:00:19 INFO - no previously-included directories found matching '.git*' 10:00:19 INFO - Installing collected packages: jsonschema, functools32 10:00:19 INFO - Running setup.py install for functools32 10:00:19 INFO - warning: no files found matching '*.txt' 10:00:19 INFO - no previously-included directories found matching 'build' 10:00:19 INFO - no previously-included directories found matching 'dist' 10:00:19 INFO - no previously-included directories found matching '.git*' 10:00:19 INFO - Successfully installed jsonschema functools32 10:00:19 INFO - Cleaning up... 10:00:19 INFO - Return code: 0 10:00:19 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:00:19 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')]} 10:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:19 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')]} 10:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f63db803f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e9d600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, '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': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', '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 10:00:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:00:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:00:19 INFO - Using env: {'DISPLAY': ':0', 10:00:19 INFO - 'HOME': '/home/cltbld', 10:00:19 INFO - 'LANG': 'en_US.UTF-8', 10:00:19 INFO - 'LANGUAGE': 'en_US:en', 10:00:19 INFO - 'LOGNAME': 'cltbld', 10:00:19 INFO - 'MAIL': '/var/mail/cltbld', 10:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:19 INFO - 'MOZ_NO_REMOTE': '1', 10:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:19 INFO - 'NO_EM_RESTART': '1', 10:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:19 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:19 INFO - 'SHELL': '/bin/bash', 10:00:19 INFO - 'SHLVL': '1', 10:00:19 INFO - 'TERM': 'linux', 10:00:19 INFO - 'TMOUT': '86400', 10:00:19 INFO - 'USER': 'cltbld', 10:00:19 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:19 INFO - '_': '/tools/buildbot/bin/python'} 10:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:19 INFO - Downloading/unpacking flake8 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:19 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 10:00:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 10:00:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 10:00:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 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 10:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 10:00:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:00:22 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 10:00:22 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 10:00:22 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 10:00:22 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 10:00:22 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 10:00:22 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 10:00:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 10:00:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:00:22 INFO - no previously-included directories found matching 'docs/_build' 10:00:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:00:22 INFO - Running setup.py install for pep8 10:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:00:22 INFO - no previously-included directories found matching 'docs/_build' 10:00:22 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:00:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:00:22 INFO - Cleaning up... 10:00:22 INFO - Return code: 0 10:00:22 INFO - Running post-action listener: _resource_record_post_action 10:00:22 INFO - Running post-action listener: _start_resource_monitoring 10:00:22 INFO - Starting resource monitoring. 10:00:22 INFO - ##### 10:00:22 INFO - ##### Running install step. 10:00:22 INFO - ##### 10:00:22 INFO - Running pre-action listener: _resource_record_pre_action 10:00:22 INFO - Running main action method: install 10:00:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:00:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:00:22 INFO - Reading from file tmpfile_stdout 10:00:22 INFO - Detecting whether we're running mozinstall >=1.0... 10:00:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:00:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:00:22 INFO - Reading from file tmpfile_stdout 10:00:22 INFO - Output received: 10:00:22 INFO - Usage: mozinstall [options] installer 10:00:22 INFO - Options: 10:00:22 INFO - -h, --help show this help message and exit 10:00:22 INFO - -d DEST, --destination=DEST 10:00:22 INFO - Directory to install application into. [default: 10:00:22 INFO - "/builds/slave/test-pgo"] 10:00:22 INFO - --app=APP Application being installed. [default: firefox] 10:00:22 INFO - mkdir: /builds/slave/test-pgo/build/application 10:00:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:00:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:00:38 INFO - Reading from file tmpfile_stdout 10:00:38 INFO - Output received: 10:00:38 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:00:38 INFO - Running post-action listener: _resource_record_post_action 10:00:38 INFO - ##### 10:00:38 INFO - ##### Running run-tests step. 10:00:38 INFO - ##### 10:00:38 INFO - Running pre-action listener: _resource_record_pre_action 10:00:38 INFO - Running main action method: run_tests 10:00:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:00:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:00:38 INFO - Python 2.7.3 10:00:38 INFO - Return code: 0 10:00:38 INFO - grabbing minidump binary from tooltool 10:00: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')]} 10:00:38 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 0x1f2c350>, '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 0x1f2d250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f2d6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:00:38 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 10:00:38 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 10:00:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:38 INFO - Return code: 0 10:00:38 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:00:38 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:00:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:00:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:00:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:00:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:00:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:00:39 INFO - Return code: 0 10:00:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:00:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost 10:00:39 INFO - Using env: {'DISPLAY': ':0', 10:00:39 INFO - 'HOME': '/home/cltbld', 10:00:39 INFO - 'LANG': 'en_US.UTF-8', 10:00:39 INFO - 'LANGUAGE': 'en_US:en', 10:00:39 INFO - 'LOGNAME': 'cltbld', 10:00:39 INFO - 'MAIL': '/var/mail/cltbld', 10:00:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:00:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:39 INFO - 'MOZ_NO_REMOTE': '1', 10:00:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:39 INFO - 'NO_EM_RESTART': '1', 10:00:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:00:39 INFO - 'PWD': '/builds/slave/test-pgo', 10:00:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:00:39 INFO - 'SHELL': '/bin/bash', 10:00:39 INFO - 'SHLVL': '1', 10:00:39 INFO - 'TERM': 'linux', 10:00:39 INFO - 'TMOUT': '86400', 10:00:39 INFO - 'USER': 'cltbld', 10:00:39 INFO - 'XDG_SESSION_COOKIE': 'd6a7e047ef025f707024e163000001c3-1450374298.281366-258946766', 10:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:39 INFO - '_': '/tools/buildbot/bin/python'} 10:00:39 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost'] with output_timeout 3600 10:00:39 INFO - mozversion INFO | application_buildid: 20151217072309 10:00:39 INFO - mozversion INFO | application_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 10:00:39 INFO - mozversion INFO | application_display_name: Nightly 10:00:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:39 INFO - mozversion INFO | application_name: Firefox 10:00:39 INFO - mozversion INFO | application_remotingname: firefox 10:00:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 10:00:39 INFO - mozversion INFO | application_vendor: Mozilla 10:00:39 INFO - mozversion INFO | application_version: 46.0a1 10:00:39 INFO - mozversion INFO | platform_buildid: 20151217072309 10:00:39 INFO - mozversion INFO | platform_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 10:00:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 10:00:39 INFO - mozversion INFO | platform_version: 46.0a1 10:00:39 INFO - 2015-12-17 10:00:39,739 DEBUG : using testdate: 1450375239 10:00:39 INFO - 2015-12-17 10:00:39,740 DEBUG : actual date: 1450375239 10:00:39 INFO - 2015-12-17 10:00:39,787 INFO : starting webserver on 'localhost:45362' 10:00:39 INFO - 2015-12-17 10:00:39,788 INFO : Starting test suite talos-linux64-ix-016.e 10:00:39 INFO - 2015-12-17 10:00:39,788 INFO : Starting test dromaeo_css 10:00:39 INFO - 2015-12-17 10:00:39,788 DEBUG : operating with platform_type : linux_ 10:00:39 INFO - 2015-12-17 10:00:39,789 INFO : Initialising browser for dromaeo_css test... 10:00:39 INFO - 2015-12-17 10:00:39,805 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptJXxDP/profile http://localhost:45362/getInfo.html 10:00:44 INFO - 2015-12-17 10:00:44,223 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:00:44 INFO - 2015-12-17 10:00:44,223 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:00:44 INFO - 2015-12-17 10:00:44,223 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 10:00:44 INFO - 2015-12-17 10:00:44,223 DEBUG : BROWSER_OUTPUT: __metrics 10:00:44 INFO - 2015-12-17 10:00:44,324 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:00:45 INFO - 2015-12-17 10:00:45,337 INFO : Browser initialized. 10:00:45 INFO - 2015-12-17 10:00:45,337 INFO : Running cycle 1/1 for dromaeo_css test... 10:00:45 INFO - 2015-12-17 10:00:45,338 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptJXxDP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:00:57 INFO - 2015-12-17 10:00:57,169 DEBUG : BROWSER_OUTPUT: RSS: Main: 150331392 10:00:57 INFO - 2015-12-17 10:00:57,170 DEBUG : BROWSER_OUTPUT: 10:01:46 INFO - 2015-12-17 10:01:46,791 DEBUG : BROWSER_OUTPUT: 1450375306789 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] 10:03:55 INFO - 2015-12-17 10:03:55,499 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45362/tests/dromaeo/cssquery-ext.html) 10:03:56 INFO - 2015-12-17 10:03:56,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 158908416 10:03:56 INFO - 2015-12-17 10:03:56,847 DEBUG : BROWSER_OUTPUT: 10:06:58 INFO - 2015-12-17 10:06:58,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-ext.html (next: http://localhost:45362/tests/dromaeo/cssquery-jquery.html) 10:06:59 INFO - 2015-12-17 10:06:59,413 DEBUG : BROWSER_OUTPUT: RSS: Main: 155922432 10:06:59 INFO - 2015-12-17 10:06:59,414 DEBUG : BROWSER_OUTPUT: 10:10:01 INFO - 2015-12-17 10:10:01,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45362/tests/dromaeo/cssquery-mootools.html) 10:10:01 INFO - 2015-12-17 10:10:01,666 DEBUG : BROWSER_OUTPUT: RSS: Main: 155582464 10:10:01 INFO - 2015-12-17 10:10:01,666 DEBUG : BROWSER_OUTPUT: 10:13:03 INFO - 2015-12-17 10:13:03,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45362/tests/dromaeo/cssquery-prototype.html) 10:13:03 INFO - 2015-12-17 10:13:03,936 DEBUG : BROWSER_OUTPUT: RSS: Main: 151404544 10:13:03 INFO - 2015-12-17 10:13:03,937 DEBUG : BROWSER_OUTPUT: 10:16:06 INFO - 2015-12-17 10:16:06,263 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45362/tests/dromaeo/cssquery-yui.html) 10:16:06 INFO - 2015-12-17 10:16:06,864 DEBUG : BROWSER_OUTPUT: RSS: Main: 153550848 10:16:06 INFO - 2015-12-17 10:16:06,864 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,085 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/dromaeo/cssquery-yui.html (next: http://localhost:45362/tests/dromaeo/cssquery-dojo.html) 10:19:09 INFO - 2015-12-17 10:19:09,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 155238400 10:19:09 INFO - 2015-12-17 10:19:09,335 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,336 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:19:09 INFO - 2015-12-17 10:19:09,336 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:19:09 INFO - 2015-12-17 10:19:09,336 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:19:09 INFO - 2015-12-17 10:19:09,336 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:19:09 INFO - 2015-12-17 10:19:09,337 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3208.919777005573;3209.3260415312784;3231.046841181851;3250.1712063423824;3256.299895522462;5153.510416510435;5306.946930530698;5380.435054319299;5393.622446428747;5412.404635490093;5236.633435659505;5247.47525247476;5405.405405405402;5495.422980587041;5496.835094947148;4885.511448855113;4943.555080042795;5005.324281222025;5034.043531728978;5040;4931.852044438672;4945.604351651878;5003.224500202475;5008.474110218429;5020.774065167058;4904.068227036852;4904.313396124546;4960.950390496085;4983.053219888209;5003.749812509378;4941.234108713155;4944.629152813517;4948.975255123737;4967.180415231483;4982.775775090094;4886.462489126193;4893.926591101136;4941.308216849644;4952.281919121707;4954.2568614707725;5053.595712343041;5164;5190.091733946544;5298.09932311508;5304.17785243284;5857.472827445507;5863;5875.529957603424;5899.469047785677;5907.1434641976675;5906.586538942232;5914.526837852962;5932;5932.644041357532;5935.673537955411;5328.147496400557;5339.5461385782255;5381.300430943991;5400.675959442446;5419.430959749248;5903.911441328383;5922;5939.316978547522;5961.493273071751;5995.190649262294;5425.593080518976;5766.971165144148;5774.82675519735;5818.854528636818;5820.88358232833;5581.358143813432;5756.222909907193;5804.361520232771;5827.504662103769;5859.882802343929;5679.148127780866;5748.39641837601;5750.942490575041;5752.482276595127;5758.280214973129;5687.829365119053;5707.4577915098;5750.6837123958585;5762.250907382014;5848;4409.867703968886;4420.093880754438;4430.269005614037;4438.467383913951;4463.642908567307;3947.348687466593;3952.8616498422416;3963.841446342114;3981.2634662587516;4007.458993035903;5367.570594352443;5443.2107344434835;5464.398916119224;5469.15228139639;5477.370102438191;5653.236813030188;5734.827955161351;5740.253767010346;5740.512056475246;5756.625819321731;5410.296661433988;5410.404855465896;5411.107167317427;5420.430854760272;5461.317335333083;4985.227289770091;4991.5258050485145;4996.07572599063;4996.300517927493;5042.243663450438;5679.062954612526;5754.971225143931;5766;5807.8548036299435;5890.734916928748;5694;5858.882822343615;5879;5888.116782482662;5890.204822348928;5925.851853703692;5938.73275702603;5952;5965.28416590012;5969.462748352669;5440.507541297525;5596.664200148004;5597.720113994365;5792.333881603501;5809.390014048464;5614.831555053355;5655.830325090254;5656.179853921076;5658.349289831722;5660.150977353265;5587.1339942309005;5677.68772717488;5785.190073389645;5800.854978625568;5835.620684655484;5665.3201615806365;5666.914996275142;5689.886202276014;5691.373948865538;5696.487316141567;5459.2902922619805;5521.641093328921;5607.411221821813;5608.242887210337;5616.831495055155;5579.163125531203;5595.384507704231;5667.121596152603;5734.483896449339;5776.104703770922;5592.7203639817035;5611.214429979887;5646.689432081111;5653.660780353192;5661.801836935688 10:19:09 INFO - 2015-12-17 10:19:09,338 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.2693060604851;591.4204080001598;594.8126340202837;609.135028259871;634.942855143037;35055;35611.4658280126;35699.2860142797;35737.2852542949;36138.45792313114;38345.808270958674;38811.61188388115;39108.60891391085;39388.40917386241;39416.80291598545;8553.957230213855;8683.913160868406;8693.652253909837;8702.912970870273;8721.389502734812;8365.832683346329;8466.534340611264;8478.872816907751;8486.575671216446;8503.659853605848;8459.873101903477;8580.570971451403;8623.913760862406;8634.222919937205;8634.740957771246;8521.44610600309;8524.232819046285;8571.10003449635;8580.485170889704;8613.138686131368;8419.915800841974;8556;8557.44376615518;8574.056853746082;8575.099614540446;35337.82331088354;35990;35990.82004589986;36001.91994240151;36149.09627259313;3229.4832826747843;3272.2146684795716;3610.963890361089;3880.2239552089695;4067.6542493888055;29124.708752912622;29256.561151582744;29839.403211935638;30315.393692126254;30869.228269293224;3718.665320121202;3740.083679498513;3766.7551609145316;3834.367329390675;3890.1830615570766;249041;249196;249518;250434;250580;54221.1866821998;55645.165322520195;55822;56015.439845601024;56072.719636401554;65275;66244.00633990494;66251.66874165599;66873;66991;16018.279177437043;16227.188640567925;16339.59151021234;16355;16392;12127.75744485123;12285.57000504978;12290.385480725929;12303.4463449145;12303.75392492163;19031.90484047571;19035.61928761445;19113;19176.232950681817;19342.8065719341;7243.348098671039;7425.1461081975185;7453.701851925862;7534.321911028033;7571.015767950131;693.5595896605691;753.7135888362387;913.164454524103;1056.3767377247307;1103.1285284625217;25999.220023399328;26976.32559186036;27065.594016089774;27444.862775686393;27449.45101098007;767.7773445700684;939.3236869453982;1046.9633562825265;1060.1095080132725;1110.4503270880966;14023.579292621238;14117.223552704494;14306.213158276394;14398.42406303736;14434.494792682206;317901;320985;322103;322984;323561;46843.76578117088;46969.765151174026;47173.46199035086;47258.52741472541;47386.05227895423;1318.4594316330258;1355.234292624664;1361.3669643615767;1364.1405914273944;1376.3187222324814;25413.110541130973;26344.8682756585;26584.091926578276;26639.200823975312;26893.86553067222;41637.167256654095;41867.16265674731;41943.580564193966;41978.79010604927;41981.79009105057;241441;242301;242528;242885;243126;29955.251118722208;31211.687883120878;31754;31971.36057278888;32299.677003229666;38006;39451.21097577975;40383.1923361537;40960.38559421671;41370.79314603408;34029.82985085158;34653.48019779655;34686.479702803976;34865;34876.12809679778;35617;35654.28691426209;36541;36631.45052824263;36735.08162295964;31521.52717709282;31618.209544761565;31635.36729265448;34180;34855.12862178466;28353.574696379157;29140.854295728386;29583.26041848971;29664.851675741484;29708.55437168401;23681.64477532804;24159.758402416453;24500.142495012587;24784.132555360477;25058.248252552454 10:19:09 INFO - 2015-12-17 10:19:09,342 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.46683641628596;363.892651667758;366.63336663336673;369.70977782440787;370.1708173690933;16644.750328745078;17186.91406542967;17755.822441775577;17993.550161245974;18589;10788.08301294389;12562.874371256285;16621.672965784768;16915.91542042288;16973.3210671573;4943.505649435055;5084.4661310562415;5109.9489005109845;5116.283720279145;5133.666311689747;5043.52086551777;5072.568831649314;5078.974605126988;5103.438621751604;5110.514501122406;4965.577925876305;5065.885505188852;5106.183010718286;5234.555062819665;5246.895062098756;5190.06578815813;5190.11767999441;5193.506616871391;5232.816851410202;5545.750441230154;5119.872003199913;5130.820421285274;5134.794608215667;5154.458781827929;5167.431582525919;12971.481140754453;15290.311935962796;18632;20243.190272389085;20293.18827246923;2015.0529251251887;2034.5015471209501;2054.1885955047824;2113.8837363944976;2290.828187885915;9323.953380233123;9445.480498572575;9497.240220782322;9616.23070154393;10341.896581034243;1812.854971602282;1842.7604411426562;1857.8978156201404;1886.3308418587683;1928.6721257386137;60879.69560152215;61586;62065;62531.37468625301;62873.68563157201;9989.400635961865;10462.319949203278;10585.364878107339;10659.307145035547;10697.090747286567;13767.655808604864;13858.792118118236;13882.167069975834;13894.096883702527;13894.305284735723;7507.21174276704;7763.961180194063;7781.221877812287;7800.960995194988;7827.608619568999;7422.925770742331;7427.368673662799;7445.739399121005;7475.551466912003;7629.160792312841;4454.309582014728;4496.865094047183;4508.0533088051225;4562.771861406916;4677.906441871211;1853.7126745354492;1894.6589613869635;1915.2147619475948;1930.3533316338903;1949.1131535151471;825.3479750996645;887.0907319997054;895.83427065993;899.1907283444821;1047.3244757131608;16451.095189764437;16724.41464548759;16937.745933811002;17098;17141.400050998403;934.1219253901447;946.1106559833735;949.0462085603924;986.7681094942683;1025.1747343388645;9335.299852511089;9516.38143520669;9559.713208603753;9579.664711735195;9637.277204209713;489134;492508;503631;549849;554662;79587.20412795914;80134.99999999884;80379.59810200911;81004.18995810085;81230;1133.580575187186;1138.4763009015946;1184.7867383870814;1191.606769765975;1197.4072833947077;14831.332590033258;14905.776413354026;14954.327055282321;15183.24083795828;15313.770293445385;35654;35840.641593584776;36124.638753612126;36819.81590092032;37163.44254836126;46173.30740038969;47585.286220706024;47732.76133619449;47898.76050619724;48654.27018594653;9225.261979041794;9579.089986451276;9601.367767479405;9625.56684949193;9723.56243969009;11360.715982100513;11370.431478426211;11419.771604568028;11502.022328102204;11518.193726439069;13784;13985.44058237659;14131.788023179866;14380.856191437952;14384.856151438771;14146.151230925765;14234.572962811133;14747;14818.62953426173;15297.46458873934;14499.130052196902;14559;14866.331015104563;14879.330430130454;15111.244437778287;9570.95214523923;9718.611255549982;9771;10150.086492215736;10177.592896284066;11947.462364193805;13871.4451421942;13967.371468284155;14046.719065618427;14266.71466570642 10:19:09 INFO - 2015-12-17 10:19:09,343 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1493.4847477620217;1727.6976529107408;1791.4446521578311;1793.300612761023;1799.190364336049;7396.4822462427655;7616.581088040156;7770.728024519136;7793.1817159198245;7905.486143400689;7642.961785191066;7649;7674.846503069935;7684.231576842313;7710;2094.9581008379823;2104.558042811004;2113.408245691209;2115.090511080235;2121.140937920145;2040.9591808163827;2069.0172168220124;2127.3086246969797;2160.384290477214;2166.11189412341;2159.5249045210107;2178.4118288062214;2192.978070219301;2200.185931205456;2200.933971980843;2157.255746767363;2167.3822960456273;2178.6078505868936;2183.727034120735;2192.265591027;2202.7246594175726;2263.6491343841567;2264.8754318512474;2266.6713326567556;2267.478479949621;7633.969414129133;7724.227577242287;7770.33952114071;7777.766666999999;7799.844003119906;4576.771161441948;4642.744649044301;4668.976655116737;4675.041616468616;4677.368555245066;3056.2359410147465;3093.288543634954;3093.3194697166587;3126.8592913318957;3135.749140068789;920.8802855628793;953.3517208298355;955.6559638530124;975.2636759246794;995.1342332170985;10570.15438764897;10636.095931845803;10693.19801014927;10706.732331691692;10721.946390267998;3954.031262340745;3958.3072962231495;3967.1867267210155;3984.8406063757707;3996.800159991989;8476.830463390697;8626.568671566522;8649.351298652626;8651.870221946676;8685.609147588373;4520.706154099973;4528.275475923836;4529.343245229424;4533.070720502289;4541.931871021938;4080.3879418087527;4119.485064366954;4120.587941205856;4183;4192.496900371914;3518.929621407609;3522.9119272018406;3535.840887160084;3546.556680414948;3567.23304489536;1051.5425789781316;1051.6266725312557;1072.0780129088978;1072.2387105155426;1086.2993369276771;994.418265314799;1004.9598016079275;1007.0734923870057;1118.4854966715402;1299.5061876486875;7251.274872512812;7253.927460725431;7255.600941948138;7271.091113610799;7298.233685463056;1092.8037183194022;1164.8078067119;1201.0751721174822;1208.293148508115;1210.903127749778;5426.484483974016;5615.719214039282;5621.690807005654;5621.959937411592;5634.746436410372;8974.775630609156;8997.955010224907;9342.90657093434;9371.812563748686;9390.483523406278;9068.45589264633;9164.90835091654;9173.541322933826;9227.584758685874;9259.583318750538;7070.858582828315;7201.3158749918675;7210.89183662235;7218.494705370678;7228.566286022856;7040.331168538981;7047.40097091753;7069.681864316015;7094.4679149062995;7108.537945033556;7978.521288722695;8128.715494957648;8165.591720414074;8201.30288925448;8210.794730131556;8312.459690120122;8397.70608028716;8465;8465.111163327761;8652.653893844175;5996.070609055429;6136.447719705248;6393.200849893763;6671.8999215010845;6792.524523283323;7200.315969982843;7224.422046236184;7244.710211591478;7247.094113235846;7280;6613.305602911625;6766.830829229309;6788.93211067883;6873.621950792754;6887.242403335729;6714.925611902072;6728.966355168192;6797.864042719217;6823.89764153548;6850.38346548813;6598.868022639424;6624.337566243337;6654.73381064752;6683.231428385798;6701.631410272482;6282.528810339334;6330.715117819617;6344.587601805868;6348.206474190726;6355.14205582259;6093.969530152321;6131.63210207389;6140.631562106288;6151.938480615316;6160.845978850564 10:19:09 INFO - 2015-12-17 10:19:09,345 DEBUG : BROWSER_OUTPUT: |4;prototype.html;327.0939497591671;327.8999905028965;335.2222843004231;336.0891982726811;342.7566427836235;6342.016987366954;6358.8728225435525;6444.872147374214;6459.935400645993;6527.379898909596;6275.4038366355235;6279.748810047593;6303.117563541108;6321.968390158043;6347.396997285262;4783.71297722136;4785.258284965827;4788.473267940523;4792.257200133985;4792.305115758213;4799.112164249607;4806.9038619227595;4807.543283388072;4810.110129626013;4811.037792441526;4649.279361698942;4705.811767529295;4734.076855013273;4741.644376671747;4749.62003039756;4676.3453116563705;4692.826793301675;4717.386739723848;4731.243001119821;4741.383620129395;4076.2866498362964;4077.1845630873945;4079.857639860844;4080.489938757655;4083.571225021389;5992.191054207713;6013.488853447462;6035.7283922223605;6051.667158306292;6051.667158306292;5414.35027796667;5516.420775818561;5519.503244707996;5526.392096869341;5541.390447050821;6734.696938637773;6742.089817874573;6745.730170793162;6747.696353664097;6758.256591774901;4504.054148628792;4524.687840526276;4533.274676051816;4537.546245375469;4553.134904368193;6848.486363522756;6910.48171387143;6945.062416573748;6961.37347638715;6991.161060672752;4675.275332323496;4702.811887524529;4708.623310135181;4712.69367491112;4713.905721885543;4764.213904705684;4777.928331075037;4778.593819525309;4779.641526885497;4780.928286075712;5519.31008623922;5532.446755324516;5534.640248383923;5557.694326811983;5566.916496252559;6622.2715501294815;6743.022261772016;6760.830979225559;6768.187817461838;6770.187577490733;1624.9512514624344;1638.9262483188286;1648.4807285704965;1650.7028734827845;1653.545275049371;1114.9108071354271;1115.275071203724;1118.4519585403257;1122.550979608147;1132.6205721083365;6333.145025421509;6407.711652975628;6429.292777794439;6432.35676432353;6433.774817881352;6870.484713646497;6913.792586222421;6922.376986071277;6923.238443771181;6929.618870962045;6283.622982620966;6343;6389.9680501597195;6411.326810684905;6421.3578642136345;3766.133789228438;3807.9718476011876;3811.5807261201244;3849.1147036181833;3850.2684489947383;7161.104861892263;7174.964125179341;7181.712731490787;7205.7838264852135;7209.927900721031;7100.076989991371;7201.819954501179;7237.855242895218;7249.948757430143;7266.564006159648;7152.785416437515;7189.245129261352;7203.747868824566;7204.423646108299;7232.566046037149;7008.894866576903;7011.1586609607175;7013.894791578028;7036.859262814817;7055.118110236221;6826.351496607815;6941.167059952636;6942.548734332252;6965.407940325129;6985.615791131537;7170.282971702996;7183.784486465414;7190.568565886064;7192.928070719226;7210;4625.537446255482;4642.883927901829;4682.063587282489;4688.765561721969;4701.388819453449;6896.7586134485055;6913.446924246149;7051.6474176292;7074.398676112588;7091.184513780981;6842;6845.691943862637;6847.349501797321;6882.7591034313555;6883.105196324646;6786.253512113763;6816;6816.591004539743;6822.317768223138;6823.420009299265;6585.539012269287;6705.966470167618;6716.764913228014;6729.1252137221845;6730;6873.312668733087;6893.586384816927;6899.896501552581;6901.549949767961;6906.102206713095;6694.129763130762;6698.698558564974;6715.160604924385;6720.764773233109;6722.428593569602 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: |5;yui.html;730.120205152791;771.3289438188773;868.5223127279999;877.3069275272535;890.0343127706439;12102.15284930054;12549.1215614907;12555.497780088786;12592.559260425887;12606.306653134074;14176.220307883063;14579.271036448188;14590.854091459083;14601.634959126028;14623.78064329036;814.7759366174305;827.4580150001728;827.9917200827974;829.3489610655637;830.1159265382348;804.3444592656995;814.4990830639144;830.7050996896105;831.6174559702514;833.6748668019477;859.6647307550061;862.2369203255101;862.6937437209795;869.5087275689216;870.5908223135148;864.3819668936709;864.5201912938317;867.418829384314;869.1178453869328;871.8735783311416;641.4771960851938;642.2838535033451;642.6369101457708;644.262319644005;644.2655372874926;12294.754104917942;12422.316772577504;12426.316552589604;12486.625401237978;12514.249145051326;11602.419879006016;11649.728302980837;11813.291202527877;11995.880041199563;12006.859348361892;9966.89684097002;10042.598296068149;10208.89791102087;10381.79236415275;11066.225364224507;3441.9827900860587;3449.499822525745;3493.877714279988;3507.754457187998;3624.2207925295925;47505;47775;47873.76063119697;47947.76026119882;48338.27492587614;10161;10217.540210690388;10247.077763001216;10271.281010329205;10306.278560500694;17817.46547603574;17988.820111798977;18010.549736256435;18058.90970545165;18065.7290140648;6875.896861547081;7000.7549735759;7014.508984371147;7045.260247674023;7070.575765454089;3849.480320156799;3897.4348719435525;3970.1662650843646;3983.24318379507;3990.960090399117;2977.76177905767;2992.3267264865667;2996.595459612968;3011.48804703201;3013.4575776360466;538.1604696673203;549.3243310727827;550.5375484592881;551.5339538090308;562.190445758107;490.784055015799;557.4509108528097;601.2814686449738;637.0921436952325;643.6974621927686;8047.798805029921;8352.415330926777;8399.664013439517;8647.221750042401;8697.434666746642;633.6894921488438;641.1760887259849;644.3234603666132;657.7383310880392;669.3774789445765;3858.1897801462005;3874.0314921269683;3919.5884432134217;3932.2332145231976;4037.0916543778003;57272.14091788627;57394.426055738906;65163;66142.33857661458;66218.66890665516;49127.50872491301;49405.50594494081;49653.25520117201;51000.48999510032;51063.489365106616;46433.767831161305;46454.354564543275;46786;46948;47459.5254047462;8248.711295104642;8293.460925039853;8340.124286949784;8478.872816907628;8515.31877449815;34432.65567344295;34511.30977380388;34940.650593494756;35037;35296.647033529334;40935.1812963745;41269.38095928504;42841.78579107209;45082.098358032;45185.09629807451;9879.056281526631;10180.338278011906;10200.132988696045;10326.741831454274;10706.785864282827;11355.772884542428;11378.886211137782;11428.71428214268;11592;11639.76720465603;10493.737656558647;10646.73383165427;11084.889151108386;11214;11427.942860285646;10732.51703673321;10812.67561973142;10821.56713731442;10829;11087.113030957666;10601.41692206936;10625.468726563795;10672.946635266773;10727.839082413926;10868.565257389615;8848.513331766815;8895.510746908982;8960.865587016331;9308.929473110407;9471.47906865129;8615.396922215636;8836.690715824916;9200.383680650351;9299.953500232456;9443.386179898285 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,321 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376349335__endTimestamp 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:19:09 INFO - 2015-12-17 10:19:09,346 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5369.31 Median:5592.72 stddev:591.02 (10.6%) stddev-sans-first:568.01 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: Values: 3208.9 3209.3 3231.0 3250.2 3256.3 5153.5 5306.9 5380.4 5393.6 5412.4 5236.6 5247.5 5405.4 5495.4 5496.8 4885.5 4943.6 5005.3 5034.0 5040.0 4931.9 4945.6 5003.2 5008.5 5020.8 4904.1 4904.3 4961.0 4983.1 5003.7 4941.2 4944.6 4949.0 4967.2 4982.8 4886.5 4893.9 4941.3 4952.3 4954.3 5053.6 5164.0 5190.1 5298.1 5304.2 5857.5 5863.0 5875.5 5899.5 5907.1 5906.6 5914.5 5932.0 5932.6 5935.7 5328.1 5339.5 5381.3 5400.7 5419.4 5903.9 5922.0 5939.3 5961.5 5995.2 5425.6 5767.0 5774.8 5818.9 5820.9 5581.4 5756.2 5804.4 5827.5 5859.9 5679.1 5748.4 5750.9 5752.5 5758.3 5687.8 5707.5 5750.7 5762.3 5848.0 4409.9 4420.1 4430.3 4438.5 4463.6 3947.3 3952.9 3963.8 3981.3 4007.5 5367.6 5443.2 5464.4 5469.2 5477.4 5653.2 5734.8 5740.3 5740.5 5756.6 5410.3 5410.4 5411.1 5420.4 5461.3 4985.2 4991.5 4996.1 4996.3 5042.2 5679.1 5755.0 5766.0 5807.9 5890.7 5694.0 5858.9 5879.0 5888.1 5890.2 5925.9 5938.7 5952.0 5965.3 5969.5 5440.5 5596.7 5597.7 5792.3 5809.4 5614.8 5655.8 5656.2 5658.3 5660.2 5587.1 5677.7 5785.2 5800.9 5835.6 5665.3 5666.9 5689.9 5691.4 5696.5 5459.3 5521.6 5607.4 5608.2 5616.8 5579.2 5595.4 5667.1 5734.5 5776.1 5592.7 5611.2 5646.7 5653.7 5661.8 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43629.53 Median:35635.64 stddev:71484.97 (200.6%) stddev-sans-first:71612.46 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: Values: 589.3 591.4 594.8 609.1 634.9 35055.0 35611.5 35699.3 35737.3 36138.5 38345.8 38811.6 39108.6 39388.4 39416.8 8554.0 8683.9 8693.7 8702.9 8721.4 8365.8 8466.5 8478.9 8486.6 8503.7 8459.9 8580.6 8623.9 8634.2 8634.7 8521.4 8524.2 8571.1 8580.5 8613.1 8419.9 8556.0 8557.4 8574.1 8575.1 35337.8 35990.0 35990.8 36001.9 36149.1 3229.5 3272.2 3611.0 3880.2 4067.7 29124.7 29256.6 29839.4 30315.4 30869.2 3718.7 3740.1 3766.8 3834.4 3890.2 249041.0 249196.0 249518.0 250434.0 250580.0 54221.2 55645.2 55822.0 56015.4 56072.7 65275.0 66244.0 66251.7 66873.0 66991.0 16018.3 16227.2 16339.6 16355.0 16392.0 12127.8 12285.6 12290.4 12303.4 12303.8 19031.9 19035.6 19113.0 19176.2 19342.8 7243.3 7425.1 7453.7 7534.3 7571.0 693.6 753.7 913.2 1056.4 1103.1 25999.2 26976.3 27065.6 27444.9 27449.5 767.8 939.3 1047.0 1060.1 1110.5 14023.6 14117.2 14306.2 14398.4 14434.5 317901.0 320985.0 322103.0 322984.0 323561.0 46843.8 46969.8 47173.5 47258.5 47386.1 1318.5 1355.2 1361.4 1364.1 1376.3 25413.1 26344.9 26584.1 26639.2 26893.9 41637.2 41867.2 41943.6 41978.8 41981.8 241441.0 242301.0 242528.0 242885.0 243126.0 29955.3 31211.7 31754.0 31971.4 32299.7 38006.0 39451.2 40383.2 40960.4 41370.8 34029.8 34653.5 34686.5 34865.0 34876.1 35617.0 35654.3 36541.0 36631.5 36735.1 31521.5 31618.2 31635.4 34180.0 34855.1 28353.6 29140.9 29583.3 29664.9 29708.6 23681.6 24159.8 24500.1 24784.1 25058.2 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,347 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28123.33 Median:18594.76 stddev:84857.79 (456.4%) stddev-sans-first:85070.24 10:19:09 INFO - 2015-12-17 10:19:09,348 DEBUG : BROWSER_OUTPUT: Values: 361.5 363.9 366.6 369.7 370.2 16644.8 17186.9 17755.8 17993.6 18589.0 10788.1 12562.9 16621.7 16915.9 16973.3 4943.5 5084.5 5109.9 5116.3 5133.7 5043.5 5072.6 5079.0 5103.4 5110.5 4965.6 5065.9 5106.2 5234.6 5246.9 5190.1 5190.1 5193.5 5232.8 5545.8 5119.9 5130.8 5134.8 5154.5 5167.4 12971.5 15290.3 18632.0 20243.2 20293.2 2015.1 2034.5 2054.2 2113.9 2290.8 9324.0 9445.5 9497.2 9616.2 10341.9 1812.9 1842.8 1857.9 1886.3 1928.7 60879.7 61586.0 62065.0 62531.4 62873.7 9989.4 10462.3 10585.4 10659.3 10697.1 13767.7 13858.8 13882.2 13894.1 13894.3 7507.2 7764.0 7781.2 7801.0 7827.6 7422.9 7427.4 7445.7 7475.6 7629.2 4454.3 4496.9 4508.1 4562.8 4677.9 1853.7 1894.7 1915.2 1930.4 1949.1 825.3 887.1 895.8 899.2 1047.3 16451.1 16724.4 16937.7 17098.0 17141.4 934.1 946.1 949.0 986.8 1025.2 9335.3 9516.4 9559.7 9579.7 9637.3 489134.0 492508.0 503631.0 549849.0 554662.0 79587.2 80135.0 80379.6 81004.2 81230.0 1133.6 1138.5 1184.8 1191.6 1197.4 14831.3 14905.8 14954.3 15183.2 15313.8 35654.0 35840.6 36124.6 36819.8 37163.4 46173.3 47585.3 47732.8 47898.8 48654.3 9225.3 9579.1 9601.4 9625.6 9723.6 11360.7 11370.4 11419.8 11502.0 11518.2 13784.0 13985.4 14131.8 14380.9 14384.9 14146.2 14234.6 14747.0 14818.6 15297.5 14499.1 14559.0 14866.3 14879.3 15111.2 9571.0 9718.6 9771.0 10150.1 10177.6 11947.5 13871.4 13967.4 14046.7 14266.7 10:19:09 INFO - 2015-12-17 10:19:09,348 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,348 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5322.08 Median:6221.69 stddev:2770.90 (44.5%) stddev-sans-first:2763.73 10:19:09 INFO - 2015-12-17 10:19:09,349 DEBUG : BROWSER_OUTPUT: Values: 1493.5 1727.7 1791.4 1793.3 1799.2 7396.5 7616.6 7770.7 7793.2 7905.5 7643.0 7649.0 7674.8 7684.2 7710.0 2095.0 2104.6 2113.4 2115.1 2121.1 2041.0 2069.0 2127.3 2160.4 2166.1 2159.5 2178.4 2193.0 2200.2 2200.9 2157.3 2167.4 2178.6 2183.7 2192.3 2202.7 2263.6 2264.9 2266.7 2267.5 7634.0 7724.2 7770.3 7777.8 7799.8 4576.8 4642.7 4669.0 4675.0 4677.4 3056.2 3093.3 3093.3 3126.9 3135.7 920.9 953.4 955.7 975.3 995.1 10570.2 10636.1 10693.2 10706.7 10721.9 3954.0 3958.3 3967.2 3984.8 3996.8 8476.8 8626.6 8649.4 8651.9 8685.6 4520.7 4528.3 4529.3 4533.1 4541.9 4080.4 4119.5 4120.6 4183.0 4192.5 3518.9 3522.9 3535.8 3546.6 3567.2 1051.5 1051.6 1072.1 1072.2 1086.3 994.4 1005.0 1007.1 1118.5 1299.5 7251.3 7253.9 7255.6 7271.1 7298.2 1092.8 1164.8 1201.1 1208.3 1210.9 5426.5 5615.7 5621.7 5622.0 5634.7 8974.8 8998.0 9342.9 9371.8 9390.5 9068.5 9164.9 9173.5 9227.6 9259.6 7070.9 7201.3 7210.9 7218.5 7228.6 7040.3 7047.4 7069.7 7094.5 7108.5 7978.5 8128.7 8165.6 8201.3 8210.8 8312.5 8397.7 8465.0 8465.1 8652.7 5996.1 6136.4 6393.2 6671.9 6792.5 7200.3 7224.4 7244.7 7247.1 7280.0 6613.3 6766.8 6788.9 6873.6 6887.2 6714.9 6729.0 6797.9 6823.9 6850.4 6598.9 6624.3 6654.7 6683.2 6701.6 6282.5 6330.7 6344.6 6348.2 6355.1 6094.0 6131.6 6140.6 6151.9 6160.8 10:19:09 INFO - 2015-12-17 10:19:09,349 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,349 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5619.99 Median:6398.84 stddev:1730.20 (27.0%) stddev-sans-first:1688.83 10:19:09 INFO - 2015-12-17 10:19:09,349 DEBUG : BROWSER_OUTPUT: Values: 327.1 327.9 335.2 336.1 342.8 6342.0 6358.9 6444.9 6459.9 6527.4 6275.4 6279.7 6303.1 6322.0 6347.4 4783.7 4785.3 4788.5 4792.3 4792.3 4799.1 4806.9 4807.5 4810.1 4811.0 4649.3 4705.8 4734.1 4741.6 4749.6 4676.3 4692.8 4717.4 4731.2 4741.4 4076.3 4077.2 4079.9 4080.5 4083.6 5992.2 6013.5 6035.7 6051.7 6051.7 5414.4 5516.4 5519.5 5526.4 5541.4 6734.7 6742.1 6745.7 6747.7 6758.3 4504.1 4524.7 4533.3 4537.5 4553.1 6848.5 6910.5 6945.1 6961.4 6991.2 4675.3 4702.8 4708.6 4712.7 4713.9 4764.2 4777.9 4778.6 4779.6 4780.9 5519.3 5532.4 5534.6 5557.7 5566.9 6622.3 6743.0 6760.8 6768.2 6770.2 1625.0 1638.9 1648.5 1650.7 1653.5 1114.9 1115.3 1118.5 1122.6 1132.6 6333.1 6407.7 6429.3 6432.4 6433.8 6870.5 6913.8 6922.4 6923.2 6929.6 6283.6 6343.0 6390.0 6411.3 6421.4 3766.1 3808.0 3811.6 3849.1 3850.3 7161.1 7175.0 7181.7 7205.8 7209.9 7100.1 7201.8 7237.9 7249.9 7266.6 7152.8 7189.2 7203.7 7204.4 7232.6 7008.9 7011.2 7013.9 7036.9 7055.1 6826.4 6941.2 6942.5 6965.4 6985.6 7170.3 7183.8 7190.6 7192.9 7210.0 4625.5 4642.9 4682.1 4688.8 4701.4 6896.8 6913.4 7051.6 7074.4 7091.2 6842.0 6845.7 6847.3 6882.8 6883.1 6786.3 6816.0 6816.6 6822.3 6823.4 6585.5 6706.0 6716.8 6729.1 6730.0 6873.3 6893.6 6899.9 6901.5 6906.1 6694.1 6698.7 6715.2 6720.8 6722.4 10:19:09 INFO - 2015-12-17 10:19:09,349 DEBUG : BROWSER_OUTPUT: 10:19:09 INFO - 2015-12-17 10:19:09,351 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13670.82 Median:46867.00 stddev:16246.64 (34.7%) stddev-sans-first:16263.16 10:19:09 INFO - 2015-12-17 10:19:09,351 DEBUG : BROWSER_OUTPUT: Values: 730.1 771.3 868.5 877.3 890.0 12102.2 12549.1 12555.5 12592.6 12606.3 14176.2 14579.3 14590.9 14601.6 14623.8 814.8 827.5 828.0 829.3 830.1 804.3 814.5 830.7 831.6 833.7 859.7 862.2 862.7 869.5 870.6 864.4 864.5 867.4 869.1 871.9 641.5 642.3 642.6 644.3 644.3 12294.8 12422.3 12426.3 12486.6 12514.2 11602.4 11649.7 11813.3 11995.9 12006.9 9966.9 10042.6 10208.9 10381.8 11066.2 3442.0 3449.5 3493.9 3507.8 3624.2 47505.0 47775.0 47873.8 47947.8 48338.3 10161.0 10217.5 10247.1 10271.3 10306.3 17817.5 17988.8 18010.5 18058.9 18065.7 6875.9 7000.8 7014.5 7045.3 7070.6 3849.5 3897.4 3970.2 3983.2 3991.0 2977.8 2992.3 2996.6 3011.5 3013.5 538.2 549.3 550.5 551.5 562.2 490.8 557.5 601.3 637.1 643.7 8047.8 8352.4 8399.7 8647.2 8697.4 633.7 641.2 644.3 657.7 669.4 3858.2 3874.0 3919.6 3932.2 4037.1 57272.1 57394.4 65163.0 66142.3 66218.7 49127.5 49405.5 49653.3 51000.5 51063.5 46433.8 46454.4 46786.0 46948.0 47459.5 8248.7 8293.5 8340.1 8478.9 8515.3 34432.7 34511.3 34940.7 35037.0 35296.6 40935.2 41269.4 42841.8 45082.1 45185.1 9879.1 10180.3 10200.1 10326.7 10706.8 11355.8 11378.9 11428.7 11592.0 11639.8 10493.7 10646.7 11084.9 11214.0 11427.9 10732.5 10812.7 10821.6 10829.0 11087.1 10601.4 10625.5 10672.9 10727.8 10868.6 8848.5 8895.5 8960.9 9308.9 9471.5 8615.4 8836.7 9200.4 9300.0 9443.4 10:19:09 INFO - 2015-12-17 10:19:09,351 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:19:09 INFO - 2015-12-17 10:19:09,351 DEBUG : BROWSER_OUTPUT: 10:19:10 INFO - 2015-12-17 10:19:10,045 INFO : Browser exited with error code: 0 10:19:10 INFO - 2015-12-17 10:19:10,069 INFO : Completed test dromaeo_css (00:18:30) 10:19:10 INFO - 2015-12-17 10:19:10,069 INFO : Starting test kraken 10:19:10 INFO - 2015-12-17 10:19:10,069 DEBUG : operating with platform_type : linux_ 10:19:10 INFO - 2015-12-17 10:19:10,069 INFO : Initialising browser for kraken test... 10:19:10 INFO - 2015-12-17 10:19:10,075 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbEFwlh/profile http://localhost:45362/getInfo.html 10:19:14 INFO - 2015-12-17 10:19:14,342 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:19:14 INFO - 2015-12-17 10:19:14,342 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:19:14 INFO - 2015-12-17 10:19:14,342 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 10:19:14 INFO - 2015-12-17 10:19:14,342 DEBUG : BROWSER_OUTPUT: __metrics 10:19:14 INFO - 2015-12-17 10:19:14,450 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:19:15 INFO - 2015-12-17 10:19:15,281 INFO : Browser initialized. 10:19:15 INFO - 2015-12-17 10:19:15,281 INFO : Running cycle 1/1 for kraken test... 10:19:15 INFO - 2015-12-17 10:19:15,282 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbEFwlh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:19:27 INFO - 2015-12-17 10:19:27,018 DEBUG : BROWSER_OUTPUT: RSS: Main: 151007232 10:19:27 INFO - 2015-12-17 10:19:27,018 DEBUG : BROWSER_OUTPUT: 10:19:29 INFO - 2015-12-17 10:19:29,493 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45362/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:30 INFO - 2015-12-17 10:19:30,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 150990848 10:19:30 INFO - 2015-12-17 10:19:30,095 DEBUG : BROWSER_OUTPUT: 10:19:32 INFO - 2015-12-17 10:19:32,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45362/tests/kraken/driver.html?testName=audio-dft) 10:19:33 INFO - 2015-12-17 10:19:33,323 DEBUG : BROWSER_OUTPUT: RSS: Main: 151093248 10:19:33 INFO - 2015-12-17 10:19:33,323 DEBUG : BROWSER_OUTPUT: 10:19:36 INFO - 2015-12-17 10:19:36,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45362/tests/kraken/driver.html?testName=audio-fft) 10:19:36 INFO - 2015-12-17 10:19:36,755 DEBUG : BROWSER_OUTPUT: RSS: Main: 146358272 10:19:36 INFO - 2015-12-17 10:19:36,756 DEBUG : BROWSER_OUTPUT: 10:19:38 INFO - 2015-12-17 10:19:38,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45362/tests/kraken/driver.html?testName=audio-oscillator) 10:19:39 INFO - 2015-12-17 10:19:39,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 145850368 10:19:39 INFO - 2015-12-17 10:19:39,354 DEBUG : BROWSER_OUTPUT: 10:19:41 INFO - 2015-12-17 10:19:41,560 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45362/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:42 INFO - 2015-12-17 10:19:42,142 DEBUG : BROWSER_OUTPUT: RSS: Main: 147349504 10:19:42 INFO - 2015-12-17 10:19:42,142 DEBUG : BROWSER_OUTPUT: 10:19:45 INFO - 2015-12-17 10:19:45,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45362/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:45 INFO - 2015-12-17 10:19:45,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 147058688 10:19:45 INFO - 2015-12-17 10:19:45,664 DEBUG : BROWSER_OUTPUT: 10:19:49 INFO - 2015-12-17 10:19:49,011 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45362/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:49 INFO - 2015-12-17 10:19:49,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 148250624 10:19:49 INFO - 2015-12-17 10:19:49,605 DEBUG : BROWSER_OUTPUT: 10:19:52 INFO - 2015-12-17 10:19:52,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45362/tests/kraken/driver.html?testName=json-parse-financial) 10:19:53 INFO - 2015-12-17 10:19:53,010 DEBUG : BROWSER_OUTPUT: RSS: Main: 148766720 10:19:53 INFO - 2015-12-17 10:19:53,010 DEBUG : BROWSER_OUTPUT: 10:19:54 INFO - 2015-12-17 10:19:54,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45362/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:55 INFO - 2015-12-17 10:19:55,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 149225472 10:19:55 INFO - 2015-12-17 10:19:55,186 DEBUG : BROWSER_OUTPUT: 10:19:56 INFO - 2015-12-17 10:19:56,981 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:57 INFO - 2015-12-17 10:19:57,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 148549632 10:19:57 INFO - 2015-12-17 10:19:57,603 DEBUG : BROWSER_OUTPUT: 10:19:59 INFO - 2015-12-17 10:19:59,699 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:20:00 INFO - 2015-12-17 10:20:00,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 148697088 10:20:00 INFO - 2015-12-17 10:20:00,293 DEBUG : BROWSER_OUTPUT: 10:20:02 INFO - 2015-12-17 10:20:02,793 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:20:03 INFO - 2015-12-17 10:20:03,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 149737472 10:20:03 INFO - 2015-12-17 10:20:03,383 DEBUG : BROWSER_OUTPUT: 10:20:05 INFO - 2015-12-17 10:20:05,980 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:20:06 INFO - 2015-12-17 10:20:06,574 DEBUG : BROWSER_OUTPUT: RSS: Main: 149757952 10:20:06 INFO - 2015-12-17 10:20:06,574 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,263 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45362/tests/kraken/driver.html?testName=ai-astar) 10:20:08 INFO - 2015-12-17 10:20:08,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 148639744 10:20:08 INFO - 2015-12-17 10:20:08,509 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,511 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:20:08 INFO - 2015-12-17 10:20:08,511 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:20:08 INFO - 2015-12-17 10:20:08,511 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:20:08 INFO - 2015-12-17 10:20:08,511 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:20:08 INFO - 2015-12-17 10:20:08,512 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;107;110;110;113;124;110;110;110;112 10:20:08 INFO - 2015-12-17 10:20:08,512 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;132;146;162;124;149;151;136;163;126 10:20:08 INFO - 2015-12-17 10:20:08,512 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;162;162;162;166;163;164;172;161 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;74;72;72;75;72;166;71;72;72 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;136;93;94;93;139;94;94;135 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;111;109;110;109;110 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;146;146;148;148;148;147;146 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;101;101;96;95;96;97;96;97;96 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;60;60;61;59;63;61;63;64;58 10:20:08 INFO - 2015-12-17 10:20:08,514 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;72;68;69;68;68;69;68;69;68 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;84;94;85;93;85;85;85;94;87 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;121;131;119;133;126;126;116;127;135 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;152;156;146;149;154;150;153;156;138 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;74;66;67;72;66;68;70;67;67;66 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,741 10:20:08 INFO - 2015-12-17 10:20:08,515 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:20:08 INFO - 2015-12-17 10:20:08,516 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376408510__endTimestamp 10:20:08 INFO - 2015-12-17 10:20:08,516 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,519 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:20:08 INFO - 2015-12-17 10:20:08,519 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:20:08 INFO - 2015-12-17 10:20:08,519 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.00 Median:111.00 stddev:4.64 (4.2%) stddev-sans-first:4.87 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: Values: 114.0 107.0 110.0 110.0 113.0 124.0 110.0 110.0 110.0 112.0 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.00 Median:150.00 stddev:13.92 (9.3%) stddev-sans-first:14.53 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: Values: 151.0 132.0 146.0 162.0 124.0 149.0 151.0 136.0 163.0 126.0 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,520 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:3.22 (2.0%) stddev-sans-first:3.35 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 162.0 162.0 162.0 166.0 163.0 164.0 172.0 161.0 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.70 Median:72.00 stddev:29.65 (41.2%) stddev-sans-first:31.19 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: Values: 71.0 74.0 72.0 72.0 75.0 72.0 166.0 71.0 72.0 72.0 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:93.50 stddev:20.84 (22.3%) stddev-sans-first:21.53 10:20:08 INFO - 2015-12-17 10:20:08,521 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 136.0 93.0 94.0 93.0 139.0 94.0 94.0 135.0 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.90 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 111.0 109.0 110.0 109.0 110.0 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 146.0 146.0 148.0 148.0 148.0 147.0 146.0 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,522 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.10 Median:96.00 stddev:2.13 (2.2%) stddev-sans-first:2.22 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: Values: 96.0 101.0 101.0 96.0 95.0 96.0 97.0 96.0 97.0 96.0 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.10 Median:61.50 stddev:1.91 (3.1%) stddev-sans-first:2.00 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: Values: 62.0 60.0 60.0 61.0 59.0 63.0 61.0 63.0 64.0 58.0 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.50 stddev:1.35 (2.0%) stddev-sans-first:1.30 10:20:08 INFO - 2015-12-17 10:20:08,523 DEBUG : BROWSER_OUTPUT: Values: 67.0 72.0 68.0 69.0 68.0 68.0 69.0 68.0 69.0 68.0 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:86.00 stddev:4.38 (5.1%) stddev-sans-first:4.33 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 94.0 85.0 93.0 85.0 85.0 85.0 94.0 87.0 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:128.00 stddev:6.13 (4.8%) stddev-sans-first:6.42 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: Values: 129.0 121.0 131.0 119.0 133.0 126.0 126.0 116.0 127.0 135.0 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,524 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.30 Median:152.50 stddev:6.48 (4.3%) stddev-sans-first:5.70 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: Values: 139.0 152.0 156.0 146.0 149.0 154.0 150.0 153.0 156.0 138.0 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:2.79 (4.1%) stddev-sans-first:2.06 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: Values: 74.0 66.0 67.0 72.0 66.0 68.0 70.0 67.0 67.0 66.0 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:20:08 INFO - 2015-12-17 10:20:08,525 DEBUG : BROWSER_OUTPUT: 10:20:09 INFO - 2015-12-17 10:20:09,369 INFO : Browser exited with error code: 0 10:20:09 INFO - 2015-12-17 10:20:09,420 INFO : Completed test kraken (00:00:59) 10:20:09 INFO - 2015-12-17 10:20:09,420 INFO : Starting test v8_7 10:20:09 INFO - 2015-12-17 10:20:09,420 DEBUG : operating with platform_type : linux_ 10:20:09 INFO - 2015-12-17 10:20:09,421 INFO : Initialising browser for v8_7 test... 10:20:09 INFO - 2015-12-17 10:20:09,427 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDj3_ob/profile http://localhost:45362/getInfo.html 10:20:13 INFO - 2015-12-17 10:20:13,797 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:20:13 INFO - 2015-12-17 10:20:13,798 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:20:13 INFO - 2015-12-17 10:20:13,798 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 10:20:13 INFO - 2015-12-17 10:20:13,798 DEBUG : BROWSER_OUTPUT: __metrics 10:20:13 INFO - 2015-12-17 10:20:13,894 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 10:20:14 INFO - 2015-12-17 10:20:14,797 INFO : Browser initialized. 10:20:14 INFO - 2015-12-17 10:20:14,797 INFO : Running cycle 1/1 for v8_7 test... 10:20:14 INFO - 2015-12-17 10:20:14,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDj3_ob/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:20:26 INFO - 2015-12-17 10:20:26,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 150593536 10:20:26 INFO - 2015-12-17 10:20:26,637 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45362/tests/v8_7/run.html (next: http://localhost:45362/tests/v8_7/run.html) 10:20:48 INFO - 2015-12-17 10:20:48,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 145707008 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |0;Richards;167.000668002672 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.46280067126608 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.3476310625175 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |5;Earley;695.4102920723227 10:20:48 INFO - 2015-12-17 10:20:48,307 DEBUG : BROWSER_OUTPUT: |6;Boyer;12256.09756097561 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: |7;RegExp;25225 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: |8;Splay;704.7685834502104 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: __startTimestamp1450376448303__endTimestamp 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.00 Median:167.00 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: Values: 167.0 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.46 Median:186.46 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,308 DEBUG : BROWSER_OUTPUT: Values: 186.5 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.35 Median:280.35 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: Values: 280.3 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: Values: 5208.3 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: Values: 1062.7 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:695.41 Median:695.41 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: Values: 695.4 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12256.10 Median:12256.10 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,309 DEBUG : BROWSER_OUTPUT: Values: 12256.1 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25225.00 Median:25225.00 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: Values: 25225.0 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:704.77 Median:704.77 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: Values: 704.8 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: Values: 4854.4 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:20:48 INFO - 2015-12-17 10:20:48,310 DEBUG : BROWSER_OUTPUT: 10:20:49 INFO - 2015-12-17 10:20:49,105 INFO : Browser exited with error code: 0 10:20:49 INFO - 2015-12-17 10:20:49,120 INFO : Completed test v8_7 (00:00:39) 10:20:49 INFO - 2015-12-17 10:20:49,346 INFO : Completed test suite (00:20:09) 10:20:49 INFO - 2015-12-17 10:20:49,346 DEBUG : Working with test: dromaeo_css 10:20:49 INFO - 2015-12-17 10:20:49,347 DEBUG : Generating results file: dromaeo_css 10:20:49 INFO - 2015-12-17 10:20:49,348 DEBUG : Working with test: kraken 10:20:49 INFO - 2015-12-17 10:20:49,348 DEBUG : Generating results file: kraken 10:20:49 INFO - 2015-12-17 10:20:49,348 INFO : javascript benchmark 10:20:49 INFO - 2015-12-17 10:20:49,348 DEBUG : Working with test: v8_7 10:20:49 INFO - 2015-12-17 10:20:49,348 DEBUG : Generating results file: v8_7 10:20:49 INFO - 2015-12-17 10:20:49,349 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:20:49 INFO - 2015-12-17 10:20:49,399 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 10:20:49 INFO - 2015-12-17 10:20:49,400 DEBUG : process_Request line: dromaeo_css 6956.19 graph.html#tests=[[72,1,43]] 10:20:49 INFO - 2015-12-17 10:20:49,400 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:20:49 INFO - 2015-12-17 10:20:49,430 DEBUG : process_Request line: kraken 1522.80 graph.html#tests=[[232,1,43]] 10:20:49 INFO - 2015-12-17 10:20:49,430 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:20:49 INFO - 2015-12-17 10:20:49,461 DEBUG : process_Request line: v8_7 21106.75 graph.html#tests=[[230,1,43]] 10:20:49 INFO - 2015-12-17 10:20:49,462 INFO : javascript benchmark 10:20:49 INFO - 2015-12-17 10:20:49,466 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3208.919777005573, 3209.3260415312784, 3231.046841181851, 3250.1712063423824, 3256.299895522462, 5153.510416510435, 5306.946930530698, 5380.435054319299, 5393.622446428747, 5412.404635490093, 5236.633435659505, 5247.47525247476, 5405.405405405402, 5495.422980587041, 5496.835094947148, 4885.511448855113, 4943.555080042795, 5005.324281222025, 5034.043531728978, 5040.0, 4931.852044438672, 4945.604351651878, 5003.224500202475, 5008.474110218429, 5020.774065167058, 4904.068227036852, 4904.313396124546, 4960.950390496085, 4983.053219888209, 5003.749812509378, 4941.234108713155, 4944.629152813517, 4948.975255123737, 4967.180415231483, 4982.775775090094, 4886.462489126193, 4893.926591101136, 4941.308216849644, 4952.281919121707, 4954.2568614707725, 5053.595712343041, 5164.0, 5190.091733946544, 5298.09932311508, 5304.17785243284, 5857.472827445507, 5863.0, 5875.529957603424, 5899.469047785677, 5907.1434641976675, 5906.586538942232, 5914.526837852962, 5932.0, 5932.644041357532, 5935.673537955411, 5328.147496400557, 5339.5461385782255, 5381.300430943991, 5400.675959442446, 5419.430959749248, 5903.911441328383, 5922.0, 5939.316978547522, 5961.493273071751, 5995.190649262294, 5425.593080518976, 5766.971165144148, 5774.82675519735, 5818.854528636818, 5820.88358232833, 5581.358143813432, 5756.222909907193, 5804.361520232771, 5827.504662103769, 5859.882802343929, 5679.148127780866, 5748.39641837601, 5750.942490575041, 5752.482276595127, 5758.280214973129, 5687.829365119053, 5707.4577915098, 5750.6837123958585, 5762.250907382014, 5848.0, 4409.867703968886, 4420.093880754438, 4430.269005614037, 4438.467383913951, 4463.642908567307, 3947.348687466593, 3952.8616498422416, 3963.841446342114, 3981.2634662587516, 4007.458993035903, 5367.570594352443, 5443.2107344434835, 5464.398916119224, 5469.15228139639, 5477.370102438191, 5653.236813030188, 5734.827955161351, 5740.253767010346, 5740.512056475246, 5756.625819321731, 5410.296661433988, 5410.404855465896, 5411.107167317427, 5420.430854760272, 5461.317335333083, 4985.227289770091, 4991.5258050485145, 4996.07572599063, 4996.300517927493, 5042.243663450438, 5679.062954612526, 5754.971225143931, 5766.0, 5807.8548036299435, 5890.734916928748, 5694.0, 5858.882822343615, 5879.0, 5888.116782482662, 5890.204822348928, 5925.851853703692, 5938.73275702603, 5952.0, 5965.28416590012, 5969.462748352669, 5440.507541297525, 5596.664200148004, 5597.720113994365, 5792.333881603501, 5809.390014048464, 5614.831555053355, 5655.830325090254, 5656.179853921076, 5658.349289831722, 5660.150977353265, 5587.1339942309005, 5677.68772717488, 5785.190073389645, 5800.854978625568, 5835.620684655484, 5665.3201615806365, 5666.914996275142, 5689.886202276014, 5691.373948865538, 5696.487316141567, 5459.2902922619805, 5521.641093328921, 5607.411221821813, 5608.242887210337, 5616.831495055155, 5579.163125531203, 5595.384507704231, 5667.121596152603, 5734.483896449339, 5776.104703770922, 5592.7203639817035, 5611.214429979887, 5646.689432081111, 5653.660780353192, 5661.801836935688], "unit": "score", "name": "dojo.html", "value": 5331.30650014898}, {"lowerIsBetter": false, "replicates": [589.2693060604851, 591.4204080001598, 594.8126340202837, 609.135028259871, 634.942855143037, 35055.0, 35611.4658280126, 35699.2860142797, 35737.2852542949, 36138.45792313114, 38345.808270958674, 38811.61188388115, 39108.60891391085, 39388.40917386241, 39416.80291598545, 8553.957230213855, 8683.913160868406, 8693.652253909837, 8702.912970870273, 8721.389502734812, 8365.832683346329, 8466.534340611264, 8478.872816907751, 8486.575671216446, 8503.659853605848, 8459.873101903477, 8580.570971451403, 8623.913760862406, 8634.222919937205, 8634.740957771246, 8521.44610600309, 8524.232819046285, 8571.10003449635, 8580.485170889704, 8613.138686131368, 8419.915800841974, 8556.0, 8557.44376615518, 8574.056853746082, 8575.099614540446, 35337.82331088354, 35990.0, 35990.82004589986, 36001.91994240151, 36149.09627259313, 3229.4832826747843, 3272.2146684795716, 3610.963890361089, 3880.2239552089695, 4067.6542493888055, 29124.708752912622, 29256.561151582744, 29839.403211935638, 30315.393692126254, 30869.228269293224, 3718.665320121202, 3740.083679498513, 3766.7551609145316, 3834.367329390675, 3890.1830615570766, 249041.0, 249196.0, 249518.0, 250434.0, 250580.0, 54221.1866821998, 55645.165322520195, 55822.0, 56015.439845601024, 56072.719636401554, 65275.0, 66244.00633990494, 66251.66874165599, 66873.0, 66991.0, 16018.279177437043, 16227.188640567925, 16339.59151021234, 16355.0, 16392.0, 12127.75744485123, 12285.57000504978, 12290.385480725929, 12303.4463449145, 12303.75392492163, 19031.90484047571, 19035.61928761445, 19113.0, 19176.232950681817, 19342.8065719341, 7243.348098671039, 7425.1461081975185, 7453.701851925862, 7534.321911028033, 7571.015767950131, 693.5595896605691, 753.7135888362387, 913.164454524103, 1056.3767377247307, 1103.1285284625217, 25999.220023399328, 26976.32559186036, 27065.594016089774, 27444.862775686393, 27449.45101098007, 767.7773445700684, 939.3236869453982, 1046.9633562825265, 1060.1095080132725, 1110.4503270880966, 14023.579292621238, 14117.223552704494, 14306.213158276394, 14398.42406303736, 14434.494792682206, 317901.0, 320985.0, 322103.0, 322984.0, 323561.0, 46843.76578117088, 46969.765151174026, 47173.46199035086, 47258.52741472541, 47386.05227895423, 1318.4594316330258, 1355.234292624664, 1361.3669643615767, 1364.1405914273944, 1376.3187222324814, 25413.110541130973, 26344.8682756585, 26584.091926578276, 26639.200823975312, 26893.86553067222, 41637.167256654095, 41867.16265674731, 41943.580564193966, 41978.79010604927, 41981.79009105057, 241441.0, 242301.0, 242528.0, 242885.0, 243126.0, 29955.251118722208, 31211.687883120878, 31754.0, 31971.36057278888, 32299.677003229666, 38006.0, 39451.21097577975, 40383.1923361537, 40960.38559421671, 41370.79314603408, 34029.82985085158, 34653.48019779655, 34686.479702803976, 34865.0, 34876.12809679778, 35617.0, 35654.28691426209, 36541.0, 36631.45052824263, 36735.08162295964, 31521.52717709282, 31618.209544761565, 31635.36729265448, 34180.0, 34855.12862178466, 28353.574696379157, 29140.854295728386, 29583.26041848971, 29664.851675741484, 29708.55437168401, 23681.64477532804, 24159.758402416453, 24500.142495012587, 24784.132555360477, 25058.248252552454], "unit": "score", "name": "ext.html", "value": 17802.485841171376}, {"lowerIsBetter": false, "replicates": [361.46683641628596, 363.892651667758, 366.63336663336673, 369.70977782440787, 370.1708173690933, 16644.750328745078, 17186.91406542967, 17755.822441775577, 17993.550161245974, 18589.0, 10788.08301294389, 12562.874371256285, 16621.672965784768, 16915.91542042288, 16973.3210671573, 4943.505649435055, 5084.4661310562415, 5109.9489005109845, 5116.283720279145, 5133.666311689747, 5043.52086551777, 5072.568831649314, 5078.974605126988, 5103.438621751604, 5110.514501122406, 4965.577925876305, 5065.885505188852, 5106.183010718286, 5234.555062819665, 5246.895062098756, 5190.06578815813, 5190.11767999441, 5193.506616871391, 5232.816851410202, 5545.750441230154, 5119.872003199913, 5130.820421285274, 5134.794608215667, 5154.458781827929, 5167.431582525919, 12971.481140754453, 15290.311935962796, 18632.0, 20243.190272389085, 20293.18827246923, 2015.0529251251887, 2034.5015471209501, 2054.1885955047824, 2113.8837363944976, 2290.828187885915, 9323.953380233123, 9445.480498572575, 9497.240220782322, 9616.23070154393, 10341.896581034243, 1812.854971602282, 1842.7604411426562, 1857.8978156201404, 1886.3308418587683, 1928.6721257386137, 60879.69560152215, 61586.0, 62065.0, 62531.37468625301, 62873.68563157201, 9989.400635961865, 10462.319949203278, 10585.364878107339, 10659.307145035547, 10697.090747286567, 13767.655808604864, 13858.792118118236, 13882.167069975834, 13894.096883702527, 13894.305284735723, 7507.21174276704, 7763.961180194063, 7781.221877812287, 7800.960995194988, 7827.608619568999, 7422.925770742331, 7427.368673662799, 7445.739399121005, 7475.551466912003, 7629.160792312841, 4454.309582014728, 4496.865094047183, 4508.0533088051225, 4562.771861406916, 4677.906441871211, 1853.7126745354492, 1894.6589613869635, 1915.2147619475948, 1930.3533316338903, 1949.1131535151471, 825.3479750996645, 887.0907319997054, 895.83427065993, 899.1907283444821, 1047.3244757131608, 16451.095189764437, 16724.41464548759, 16937.745933811002, 17098.0, 17141.400050998403, 934.1219253901447, 946.1106559833735, 949.0462085603924, 986.7681094942683, 1025.1747343388645, 9335.299852511089, 9516.38143520669, 9559.713208603753, 9579.664711735195, 9637.277204209713, 489134.0, 492508.0, 503631.0, 549849.0, 554662.0, 79587.20412795914, 80134.99999999884, 80379.59810200911, 81004.18995810085, 81230.0, 1133.580575187186, 1138.4763009015946, 1184.7867383870814, 1191.606769765975, 1197.4072833947077, 14831.332590033258, 14905.776413354026, 14954.327055282321, 15183.24083795828, 15313.770293445385, 35654.0, 35840.641593584776, 36124.638753612126, 36819.81590092032, 37163.44254836126, 46173.30740038969, 47585.286220706024, 47732.76133619449, 47898.76050619724, 48654.27018594653, 9225.261979041794, 9579.089986451276, 9601.367767479405, 9625.56684949193, 9723.56243969009, 11360.715982100513, 11370.431478426211, 11419.771604568028, 11502.022328102204, 11518.193726439069, 13784.0, 13985.44058237659, 14131.788023179866, 14380.856191437952, 14384.856151438771, 14146.151230925765, 14234.572962811133, 14747.0, 14818.62953426173, 15297.46458873934, 14499.130052196902, 14559.0, 14866.331015104563, 14879.330430130454, 15111.244437778287, 9570.95214523923, 9718.611255549982, 9771.0, 10150.086492215736, 10177.592896284066, 11947.462364193805, 13871.4451421942, 13967.371468284155, 14046.719065618427, 14266.71466570642], "unit": "score", "name": "jquery.html", "value": 8753.577738587217}, {"lowerIsBetter": false, "replicates": [1493.4847477620217, 1727.6976529107408, 1791.4446521578311, 1793.300612761023, 1799.190364336049, 7396.4822462427655, 7616.581088040156, 7770.728024519136, 7793.1817159198245, 7905.486143400689, 7642.961785191066, 7649.0, 7674.846503069935, 7684.231576842313, 7710.0, 2094.9581008379823, 2104.558042811004, 2113.408245691209, 2115.090511080235, 2121.140937920145, 2040.9591808163827, 2069.0172168220124, 2127.3086246969797, 2160.384290477214, 2166.11189412341, 2159.5249045210107, 2178.4118288062214, 2192.978070219301, 2200.185931205456, 2200.933971980843, 2157.255746767363, 2167.3822960456273, 2178.6078505868936, 2183.727034120735, 2192.265591027, 2202.7246594175726, 2263.6491343841567, 2264.8754318512474, 2266.6713326567556, 2267.478479949621, 7633.969414129133, 7724.227577242287, 7770.33952114071, 7777.766666999999, 7799.844003119906, 4576.771161441948, 4642.744649044301, 4668.976655116737, 4675.041616468616, 4677.368555245066, 3056.2359410147465, 3093.288543634954, 3093.3194697166587, 3126.8592913318957, 3135.749140068789, 920.8802855628793, 953.3517208298355, 955.6559638530124, 975.2636759246794, 995.1342332170985, 10570.15438764897, 10636.095931845803, 10693.19801014927, 10706.732331691692, 10721.946390267998, 3954.031262340745, 3958.3072962231495, 3967.1867267210155, 3984.8406063757707, 3996.800159991989, 8476.830463390697, 8626.568671566522, 8649.351298652626, 8651.870221946676, 8685.609147588373, 4520.706154099973, 4528.275475923836, 4529.343245229424, 4533.070720502289, 4541.931871021938, 4080.3879418087527, 4119.485064366954, 4120.587941205856, 4183.0, 4192.496900371914, 3518.929621407609, 3522.9119272018406, 3535.840887160084, 3546.556680414948, 3567.23304489536, 1051.5425789781316, 1051.6266725312557, 1072.0780129088978, 1072.2387105155426, 1086.2993369276771, 994.418265314799, 1004.9598016079275, 1007.0734923870057, 1118.4854966715402, 1299.5061876486875, 7251.274872512812, 7253.927460725431, 7255.600941948138, 7271.091113610799, 7298.233685463056, 1092.8037183194022, 1164.8078067119, 1201.0751721174822, 1208.293148508115, 1210.903127749778, 5426.484483974016, 5615.719214039282, 5621.690807005654, 5621.959937411592, 5634.746436410372, 8974.775630609156, 8997.955010224907, 9342.90657093434, 9371.812563748686, 9390.483523406278, 9068.45589264633, 9164.90835091654, 9173.541322933826, 9227.584758685874, 9259.583318750538, 7070.858582828315, 7201.3158749918675, 7210.89183662235, 7218.494705370678, 7228.566286022856, 7040.331168538981, 7047.40097091753, 7069.681864316015, 7094.4679149062995, 7108.537945033556, 7978.521288722695, 8128.715494957648, 8165.591720414074, 8201.30288925448, 8210.794730131556, 8312.459690120122, 8397.70608028716, 8465.0, 8465.111163327761, 8652.653893844175, 5996.070609055429, 6136.447719705248, 6393.200849893763, 6671.8999215010845, 6792.524523283323, 7200.315969982843, 7224.422046236184, 7244.710211591478, 7247.094113235846, 7280.0, 6613.305602911625, 6766.830829229309, 6788.93211067883, 6873.621950792754, 6887.242403335729, 6714.925611902072, 6728.966355168192, 6797.864042719217, 6823.89764153548, 6850.38346548813, 6598.868022639424, 6624.337566243337, 6654.73381064752, 6683.231428385798, 6701.631410272482, 6282.528810339334, 6330.715117819617, 6344.587601805868, 6348.206474190726, 6355.14205582259, 6093.969530152321, 6131.63210207389, 6140.631562106288, 6151.938480615316, 6160.845978850564], "unit": "score", "name": "mootools.html", "value": 4369.588332755338}, {"lowerIsBetter": false, "replicates": [327.0939497591671, 327.8999905028965, 335.2222843004231, 336.0891982726811, 342.7566427836235, 6342.016987366954, 6358.8728225435525, 6444.872147374214, 6459.935400645993, 6527.379898909596, 6275.4038366355235, 6279.748810047593, 6303.117563541108, 6321.968390158043, 6347.396997285262, 4783.71297722136, 4785.258284965827, 4788.473267940523, 4792.257200133985, 4792.305115758213, 4799.112164249607, 4806.9038619227595, 4807.543283388072, 4810.110129626013, 4811.037792441526, 4649.279361698942, 4705.811767529295, 4734.076855013273, 4741.644376671747, 4749.62003039756, 4676.3453116563705, 4692.826793301675, 4717.386739723848, 4731.243001119821, 4741.383620129395, 4076.2866498362964, 4077.1845630873945, 4079.857639860844, 4080.489938757655, 4083.571225021389, 5992.191054207713, 6013.488853447462, 6035.7283922223605, 6051.667158306292, 6051.667158306292, 5414.35027796667, 5516.420775818561, 5519.503244707996, 5526.392096869341, 5541.390447050821, 6734.696938637773, 6742.089817874573, 6745.730170793162, 6747.696353664097, 6758.256591774901, 4504.054148628792, 4524.687840526276, 4533.274676051816, 4537.546245375469, 4553.134904368193, 6848.486363522756, 6910.48171387143, 6945.062416573748, 6961.37347638715, 6991.161060672752, 4675.275332323496, 4702.811887524529, 4708.623310135181, 4712.69367491112, 4713.905721885543, 4764.213904705684, 4777.928331075037, 4778.593819525309, 4779.641526885497, 4780.928286075712, 5519.31008623922, 5532.446755324516, 5534.640248383923, 5557.694326811983, 5566.916496252559, 6622.2715501294815, 6743.022261772016, 6760.830979225559, 6768.187817461838, 6770.187577490733, 1624.9512514624344, 1638.9262483188286, 1648.4807285704965, 1650.7028734827845, 1653.545275049371, 1114.9108071354271, 1115.275071203724, 1118.4519585403257, 1122.550979608147, 1132.6205721083365, 6333.145025421509, 6407.711652975628, 6429.292777794439, 6432.35676432353, 6433.774817881352, 6870.484713646497, 6913.792586222421, 6922.376986071277, 6923.238443771181, 6929.618870962045, 6283.622982620966, 6343.0, 6389.9680501597195, 6411.326810684905, 6421.3578642136345, 3766.133789228438, 3807.9718476011876, 3811.5807261201244, 3849.1147036181833, 3850.2684489947383, 7161.104861892263, 7174.964125179341, 7181.712731490787, 7205.7838264852135, 7209.927900721031, 7100.076989991371, 7201.819954501179, 7237.855242895218, 7249.948757430143, 7266.564006159648, 7152.785416437515, 7189.245129261352, 7203.747868824566, 7204.423646108299, 7232.566046037149, 7008.894866576903, 7011.1586609607175, 7013.894791578028, 7036.859262814817, 7055.118110236221, 6826.351496607815, 6941.167059952636, 6942.548734332252, 6965.407940325129, 6985.615791131537, 7170.282971702996, 7183.784486465414, 7190.568565886064, 7192.928070719226, 7210.0, 4625.537446255482, 4642.883927901829, 4682.063587282489, 4688.765561721969, 4701.388819453449, 6896.7586134485055, 6913.446924246149, 7051.6474176292, 7074.398676112588, 7091.184513780981, 6842.0, 6845.691943862637, 6847.349501797321, 6882.7591034313555, 6883.105196324646, 6786.253512113763, 6816.0, 6816.591004539743, 6822.317768223138, 6823.420009299265, 6585.539012269287, 6705.966470167618, 6716.764913228014, 6729.1252137221845, 6730.0, 6873.312668733087, 6893.586384816927, 6899.896501552581, 6901.549949767961, 6906.102206713095, 6694.129763130762, 6698.698558564974, 6715.160604924385, 6720.764773233109, 6722.428593569602], "unit": "score", "name": "prototype.html", "value": 5047.443951785439}, {"lowerIsBetter": false, "replicates": [730.120205152791, 771.3289438188773, 868.5223127279999, 877.3069275272535, 890.0343127706439, 12102.15284930054, 12549.1215614907, 12555.497780088786, 12592.559260425887, 12606.306653134074, 14176.220307883063, 14579.271036448188, 14590.854091459083, 14601.634959126028, 14623.78064329036, 814.7759366174305, 827.4580150001728, 827.9917200827974, 829.3489610655637, 830.1159265382348, 804.3444592656995, 814.4990830639144, 830.7050996896105, 831.6174559702514, 833.6748668019477, 859.6647307550061, 862.2369203255101, 862.6937437209795, 869.5087275689216, 870.5908223135148, 864.3819668936709, 864.5201912938317, 867.418829384314, 869.1178453869328, 871.8735783311416, 641.4771960851938, 642.2838535033451, 642.6369101457708, 644.262319644005, 644.2655372874926, 12294.754104917942, 12422.316772577504, 12426.316552589604, 12486.625401237978, 12514.249145051326, 11602.419879006016, 11649.728302980837, 11813.291202527877, 11995.880041199563, 12006.859348361892, 9966.89684097002, 10042.598296068149, 10208.89791102087, 10381.79236415275, 11066.225364224507, 3441.9827900860587, 3449.499822525745, 3493.877714279988, 3507.754457187998, 3624.2207925295925, 47505.0, 47775.0, 47873.76063119697, 47947.76026119882, 48338.27492587614, 10161.0, 10217.540210690388, 10247.077763001216, 10271.281010329205, 10306.278560500694, 17817.46547603574, 17988.820111798977, 18010.549736256435, 18058.90970545165, 18065.7290140648, 6875.896861547081, 7000.7549735759, 7014.508984371147, 7045.260247674023, 7070.575765454089, 3849.480320156799, 3897.4348719435525, 3970.1662650843646, 3983.24318379507, 3990.960090399117, 2977.76177905767, 2992.3267264865667, 2996.595459612968, 3011.48804703201, 3013.4575776360466, 538.1604696673203, 549.3243310727827, 550.5375484592881, 551.5339538090308, 562.190445758107, 490.784055015799, 557.4509108528097, 601.2814686449738, 637.0921436952325, 643.6974621927686, 8047.798805029921, 8352.415330926777, 8399.664013439517, 8647.221750042401, 8697.434666746642, 633.6894921488438, 641.1760887259849, 644.3234603666132, 657.7383310880392, 669.3774789445765, 3858.1897801462005, 3874.0314921269683, 3919.5884432134217, 3932.2332145231976, 4037.0916543778003, 57272.14091788627, 57394.426055738906, 65163.0, 66142.33857661458, 66218.66890665516, 49127.50872491301, 49405.50594494081, 49653.25520117201, 51000.48999510032, 51063.489365106616, 46433.767831161305, 46454.354564543275, 46786.0, 46948.0, 47459.5254047462, 8248.711295104642, 8293.460925039853, 8340.124286949784, 8478.872816907628, 8515.31877449815, 34432.65567344295, 34511.30977380388, 34940.650593494756, 35037.0, 35296.647033529334, 40935.1812963745, 41269.38095928504, 42841.78579107209, 45082.098358032, 45185.09629807451, 9879.056281526631, 10180.338278011906, 10200.132988696045, 10326.741831454274, 10706.785864282827, 11355.772884542428, 11378.886211137782, 11428.71428214268, 11592.0, 11639.76720465603, 10493.737656558647, 10646.73383165427, 11084.889151108386, 11214.0, 11427.942860285646, 10732.51703673321, 10812.67561973142, 10821.56713731442, 10829.0, 11087.113030957666, 10601.41692206936, 10625.468726563795, 10672.946635266773, 10727.839082413926, 10868.565257389615, 8848.513331766815, 8895.510746908982, 8960.865587016331, 9308.929473110407, 9471.47906865129, 8615.396922215636, 8836.690715824916, 9200.383680650351, 9299.953500232456, 9443.386179898285], "unit": "score", "name": "yui.html", "value": 6182.749048391541}], "name": "dromaeo_css", "value": 6956.193911494178}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 107.0, 110.0, 110.0, 113.0, 124.0, 110.0, 110.0, 110.0, 112.0], "unit": "score", "name": "ai-astar", "value": 112.0}, {"lowerIsBetter": true, "replicates": [151.0, 132.0, 146.0, 162.0, 124.0, 149.0, 151.0, 136.0, 163.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 144.0}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 162.0, 162.0, 162.0, 166.0, 163.0, 164.0, 172.0, 161.0], "unit": "score", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [71.0, 74.0, 72.0, 72.0, 75.0, 72.0, 166.0, 71.0, 72.0, 72.0], "unit": "score", "name": "audio-fft", "value": 81.7}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 136.0, 93.0, 94.0, 93.0, 139.0, 94.0, 94.0, 135.0], "unit": "score", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 109.0, 110.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.9}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 148.0, 148.0, 148.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [96.0, 101.0, 101.0, 96.0, 95.0, 96.0, 97.0, 96.0, 97.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 97.1}, {"lowerIsBetter": true, "replicates": [62.0, 60.0, 60.0, 61.0, 59.0, 63.0, 61.0, 63.0, 64.0, 58.0], "unit": "score", "name": "json-parse-financial", "value": 61.1}, {"lowerIsBetter": true, "replicates": [67.0, 72.0, 68.0, 69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 94.0, 85.0, 93.0, 85.0, 85.0, 85.0, 94.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.5}, {"lowerIsBetter": true, "replicates": [129.0, 121.0, 131.0, 119.0, 133.0, 126.0, 126.0, 116.0, 127.0, 135.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.3}, {"lowerIsBetter": true, "replicates": [139.0, 152.0, 156.0, 146.0, 149.0, 154.0, 150.0, 153.0, 156.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.3}, {"lowerIsBetter": true, "replicates": [74.0, 66.0, 67.0, 72.0, 66.0, 68.0, 70.0, 67.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.3}], "name": "kraken", "value": 1522.7999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.000668002672], "unit": "score", "name": "Richards", "value": 211.38837599999997}, {"lowerIsBetter": false, "replicates": [186.46280067126608], "unit": "score", "name": "DeltaBlue", "value": 354.59083400000003}, {"lowerIsBetter": false, "replicates": [280.3476310625175], "unit": "score", "name": "Encrypt", "value": 949.4676269999999}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [695.4102920723227], "unit": "score", "name": "Earley", "value": 958.3737939999997}, {"lowerIsBetter": false, "replicates": [12256.09756097561], "unit": "score", "name": "Boyer", "value": 54.378075621890574}, {"lowerIsBetter": false, "replicates": [25225.0], "unit": "score", "name": "RegExp", "value": 36.11437066402381}, {"lowerIsBetter": false, "replicates": [704.7685834502104], "unit": "score", "name": "Splay", "value": 115.62802587064682}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 21106.748901823845}]} 10:20:49 INFO - RETURN: dromaeo_css: 6956.19 10:20:49 INFO - RETURN: kraken: 1522.80 10:20:49 INFO - RETURN: v8_7: 21106.75 10:20:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21106.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1522.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6956.19"}}} 10:20:49 INFO - Return code: 0 10:20:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:20:49 INFO - # TBPL SUCCESS # 10:20:49 INFO - Running post-action listener: _resource_record_post_action 10:20:49 INFO - Running post-run listener: _resource_record_post_run 10:20:50 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 12288; Write bytes: 556847104; Read time: 252; Write time: 443280 10:20:50 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 234352640; Read time: 104; Write time: 259676 10:20:50 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 8192; Write bytes: 322301952; Read time: 148; Write time: 183584 10:20:50 INFO - Running post-run listener: _upload_blobber_files 10:20:50 INFO - Blob upload gear active. 10:20:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:20:50 INFO - Copying logs to upload dir... 10:20:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1271.785072 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2015-12-17 10:20:50.490754) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:20:50.494447) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009964 build_url: 'https://queue.taskcluster.net/v1/task/SoX8oJlDQk6_Qw43mJ0G7g/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 10:20:50.546068) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:20:50.546426) ========= 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=d6a7e047ef025f707024e163000001c3-1450374298.281366-258946766 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004645 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 10:20:50.595946) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:20:50.596301) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 10:20:50.596649) ========= ========= Total master_lag: 0.24 =========