builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-009 starttime: 1450407135.7 results: success (0) buildid: 20151217163007 builduid: be6ffaff701a490eaa3452aa84d0e90d revision: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.695556) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.696196) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.696581) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006211 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.719637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.719973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.733464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.733834) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004527 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.783051) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.783384) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.783798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.784130) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 18:52:15-- 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% 222M=0s 2015-12-17 18:52:16 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095605 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.892966) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.893333) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022378 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.943121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:15.943492) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --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 66fb852962c0d5f6f5fe0604204da4f5d17763c9 --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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 18:52:16,155 truncating revision to first 12 chars 2015-12-17 18:52:16,155 Setting DEBUG logging. 2015-12-17 18:52:16,155 attempt 1/10 2015-12-17 18:52:16,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66fb852962c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 18:52:16,409 unpacking tar archive at: mozilla-central-66fb852962c0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.536662 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:16.493076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:16.493463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:16.503446) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:16.503722) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 18:52:16.504127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-17 18:52:16.504433) ========= /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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:52:16 INFO - MultiFileLogger online at 20151217 18:52:16 in /builds/slave/test-pgo 18:52:16 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 18:52:16 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:52:16 INFO - {'append_to_log': False, 18:52:16 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:52:16 INFO - 'blob_upload_branch': 'Firefox', 18:52:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:52:16 INFO - 'branch': 'Firefox', 18:52:16 INFO - 'buildbot_json_path': 'buildprops.json', 18:52:16 INFO - 'config_files': ('talos/linux_config.py',), 18:52:16 INFO - 'default_actions': ('clobber', 18:52:16 INFO - 'read-buildbot-config', 18:52:16 INFO - 'download-and-extract', 18:52:16 INFO - 'populate-webroot', 18:52:16 INFO - 'create-virtualenv', 18:52:16 INFO - 'install', 18:52:16 INFO - 'run-tests'), 18:52:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:52:16 INFO - 'download_minidump_stackwalk': True, 18:52:16 INFO - 'download_symbols': 'ondemand', 18:52:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:52:16 INFO - 'tooltool.py': '/tools/tooltool.py', 18:52:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:52:16 INFO - '/tools/misc-python/virtualenv.py')}, 18:52:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:52:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:52:16 INFO - 'installer_path': 'installer.exe', 18:52:16 INFO - 'log_level': 'info', 18:52:16 INFO - 'log_name': 'talos', 18:52:16 INFO - 'log_to_console': True, 18:52:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:52:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:52:16 INFO - 'opt_config_files': (), 18:52:16 INFO - 'pip_index': False, 18:52:16 INFO - 'sps_profile': False, 18:52:16 INFO - 'sps_profile_interval': 0, 18:52:16 INFO - 'suite': 'dromaeojs-e10s', 18:52:16 INFO - 'system_bits': '64', 18:52:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:52:16 INFO - 'title': 'talos-linux64-ix-009', 18:52:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:52:16 INFO - 'use_talos_json': True, 18:52:16 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:52:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:52:16 INFO - 'work_dir': 'build'} 18:52:16 INFO - ##### 18:52:16 INFO - ##### Running clobber step. 18:52:16 INFO - ##### 18:52:16 INFO - Running pre-action listener: _resource_record_pre_action 18:52:16 INFO - Running main action method: clobber 18:52:16 INFO - rmtree: /builds/slave/test-pgo/build 18:52:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:52:17 INFO - Running post-action listener: _resource_record_post_action 18:52:17 INFO - ##### 18:52:17 INFO - ##### Running read-buildbot-config step. 18:52:17 INFO - ##### 18:52:17 INFO - Running pre-action listener: _resource_record_pre_action 18:52:17 INFO - Running main action method: read_buildbot_config 18:52:17 INFO - Using buildbot properties: 18:52:17 INFO - { 18:52:17 INFO - "project": "", 18:52:17 INFO - "product": "firefox", 18:52:17 INFO - "script_repo_revision": "production", 18:52:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:52:17 INFO - "repository": "", 18:52:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:52:17 INFO - "buildid": "20151217163007", 18:52:17 INFO - "pgo_build": "True", 18:52:17 INFO - "basedir": "/builds/slave/test-pgo", 18:52:17 INFO - "buildnumber": 585, 18:52:17 INFO - "slavename": "talos-linux64-ix-009", 18:52:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:52:17 INFO - "platform": "ubuntu64_hw", 18:52:17 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:52:17 INFO - "branch": "mozilla-central", 18:52:17 INFO - "repo_path": "mozilla-central", 18:52:17 INFO - "revision": "66fb852962c0d5f6f5fe0604204da4f5d17763c9", 18:52:17 INFO - "stage_platform": "linux64-pgo", 18:52:17 INFO - "builduid": "be6ffaff701a490eaa3452aa84d0e90d", 18:52:17 INFO - "slavebuilddir": "test" 18:52:17 INFO - } 18:52:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 18:52:17 INFO - Running post-action listener: _resource_record_post_action 18:52:17 INFO - ##### 18:52:17 INFO - ##### Running download-and-extract step. 18:52:17 INFO - ##### 18:52:17 INFO - Running pre-action listener: _resource_record_pre_action 18:52:17 INFO - Running main action method: download_and_extract 18:52:17 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:52: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')]} 18:52:17 INFO - https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:52:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/test_packages.json 18:52:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/test_packages.json 18:52:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:52:18 INFO - Downloaded 1302 bytes. 18:52:18 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:52:18 INFO - Using the following test package requirements: 18:52:18 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:52:18 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:52:18 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'jsshell-linux-x86_64.zip'], 18:52:18 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:52:18 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:52:18 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:52:18 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:52:18 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:52:18 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 18:52:18 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 18:52:18 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:52:18 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:52:18 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')]} 18:52:18 INFO - https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:52:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:19 INFO - Downloaded 23120769 bytes. 18:52:19 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 18:52:19 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:19 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 18:52:21 INFO - Return code: 0 18:52:21 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 18:52:21 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')]} 18:52:21 INFO - https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:52:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:22 INFO - Downloaded 23120769 bytes. 18:52:22 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 18:52:22 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 18:52:22 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 18:52:23 INFO - Return code: 0 18:52:23 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')]} 18:52:23 INFO - https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:52:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:52:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:52:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:24 INFO - Downloaded 11132039 bytes. 18:52:24 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 18:52:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 18:52:24 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 18:52:25 INFO - Return code: 0 18:52:25 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')]} 18:52:25 INFO - https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:52:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:52:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:52:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 18:52:26 INFO - Downloaded 54637639 bytes. 18:52:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:52:26 INFO - mkdir: /builds/slave/test-pgo/properties 18:52:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:52:26 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:52:26 INFO - Contents: 18:52:26 INFO - build_url:https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 18:52:26 INFO - Running post-action listener: _resource_record_post_action 18:52:26 INFO - Running post-action listener: set_extra_try_arguments 18:52:26 INFO - ##### 18:52:26 INFO - ##### Running populate-webroot step. 18:52:26 INFO - ##### 18:52:26 INFO - Running pre-action listener: _resource_record_pre_action 18:52:26 INFO - Running main action method: populate_webroot 18:52:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:52:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:52:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:52:26 INFO - '--tppagecycles', 18:52:26 INFO - '7'], 18:52:26 INFO - 'tests': ['tsvgm']}}, 18:52:26 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:52:26 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:52:26 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:52:26 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:52:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'tests': ['damp', 'tps']}, 18:52:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['damp', 'tps']}, 18:52:26 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:52:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:52:26 INFO - 'other': {'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'other_l64': {'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'other_nol64': {'tests': ['a11yr', 18:52:26 INFO - 'ts_paint', 18:52:26 INFO - 'tpaint', 18:52:26 INFO - 'sessionrestore', 18:52:26 INFO - 'sessionrestore_no_auto_restore']}, 18:52:26 INFO - 'svgr': {'tests': ['tsvgx', 18:52:26 INFO - 'tsvgr_opacity', 18:52:26 INFO - 'tart', 18:52:26 INFO - 'tscrollx', 18:52:26 INFO - 'cart']}, 18:52:26 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['tsvgx', 18:52:26 INFO - 'tsvgr_opacity', 18:52:26 INFO - 'tart', 18:52:26 INFO - 'tscrollx', 18:52:26 INFO - 'cart']}, 18:52:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'tests': ['tp5o']}, 18:52:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'talos_options': ['--e10s'], 18:52:26 INFO - 'tests': ['tp5o']}, 18:52:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'talos_options': ['--xperf_path', 18:52:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:26 INFO - 'tests': ['tp5n']}, 18:52:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:26 INFO - 'talos_options': ['--e10s', 18:52:26 INFO - '--xperf_path', 18:52:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:26 INFO - 'tests': ['tp5n']}}, 18:52:26 INFO - 'talos.zip': {'path': '', 18:52:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:52:26 INFO - Running post-action listener: _resource_record_post_action 18:52:26 INFO - ##### 18:52:26 INFO - ##### Running create-virtualenv step. 18:52:26 INFO - ##### 18:52:26 INFO - Running pre-action listener: _resource_record_pre_action 18:52:26 INFO - Running main action method: create_virtualenv 18:52:26 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:52:26 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 18:52:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:52:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:52:26 INFO - Using real prefix '/usr' 18:52:26 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:52:26 INFO - Installing distribute.............................................................................................................................................................................................done. 18:52:28 INFO - Installing pip.................done. 18:52:28 INFO - Return code: 0 18:52:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:29 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:29 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 18:52:29 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 18:52:29 INFO - Using env: {'DISPLAY': ':0', 18:52:29 INFO - 'HOME': '/home/cltbld', 18:52:29 INFO - 'LANG': 'en_US.UTF-8', 18:52:29 INFO - 'LANGUAGE': 'en_US:en', 18:52:29 INFO - 'LOGNAME': 'cltbld', 18:52:29 INFO - 'MAIL': '/var/mail/cltbld', 18:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:29 INFO - 'MOZ_NO_REMOTE': '1', 18:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:29 INFO - 'NO_EM_RESTART': '1', 18:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:29 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:29 INFO - 'SHELL': '/bin/bash', 18:52:29 INFO - 'SHLVL': '1', 18:52:29 INFO - 'TERM': 'linux', 18:52:29 INFO - 'TMOUT': '86400', 18:52:29 INFO - 'USER': 'cltbld', 18:52:29 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:29 INFO - '_': '/tools/buildbot/bin/python'} 18:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:29 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 18:52:29 INFO - Cleaning up... 18:52:29 INFO - Return code: 0 18:52:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:29 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:29 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 18:52:29 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 18:52:29 INFO - Using env: {'DISPLAY': ':0', 18:52:29 INFO - 'HOME': '/home/cltbld', 18:52:29 INFO - 'LANG': 'en_US.UTF-8', 18:52:29 INFO - 'LANGUAGE': 'en_US:en', 18:52:29 INFO - 'LOGNAME': 'cltbld', 18:52:29 INFO - 'MAIL': '/var/mail/cltbld', 18:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:29 INFO - 'MOZ_NO_REMOTE': '1', 18:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:29 INFO - 'NO_EM_RESTART': '1', 18:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:29 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:29 INFO - 'SHELL': '/bin/bash', 18:52:29 INFO - 'SHLVL': '1', 18:52:29 INFO - 'TERM': 'linux', 18:52:29 INFO - 'TMOUT': '86400', 18:52:29 INFO - 'USER': 'cltbld', 18:52:29 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:29 INFO - '_': '/tools/buildbot/bin/python'} 18:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:29 INFO - Downloading/unpacking psutil>=0.7.1 18:52:29 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 18:52:29 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 18:52:29 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 18:52:29 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 18:52:29 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 18:52:29 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 18:52:31 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:52:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:52:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:52:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:31 INFO - Installing collected packages: psutil 18:52:31 INFO - Running setup.py install for psutil 18:52:31 INFO - building 'psutil._psutil_linux' extension 18:52:31 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 18:52:31 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 18:52:31 INFO - building 'psutil._psutil_posix' extension 18:52:31 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 18:52:31 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 18:52:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:31 INFO - Successfully installed psutil 18:52:31 INFO - Cleaning up... 18:52:31 INFO - Return code: 0 18:52:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:31 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:31 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 18:52:31 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 18:52:31 INFO - Using env: {'DISPLAY': ':0', 18:52:31 INFO - 'HOME': '/home/cltbld', 18:52:31 INFO - 'LANG': 'en_US.UTF-8', 18:52:31 INFO - 'LANGUAGE': 'en_US:en', 18:52:31 INFO - 'LOGNAME': 'cltbld', 18:52:31 INFO - 'MAIL': '/var/mail/cltbld', 18:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:31 INFO - 'MOZ_NO_REMOTE': '1', 18:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:31 INFO - 'NO_EM_RESTART': '1', 18:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:31 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:31 INFO - 'SHELL': '/bin/bash', 18:52:31 INFO - 'SHLVL': '1', 18:52:31 INFO - 'TERM': 'linux', 18:52:31 INFO - 'TMOUT': '86400', 18:52:31 INFO - 'USER': 'cltbld', 18:52:31 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:31 INFO - '_': '/tools/buildbot/bin/python'} 18:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:52:31 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 18:52:31 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 18:52:31 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 18:52:31 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 18:52:31 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 18:52:31 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 18:52:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:52:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:52:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:52:33 INFO - Installing collected packages: mozsystemmonitor 18:52:33 INFO - Running setup.py install for mozsystemmonitor 18:52:33 INFO - Successfully installed mozsystemmonitor 18:52:33 INFO - Cleaning up... 18:52:33 INFO - Return code: 0 18:52:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:52:33 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')]} 18:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:33 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')]} 18:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:33 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:33 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 18:52:33 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 18:52:33 INFO - Using env: {'DISPLAY': ':0', 18:52:33 INFO - 'HOME': '/home/cltbld', 18:52:33 INFO - 'LANG': 'en_US.UTF-8', 18:52:33 INFO - 'LANGUAGE': 'en_US:en', 18:52:33 INFO - 'LOGNAME': 'cltbld', 18:52:33 INFO - 'MAIL': '/var/mail/cltbld', 18:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:33 INFO - 'MOZ_NO_REMOTE': '1', 18:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:33 INFO - 'NO_EM_RESTART': '1', 18:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:33 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:33 INFO - 'SHELL': '/bin/bash', 18:52:33 INFO - 'SHLVL': '1', 18:52:33 INFO - 'TERM': 'linux', 18:52:33 INFO - 'TMOUT': '86400', 18:52:33 INFO - 'USER': 'cltbld', 18:52:33 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:33 INFO - '_': '/tools/buildbot/bin/python'} 18:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:34 INFO - Downloading/unpacking blobuploader==1.2.4 18:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:35 INFO - Downloading blobuploader-1.2.4.tar.gz 18:52:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:52:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:52:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:52:35 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 18:52:35 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 18:52:35 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 18:52:35 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 18:52:35 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 18:52:35 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 18:52:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:52:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:52:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:36 INFO - Downloading docopt-0.6.1.tar.gz 18:52:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:52:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:52:36 INFO - Installing collected packages: blobuploader, requests, docopt 18:52:36 INFO - Running setup.py install for blobuploader 18:52:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:52:36 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:52:36 INFO - Running setup.py install for requests 18:52:36 INFO - Running setup.py install for docopt 18:52:37 INFO - Successfully installed blobuploader requests docopt 18:52:37 INFO - Cleaning up... 18:52:37 INFO - Return code: 0 18:52:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:52:37 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')]} 18:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:37 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')]} 18:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:37 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:37 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 18:52:37 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 18:52:37 INFO - Using env: {'DISPLAY': ':0', 18:52:37 INFO - 'HOME': '/home/cltbld', 18:52:37 INFO - 'LANG': 'en_US.UTF-8', 18:52:37 INFO - 'LANGUAGE': 'en_US:en', 18:52:37 INFO - 'LOGNAME': 'cltbld', 18:52:37 INFO - 'MAIL': '/var/mail/cltbld', 18:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:37 INFO - 'MOZ_NO_REMOTE': '1', 18:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:37 INFO - 'NO_EM_RESTART': '1', 18:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:37 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:37 INFO - 'SHELL': '/bin/bash', 18:52:37 INFO - 'SHLVL': '1', 18:52:37 INFO - 'TERM': 'linux', 18:52:37 INFO - 'TMOUT': '86400', 18:52:37 INFO - 'USER': 'cltbld', 18:52:37 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:37 INFO - '_': '/tools/buildbot/bin/python'} 18:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:52:37 INFO - Running setup.py (path:/tmp/pip-HFwDNI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:52:37 INFO - Running setup.py (path:/tmp/pip-zwoalx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:52:37 INFO - Running setup.py (path:/tmp/pip-mSWfjP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:52:37 INFO - Running setup.py (path:/tmp/pip-MsnAeA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:52:37 INFO - Running setup.py (path:/tmp/pip-KCABD1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:52:37 INFO - Running setup.py (path:/tmp/pip-XaipOK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:52:37 INFO - Running setup.py (path:/tmp/pip-oe3_9d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:52:37 INFO - Running setup.py (path:/tmp/pip-TjHjQZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:52:37 INFO - Running setup.py (path:/tmp/pip-gERQEk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:52:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:52:37 INFO - Running setup.py (path:/tmp/pip-sUTiGy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:52:38 INFO - Running setup.py (path:/tmp/pip-elRPzg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:52:38 INFO - Running setup.py (path:/tmp/pip-Pay5R4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:52:38 INFO - Running setup.py (path:/tmp/pip-QKXRyv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:52:38 INFO - Running setup.py (path:/tmp/pip-xcwaPe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:52:38 INFO - Running setup.py (path:/tmp/pip-EReaoJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:52:38 INFO - Running setup.py (path:/tmp/pip-bSBStm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:52:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:52:38 INFO - Running setup.py (path:/tmp/pip-I_MRDK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:52:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:52:38 INFO - Running setup.py install for manifestparser 18:52:38 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:52:38 INFO - Running setup.py install for mozcrash 18:52:38 INFO - Running setup.py install for mozdebug 18:52:38 INFO - Running setup.py install for mozdevice 18:52:38 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:52:38 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:52:38 INFO - Running setup.py install for mozfile 18:52:39 INFO - Running setup.py install for mozhttpd 18:52:39 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozinfo 18:52:39 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozInstall 18:52:39 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozleak 18:52:39 INFO - Running setup.py install for mozlog 18:52:39 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for moznetwork 18:52:39 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozprocess 18:52:39 INFO - Running setup.py install for mozprofile 18:52:39 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozrunner 18:52:39 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:52:39 INFO - Running setup.py install for mozscreenshot 18:52:40 INFO - Running setup.py install for moztest 18:52:40 INFO - Running setup.py install for mozversion 18:52:40 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:52:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:52:40 INFO - Cleaning up... 18:52:40 INFO - Return code: 0 18:52:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:40 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:40 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 18:52:40 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 18:52:40 INFO - Using env: {'DISPLAY': ':0', 18:52:40 INFO - 'HOME': '/home/cltbld', 18:52:40 INFO - 'LANG': 'en_US.UTF-8', 18:52:40 INFO - 'LANGUAGE': 'en_US:en', 18:52:40 INFO - 'LOGNAME': 'cltbld', 18:52:40 INFO - 'MAIL': '/var/mail/cltbld', 18:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:40 INFO - 'MOZ_NO_REMOTE': '1', 18:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:40 INFO - 'NO_EM_RESTART': '1', 18:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:40 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:40 INFO - 'SHELL': '/bin/bash', 18:52:40 INFO - 'SHLVL': '1', 18:52:40 INFO - 'TERM': 'linux', 18:52:40 INFO - 'TMOUT': '86400', 18:52:40 INFO - 'USER': 'cltbld', 18:52:40 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:40 INFO - '_': '/tools/buildbot/bin/python'} 18:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:52:40 INFO - Running setup.py (path:/tmp/pip-f6Ltth-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:52:40 INFO - Running setup.py (path:/tmp/pip-g9OcUD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:52:40 INFO - Running setup.py (path:/tmp/pip-0b8VkV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:52:40 INFO - Running setup.py (path:/tmp/pip-btjpEK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:52:40 INFO - Running setup.py (path:/tmp/pip-snY7pI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:52:40 INFO - Running setup.py (path:/tmp/pip-m8dmYO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:52:40 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)) 18:52:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:52:40 INFO - Running setup.py (path:/tmp/pip-nWG7CL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:52:41 INFO - Running setup.py (path:/tmp/pip-f96qtD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:52:41 INFO - Running setup.py (path:/tmp/pip-vhTBbj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:52:41 INFO - Running setup.py (path:/tmp/pip-7U9r84-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:52:41 INFO - Running setup.py (path:/tmp/pip-dRnF0L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:52:41 INFO - Running setup.py (path:/tmp/pip-RCA8G2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:52:41 INFO - Running setup.py (path:/tmp/pip-anFPzW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:52:41 INFO - Running setup.py (path:/tmp/pip-XchJcW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:52:41 INFO - Running setup.py (path:/tmp/pip-QfIKHF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:52:41 INFO - Running setup.py (path:/tmp/pip-c8ygxf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:52:41 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)) 18:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:52:41 INFO - Running setup.py (path:/tmp/pip-UDdD2q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:52:41 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)) 18:52:41 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)) 18:52:41 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)) 18:52:41 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)) 18:52:41 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)) 18:52:41 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)) 18:52:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:43 INFO - Downloading blessings-1.5.1.tar.gz 18:52:43 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 18:52:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:52:43 INFO - Installing collected packages: blessings 18:52:43 INFO - Running setup.py install for blessings 18:52:43 INFO - Successfully installed blessings 18:52:43 INFO - Cleaning up... 18:52:43 INFO - Return code: 0 18:52:43 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:52:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:52:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:52:43 INFO - Reading from file tmpfile_stdout 18:52:43 INFO - Current package versions: 18:52:43 INFO - argparse == 1.2.1 18:52:43 INFO - blessings == 1.5.1 18:52:43 INFO - blobuploader == 1.2.4 18:52:43 INFO - docopt == 0.6.1 18:52:43 INFO - manifestparser == 1.1 18:52:43 INFO - mozInstall == 1.12 18:52:43 INFO - mozcrash == 0.16 18:52:43 INFO - mozdebug == 0.1 18:52:43 INFO - mozdevice == 0.47 18:52:43 INFO - mozfile == 1.2 18:52:43 INFO - mozhttpd == 0.7 18:52:43 INFO - mozinfo == 0.9 18:52:43 INFO - mozleak == 0.1 18:52:43 INFO - mozlog == 3.1 18:52:43 INFO - moznetwork == 0.27 18:52:43 INFO - mozprocess == 0.22 18:52:43 INFO - mozprofile == 0.27 18:52:43 INFO - mozrunner == 6.11 18:52:43 INFO - mozscreenshot == 0.1 18:52:43 INFO - mozsystemmonitor == 0.0 18:52:43 INFO - moztest == 0.7 18:52:43 INFO - mozversion == 1.4 18:52:43 INFO - psutil == 3.1.1 18:52:43 INFO - requests == 1.2.3 18:52:43 INFO - wsgiref == 0.1.2 18:52:43 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:43 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:43 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 18:52:43 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 18:52:43 INFO - Using env: {'DISPLAY': ':0', 18:52:43 INFO - 'HOME': '/home/cltbld', 18:52:43 INFO - 'LANG': 'en_US.UTF-8', 18:52:43 INFO - 'LANGUAGE': 'en_US:en', 18:52:43 INFO - 'LOGNAME': 'cltbld', 18:52:43 INFO - 'MAIL': '/var/mail/cltbld', 18:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:43 INFO - 'MOZ_NO_REMOTE': '1', 18:52:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:43 INFO - 'NO_EM_RESTART': '1', 18:52:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:43 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:43 INFO - 'SHELL': '/bin/bash', 18:52:43 INFO - 'SHLVL': '1', 18:52:43 INFO - 'TERM': 'linux', 18:52:43 INFO - 'TMOUT': '86400', 18:52:43 INFO - 'USER': 'cltbld', 18:52:43 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:43 INFO - '_': '/tools/buildbot/bin/python'} 18:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 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)) 18:52:44 INFO - Cleaning up... 18:52:44 INFO - Return code: 0 18:52:44 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:44 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 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:44 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 18:52:44 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 18:52:44 INFO - Using env: {'DISPLAY': ':0', 18:52:44 INFO - 'HOME': '/home/cltbld', 18:52:44 INFO - 'LANG': 'en_US.UTF-8', 18:52:44 INFO - 'LANGUAGE': 'en_US:en', 18:52:44 INFO - 'LOGNAME': 'cltbld', 18:52:44 INFO - 'MAIL': '/var/mail/cltbld', 18:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:44 INFO - 'MOZ_NO_REMOTE': '1', 18:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:44 INFO - 'NO_EM_RESTART': '1', 18:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:44 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:44 INFO - 'SHELL': '/bin/bash', 18:52:44 INFO - 'SHLVL': '1', 18:52:44 INFO - 'TERM': 'linux', 18:52:44 INFO - 'TMOUT': '86400', 18:52:44 INFO - 'USER': 'cltbld', 18:52:44 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:44 INFO - '_': '/tools/buildbot/bin/python'} 18:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:44 INFO - Downloading/unpacking jsonschema 18:52:44 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 18:52:44 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 18:52:44 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 18:52:44 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 18:52:44 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 18:52:44 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 18:52:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:52:45 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 18:52:45 INFO - Downloading/unpacking functools32 (from jsonschema) 18:52:45 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 18:52:45 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 18:52:45 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 18:52:45 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 18:52:45 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 18:52:45 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 18:52:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:52:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:52:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:52:46 INFO - warning: no files found matching '*.txt' 18:52:46 INFO - no previously-included directories found matching 'build' 18:52:46 INFO - no previously-included directories found matching 'dist' 18:52:46 INFO - no previously-included directories found matching '.git*' 18:52:46 INFO - Installing collected packages: jsonschema, functools32 18:52:46 INFO - Running setup.py install for functools32 18:52:46 INFO - warning: no files found matching '*.txt' 18:52:46 INFO - no previously-included directories found matching 'build' 18:52:46 INFO - no previously-included directories found matching 'dist' 18:52:46 INFO - no previously-included directories found matching '.git*' 18:52:46 INFO - Successfully installed jsonschema functools32 18:52:46 INFO - Cleaning up... 18:52:46 INFO - Return code: 0 18:52:46 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ecba8bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', '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 18:52:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 18:52:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:52:46 INFO - Using env: {'DISPLAY': ':0', 18:52:46 INFO - 'HOME': '/home/cltbld', 18:52:46 INFO - 'LANG': 'en_US.UTF-8', 18:52:46 INFO - 'LANGUAGE': 'en_US:en', 18:52:46 INFO - 'LOGNAME': 'cltbld', 18:52:46 INFO - 'MAIL': '/var/mail/cltbld', 18:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:46 INFO - 'MOZ_NO_REMOTE': '1', 18:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:52:46 INFO - 'NO_EM_RESTART': '1', 18:52:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:52:46 INFO - 'PWD': '/builds/slave/test-pgo', 18:52:46 INFO - 'SHELL': '/bin/bash', 18:52:46 INFO - 'SHLVL': '1', 18:52:46 INFO - 'TERM': 'linux', 18:52:46 INFO - 'TMOUT': '86400', 18:52:46 INFO - 'USER': 'cltbld', 18:52:46 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:46 INFO - '_': '/tools/buildbot/bin/python'} 18:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:46 INFO - Downloading/unpacking flake8 18:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:52:48 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 18:52:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:52:48 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 18:52:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 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 18:52:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:52:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:52:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:52:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:52:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:52:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:52:49 INFO - no previously-included directories found matching 'docs/_build' 18:52:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:52:49 INFO - Running setup.py install for pep8 18:52:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:52:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:52:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:52:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:52:49 INFO - no previously-included directories found matching 'docs/_build' 18:52:49 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:52:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:52:49 INFO - Cleaning up... 18:52:49 INFO - Return code: 0 18:52:49 INFO - Running post-action listener: _resource_record_post_action 18:52:49 INFO - Running post-action listener: _start_resource_monitoring 18:52:49 INFO - Starting resource monitoring. 18:52:49 INFO - ##### 18:52:49 INFO - ##### Running install step. 18:52:49 INFO - ##### 18:52:49 INFO - Running pre-action listener: _resource_record_pre_action 18:52:49 INFO - Running main action method: install 18:52:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:52:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:52:49 INFO - Reading from file tmpfile_stdout 18:52:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:52:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:52:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:52:49 INFO - Reading from file tmpfile_stdout 18:52:49 INFO - Output received: 18:52:49 INFO - Usage: mozinstall [options] installer 18:52:49 INFO - Options: 18:52:49 INFO - -h, --help show this help message and exit 18:52:49 INFO - -d DEST, --destination=DEST 18:52:49 INFO - Directory to install application into. [default: 18:52:49 INFO - "/builds/slave/test-pgo"] 18:52:49 INFO - --app=APP Application being installed. [default: firefox] 18:52:49 INFO - mkdir: /builds/slave/test-pgo/build/application 18:52:49 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'] 18:52:49 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 18:53:05 INFO - Reading from file tmpfile_stdout 18:53:05 INFO - Output received: 18:53:05 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:53:05 INFO - Running post-action listener: _resource_record_post_action 18:53:05 INFO - ##### 18:53:05 INFO - ##### Running run-tests step. 18:53:05 INFO - ##### 18:53:05 INFO - Running pre-action listener: _resource_record_pre_action 18:53:05 INFO - Running main action method: run_tests 18:53:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:53:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:53:05 INFO - Python 2.7.3 18:53:05 INFO - Return code: 0 18:53:05 INFO - grabbing minidump binary from tooltool 18:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:53:05 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 0x293b350>, '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 0x293c250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x293c6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:53:05 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 18:53:05 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 18:53:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:53:05 INFO - Return code: 0 18:53:05 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:53:05 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:53:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:53:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:53:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:53:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:53:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:53:06 INFO - Return code: 0 18:53:06 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/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:53:06 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/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost 18:53:06 INFO - Using env: {'DISPLAY': ':0', 18:53:06 INFO - 'HOME': '/home/cltbld', 18:53:06 INFO - 'LANG': 'en_US.UTF-8', 18:53:06 INFO - 'LANGUAGE': 'en_US:en', 18:53:06 INFO - 'LOGNAME': 'cltbld', 18:53:06 INFO - 'MAIL': '/var/mail/cltbld', 18:53:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:53:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:06 INFO - 'MOZ_NO_REMOTE': '1', 18:53:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:53:06 INFO - 'NO_EM_RESTART': '1', 18:53:06 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:53:06 INFO - 'PWD': '/builds/slave/test-pgo', 18:53:06 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:53:06 INFO - 'SHELL': '/bin/bash', 18:53:06 INFO - 'SHLVL': '1', 18:53:06 INFO - 'TERM': 'linux', 18:53:06 INFO - 'TMOUT': '86400', 18:53:06 INFO - 'USER': 'cltbld', 18:53:06 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953', 18:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:06 INFO - '_': '/tools/buildbot/bin/python'} 18:53:06 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/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] with output_timeout 3600 18:53:06 INFO - mozversion INFO | application_buildid: 20151217163007 18:53:06 INFO - mozversion INFO | application_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 18:53:06 INFO - mozversion INFO | application_display_name: Nightly 18:53:06 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:53:06 INFO - mozversion INFO | application_name: Firefox 18:53:06 INFO - mozversion INFO | application_remotingname: firefox 18:53:06 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:53:06 INFO - mozversion INFO | application_vendor: Mozilla 18:53:06 INFO - mozversion INFO | application_version: 46.0a1 18:53:06 INFO - mozversion INFO | platform_buildid: 20151217163007 18:53:06 INFO - mozversion INFO | platform_changeset: 66fb852962c0d5f6f5fe0604204da4f5d17763c9 18:53:06 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:53:06 INFO - mozversion INFO | platform_version: 46.0a1 18:53:06 INFO - 2015-12-17 18:53:06,532 DEBUG : using testdate: 1450407186 18:53:06 INFO - 2015-12-17 18:53:06,532 DEBUG : actual date: 1450407186 18:53:06 INFO - 2015-12-17 18:53:06,584 INFO : starting webserver on 'localhost:44482' 18:53:06 INFO - 2015-12-17 18:53:06,585 INFO : Starting test suite talos-linux64-ix-009.e 18:53:06 INFO - 2015-12-17 18:53:06,585 INFO : Starting test dromaeo_css 18:53:06 INFO - 2015-12-17 18:53:06,585 DEBUG : operating with platform_type : linux_ 18:53:06 INFO - 2015-12-17 18:53:06,586 INFO : Initialising browser for dromaeo_css test... 18:53:06 INFO - 2015-12-17 18:53:06,603 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphztWoy/profile http://localhost:44482/getInfo.html 18:53:10 INFO - 2015-12-17 18:53:10,847 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:53:10 INFO - 2015-12-17 18:53:10,847 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:53:10 INFO - 2015-12-17 18:53:10,847 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:53:10 INFO - 2015-12-17 18:53:10,847 DEBUG : BROWSER_OUTPUT: __metrics 18:53:10 INFO - 2015-12-17 18:53:10,952 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] 18:53:11 INFO - 2015-12-17 18:53:11,797 INFO : Browser initialized. 18:53:11 INFO - 2015-12-17 18:53:11,798 INFO : Running cycle 1/1 for dromaeo_css test... 18:53:11 INFO - 2015-12-17 18:53:11,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphztWoy/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:53:23 INFO - 2015-12-17 18:53:23,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 150413312 18:53:23 INFO - 2015-12-17 18:53:23,595 DEBUG : BROWSER_OUTPUT: 18:54:13 INFO - 2015-12-17 18:54:13,210 DEBUG : BROWSER_OUTPUT: 1450407253208 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] 18:56:22 INFO - 2015-12-17 18:56:22,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44482/tests/dromaeo/cssquery-ext.html) 18:56:23 INFO - 2015-12-17 18:56:23,594 DEBUG : BROWSER_OUTPUT: RSS: Main: 157454336 18:56:23 INFO - 2015-12-17 18:56:23,595 DEBUG : BROWSER_OUTPUT: 18:59:25 INFO - 2015-12-17 18:59:25,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-ext.html (next: http://localhost:44482/tests/dromaeo/cssquery-jquery.html) 18:59:26 INFO - 2015-12-17 18:59:26,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 154390528 18:59:26 INFO - 2015-12-17 18:59:26,261 DEBUG : BROWSER_OUTPUT: 19:02:27 INFO - 2015-12-17 19:02:27,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44482/tests/dromaeo/cssquery-mootools.html) 19:02:28 INFO - 2015-12-17 19:02:28,555 DEBUG : BROWSER_OUTPUT: RSS: Main: 154132480 19:02:28 INFO - 2015-12-17 19:02:28,555 DEBUG : BROWSER_OUTPUT: 19:05:30 INFO - 2015-12-17 19:05:30,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44482/tests/dromaeo/cssquery-prototype.html) 19:05:30 INFO - 2015-12-17 19:05:30,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 152563712 19:05:30 INFO - 2015-12-17 19:05:30,812 DEBUG : BROWSER_OUTPUT: 19:08:33 INFO - 2015-12-17 19:08:33,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44482/tests/dromaeo/cssquery-yui.html) 19:08:33 INFO - 2015-12-17 19:08:33,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 152207360 19:08:33 INFO - 2015-12-17 19:08:33,779 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/dromaeo/cssquery-yui.html (next: http://localhost:44482/tests/dromaeo/cssquery-dojo.html) 19:11:36 INFO - 2015-12-17 19:11:36,493 DEBUG : BROWSER_OUTPUT: RSS: Main: 155602944 19:11:36 INFO - 2015-12-17 19:11:36,494 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,495 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:11:36 INFO - 2015-12-17 19:11:36,495 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:11:36 INFO - 2015-12-17 19:11:36,495 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:11:36 INFO - 2015-12-17 19:11:36,495 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:11:36 INFO - 2015-12-17 19:11:36,499 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3210.3258315753696;3224.435723748345;3245.237369218232;3262.265990152216;3276.574045374101;5290.603204759639;5430.456954304567;5432.592555558334;5439.918401223985;5450.809221677242;5401;5407.972960135204;5428.5928555358405;5478.643888147278;5489.34127904652;4977.477364876681;5060;5106.7701953412;5149.124648809711;5168.638195326329;4937.061958227943;4993.575546078588;5015.949840501603;5047.520485553867;5054.444011158769;4888.633352498541;4932.975335123338;4962.528559786814;5020.774065167058;5042.168042273039;4983;5012.248162775612;5020.322256495399;5027.648064635477;5031.52200540948;4996.125678006334;5008.098542262392;5021.196608542652;5021.548060674557;5033.546980771712;5149.613778966593;5169.767360468751;5296;5304.469553044703;5344.492273234076;5837.007708689491;5967.940320596782;5993.250843644544;6010;6018.578699491038;6017.909731354033;6019.729112189962;6025.9397406026255;6028.638281703068;6048.304444988795;5421.756020979065;5448.101063324547;5498.890022199534;5499.50504454601;5549.72251387429;6026.186464827279;6052.6671033092725;6073.18012068374;6074.331823499411;6081.726322315506;5590;5845.269341332333;5846.035404158351;5856.238688970407;5857.7656893724625;5886.970565147147;5942;6017.849553761103;6020.337762846083;6073.453389194905;5817.912731309034;5832.12518122285;5840.182374467578;5857.970710146422;5865.032269675456;5618.185363122325;5737.139429085587;5802.82591522255;5850.180974663551;5854.560907931837;4459.706685061361;4485.798139083749;4514.480834704051;4523.864284071417;4558.635309175258;4013.578574249662;4037.9394309085387;4046.9477935736923;4054.8175332110127;4083.5508094109623;5311.442298558673;5368.651037682538;5468.152436372368;5604.803831865946;5606.411326810708;5886.352501224862;5898.8820223595285;5935.703214839241;5936.1392598073035;5938.168656388109;5476.561875049986;5478.534324582375;5522.6134170607675;5532.5020748132865;5533.142362933751;4993.625478089158;5096.006278775603;5113.079645663816;5122.436531981479;5216.713080780518;6038.154658347835;6038.698065096728;6045.3652366501765;6055.8486037848525;6058.787942421994;5997.13041608973;6010.278766548042;6012.518998480198;6014.609050411709;6025.728842202199;5883.146943693055;5966.791162309299;5995.670238136944;6030.547708921936;6038;5786.247787787561;5796.565257605781;5831.94168058314;5873.735681894411;5925.496332811586;5902.970485147718;5910.527157827278;5915.024021036481;5918.970405147947;5949.405059494189;5631.943680563142;5720.856978575569;5736.971315143564;5757.683327417032;5780.132980053026;5700.144978253295;5747.3103227612955;5762.4525670061275;5774.076147816497;5812.680302583399;5674.744636491285;5705.914411283751;5738.741756621045;5745.540356771532;5752.712364381848;5581.413951535031;5753.9136912945505;5819.82540523785;5826.271716035496;5853.707314634336;5687.630304030225;5753.510951569164;5761.798337058183;5763.625364351304;5794.536437084938 19:11:36 INFO - 2015-12-17 19:11:36,499 DEBUG : BROWSER_OUTPUT: |1;ext.html;594.4352864778461;599.0863932502932;601.4887345756108;616.4236438929602;621.7450845153488;36849;38097;38291.80854095726;38328;38365.42451863221;43328.3500747489;43658.563414365926;43734.56265437345;43802.78098609502;43878.78060609692;8775.034746177931;8905;8909.509976951265;8910;8924.375293729408;8602.18279263469;8701.347398945072;8729.388942774009;8735.1701588349;8748.081451447602;8708;8762.036176020632;8787.604557794883;8802.823943521158;8824.249938755182;8730.956345218265;8772.166644168825;8793.252573531257;8828.602712877935;8835.646574137027;8644.567771611395;8712;8794.296456283482;8795.07651696569;8798.340124490689;39701;39918.401223981666;40170.79914600438;40303;40335;3282.3107147499054;3319.170207448138;3338.6327503974544;3386.5766779152605;3644.1071937375514;29352.886638021282;29750.10749677513;29798.10605681833;29923.70076299231;30920.845395772878;3768.6796622286865;3871.651551360363;3933.5476420211758;3970.463987361698;3986.920261594781;251882;252691;254705;255352.72323638448;255664;54313.185302220496;55610;61892;62036.379636203965;62107;55171.448285517436;55222.17166742502;55258.447415526134;55319.72340138354;55437;16204.270807813677;16291.755623665655;16347.34610615586;16366.590835229215;16414.26135823891;12213;12219;12244.204126731735;12268.44791984363;12296.323702196287;20735.58528829415;20861.37415877526;20974.21719153889;20996.89501552513;21036.684449733268;8105.473144245605;8298.128696486903;8425.199606037417;8464.957675211585;8470.83058338842;952.3857112162616;963.2631037256505;965.5365424596092;1050.3172937590627;1061.9415932123652;27693.753781079497;32670;33528.66471335304;33843;33858.15354616154;1069.4492336446735;1079.5250089960393;1080.098118071419;1142.9941651346762;1157.548556063136;15127.848721512644;15138.394464221528;15174.393024279127;15440.45958391451;15529;383655;388773;391092;392551;393794;54348.45651543513;54816.45183548114;54871.451285487434;54928;54965.17552236719;1410.6120816775265;1414.1091112599202;1431.0984080029514;1434.8278206615273;1530.3113598880548;28187.577186341812;28358.291042724097;28702;28922.710772892002;29005.854970725013;46027.76986115182;47002;47114.76442617765;47136.29295560638;47202.76398617985;315054;316267;317744;318584;318956;29506.78699097533;30305;31617.20956976094;32654;33275;40426.59573404228;40639.59360406358;41203.175936480504;41561.792191038854;41692.58307416887;34092.31815363729;34929;35004.12489687778;35100.82449587736;35373.469397958535;34792.652073479956;35475.290494190485;35545.644543554234;35665.46501802423;35945.281094377446;34047.82976085104;34638.82680586581;34656.133596660286;34705.95882123541;34875;30944.381112377177;31485.527717083805;31839.6816031846;32184.678153218167;32363.51454728228;24306.756932431155;24308;24473.755262447146;24830.751692482845;25061.373465663506 19:11:36 INFO - 2015-12-17 19:11:36,499 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.9749417540772;360.1248965015014;360.6303538872656;361.62762317006974;362.54138514778793;14281.71436571268;14550.636234094152;16565.66868662626;16981.83018169818;17556.648867022668;16650.417235396762;16781.41265055723;17559.91220043898;17869.28522859084;17903.28386864527;5277.947220527793;5396.811111611094;5398.568114550846;5398.946010539884;5399.352077750676;5016.47327030662;5154.123798954168;5178.275041494194;5187.351581052369;5352.197170424429;5073.365829271341;5146.6140039497;5158.200478925773;5177.378714554259;5410.431904650014;5339.652922560041;5358.490943360374;5365.892682146375;5379.542738867201;5380.757865896044;5053.974730126362;5137.2037334213255;5151.278820965068;5170.25031370453;5200.349956255468;12357.135000549964;12610.81083783744;12910.806337904938;12926.676833079155;12949.805752913713;2036.5519585691125;2063.515073957619;2114.2071723103836;2123.3417640531484;2196.6924630551734;9161.175494205487;9430.669926552606;9470.242380609534;9618.374805637612;10222.386656800616;1820.3810704360449;1844.97232541512;1852.5739080011538;1877.7371168036486;1878.9060546972596;52819.47180528184;64434.677826611034;64463;64501.354986450475;64607;10397.948010259901;10400.791984160274;10435.94782026085;10483.790324193473;10501.212409069198;13593.184408935496;13628.86371136276;13651.453941842414;13666.931665341808;13680.452781888613;7576.696932122763;7617.847643047219;7696.769096927102;7723.150453450116;7735.226477352332;7365.1530074042175;7389.408847292204;7399.593022383713;7428.8485284780945;7446.25537446251;4064.6545043671617;4471.3516540102155;4485.349624304523;4495.056038231943;4518.706284091523;1771.7430972508914;1836.091134888239;1842.023727424467;1859.3399343233227;1872.7190921361632;942.34036174678;944.9811003779886;1002.979940401188;1012.5241136665755;1079.5843600213818;16222.18889055566;16569;16609.6678066438;16645.167741613113;16971.660566788596;929.6374413978554;941.1999800169938;1011;1016.2022812092472;1051.1223128687632;9108.180263776292;9219.44683319003;9221.492817894947;9252.90747092534;9365.203957663674;531875;535821;542627;549481;551611;81109;82785;82904.58547707222;83210.16789832024;83540.91147721355;1164.8602167739757;1166.550877911993;1179.5399794080488;1181.0669571038954;1182.526989204308;13969.301534923416;14126.576202713935;14147.36336864823;14174.14955102697;14257.42970281177;32625.347493049532;33041.66958330386;34040.82979585086;34281.657183427844;34928.82535587306;43603.56396435995;44618.3307250398;45983.77008114938;47092.29361559511;47336.526634733214;9383.249340052917;9489.715308540755;9555.4233551466;9569.760755981157;9763.804723905625;10443.895561044596;16514.339426422808;17034.48896533106;17214.311427542758;17457.73813392775;13962.860371396157;14107.506237281646;14668.193249371387;14821.40714371413;14879.553613391616;13997.860021399656;14167.079139856289;14438.422463101359;14466.855331446552;14620.122792632475;13969.860301396857;14055;14272.928635356757;14610.926945365205;14758.262086895827;8859.202671759573;9071.909280907106;9083.18251357381;9646.42121472714;10132.848007280045;14429.42282308696;14854.851451485347;14890.478833240784;14925;15058.397664093314 19:11:36 INFO - 2015-12-17 19:11:36,500 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1543.1127101916397;1569.1056098024117;1598.3287019451825;1724.4395571439286;1809.6290260496598;7208.24313447087;7216.350528452444;7364.079490063742;7631;7670.041244844395;7660.1573826879;7670.117936437311;7755.224477552242;7766.883496747553;7772.572508512044;2210.8341874359407;2231.6987206727126;2235.4970131720315;2241.652543855705;2243.371855880356;2194.4294483434264;2202.3062735238427;2256.8307376946714;2262.3439202631216;2269.1830940861278;2169.2516081951703;2192.8684278943233;2197.121151539381;2209.63541015733;2249.4376405898524;2231.15216217837;2271.5229801741652;2274.977250227493;2276.5560715660454;2291.8051965582945;2250.5386395788823;2310.364649721323;2312.7455979842202;2314.4329639238495;2315.8842057897036;7532.849343013164;7576.204498527631;7609.847803043908;7624.923750762477;7636.541807491512;4539.16025535277;4609.400777898885;4618.976905115486;4630.907381852344;4647.442306923157;3096.040227529473;3114.112477943798;3129.765267604916;3144.24538110854;3158.2262345725444;1047.9738006549896;1088.5972190289565;1094.5512339940665;1108.4346983038708;1127.6391554702498;10106.242031847567;10124.291299608956;10146;10160;10187.337823041478;3954;3985.70107241958;4023.2355852387955;4030.2544029354663;4103.302438585448;8427.367947403969;8428.536430496259;8434;8435.493870367798;8442.704505342406;4583.2666773316105;4585.95414045862;4611.515790841981;4616.838410655611;4619.86140415788;4195.4336164618;4218.493780746268;4241;4244.129953359616;4256.404103425523;3273.525338825857;3274.574305340338;3275.5905511811025;3279.196596833791;3328.7503437242303;1027.2398425165325;1040.77623310987;1044.8014877173314;1056.6354607660344;1063.7500187455942;971.7473456901257;1021.6526381030485;1085.0722632149557;1227.8035514317667;1342.8187194728587;7044.471664625174;7053.92946070543;7062.576245425188;7121.643917804089;7134.821629459305;629.4586655476287;645.5094128462403;648.9810996735081;651.8012006338017;783.506390973683;5487.067198576252;5550.417206193372;5585.497305242467;5592.664440133605;5602.159676048625;8775.912240877637;8787.692430764893;8932.553372331358;9042.502662353501;9117.08829117083;8925.107489251153;8965.686200982935;9033.548322583845;9050.728478145666;9057.275417966546;7044.436445084381;7061.89407158893;7080.858382832314;7140.9285907140265;7143.892841607276;6988.685509151998;7006.929930700831;7039.331263529956;7044.718211271696;7047.859042819217;7881.369490440866;8028.879566806386;8255.669773209005;8257.091719910684;8337.749867503984;7800.375969922506;7888.48724832884;7941.324987376138;7954.085280192852;8301.335893128657;6495.610263384212;6495.675216239264;6542.509311801538;6573.737050518117;6575.769848055295;6962.268961759145;6982.301769822977;7035.226125126334;7069.752558660628;7081.964590177016;6611.305812889577;6612.776636322295;6653.500987425866;6746.8650626988165;6766.7969960901255;6149.600275982047;6338.619682819046;6549.181352330958;6779.017042528904;6839.350261725128;6486.156799616019;6601.669916504252;6610.206775187008;6647.966760166169;6863.828404289933;6421.518386121153;6423.96788016057;6432.903506447314;6437.967810160919;6454.031895215755;6195.94668906278;6202;6392.872142557216;6489.1888513935755;6494.545381823227 19:11:36 INFO - 2015-12-17 19:11:36,501 DEBUG : BROWSER_OUTPUT: |4;prototype.html;340.49436586668793;349.0418800392922;350.81231541125493;352.9223570814423;355.2344697177583;6216.937830621692;6227.844303892405;6325.905111423321;6363.904541431876;6425.6144631322095;6127.203463549743;6305.887527678234;6313.431791138796;6323.462505687011;6329.113924050636;4864.927026094611;4879.9512004880025;4881.560659540641;4884.242942343943;4887.340209071783;4882.633802464829;4885.2427873679635;4895.265710143472;4898.534639209287;4905.141600219965;4720.197566413718;4827.10698520773;4830.951690483085;4842.733649649268;4849.660523763338;4774.570288674001;4798.664093513438;4802.879928001793;4813.350197723333;4847.539483749073;4230.661547076257;4234.640055595278;4247.660187185018;4257.063446041866;4264.189803937242;5881.882362352771;5954.136650185742;5954.970225148846;5996.400359964013;5998.97000514999;5629.043062679354;5660;5661.60368774186;5664.716764161775;5668.546516278729;6690;6712.362325579063;6713.227978782454;6720.294369091224;6733.360330768618;4749.691270067435;4753.83361582343;4755.83354582588;4755.881102972453;4756;6822.863542729167;6932.6533673316135;6953.165620125566;6956.686949087303;6974.302569743036;4836.419629644465;4849.927251091236;4856.028794241166;4856.562909338177;4857.562819346205;4904.779284932187;4910.803567857317;4912.04215178044;4912.091263116335;4928.802847886116;5485.204645326406;5629.155626655953;5643.068893632587;5650.830475085671;5662.207290979266;6664.133662623828;6665.566738162004;6672.666366681647;6686.665666716644;6710.328967103251;1643.7945256842895;1695.2371432855264;1696.2536483947024;1697.109017765683;1725.4651058172008;1100.5487750022453;1107.5514416661265;1114.6990312615549;1116.1405717597404;1118.3904771899333;6379.170707807955;6387.265464471552;6401.007843784221;6414.102025716404;6415.615063096229;6895.793126206222;6897.862042759117;6900.6204658743245;6903.447724182053;6905.965470172617;6285.308616052231;6364.586301890362;6394.744210231539;6396.264429590564;6399.520035997226;3728.515293011891;3766.1149629837514;3766.322062028861;3766.4350347447553;3771.396576547739;7006.614636195059;7015.789526314219;7027.297270272932;7036.859262814817;7049.224585295613;6965.756198533027;7057.964710176519;7071;7087.149542054986;7102.68037938294;6988.58893681679;7007.964960175166;7012.298770123049;7012.7545535906;7016.158061032606;6860.794176174722;6900.102986611709;6922.8615427692175;6925.965370173117;6938.722451102101;6716.093327400738;6839.350261725128;6840.384365407137;6843.3840954314355;6853.383195512428;6942.652867356713;6966;6974.163100427778;6995.370416662321;7004.299570042955;4716.976415117902;4728.952710472989;4730.929036064531;4731.668783185281;4732.337472753748;6778.661066946732;6899.275576064641;6900.551464154814;6903.309669033057;6920.9307906920285;6686.264510903699;6706.765263215764;6710.798676039727;6713.6643167842385;6725.865482690221;6634.170728658918;6689.866202676017;6690.498212633975;6690.594975055098;6692.5315227935525;6430.807075787734;6547.083408322934;6568.6058836469965;6568.6058836469965;6571.638559879252;6670.099536562502;6674.464873079316;6741.764038258833;6743.224529179011;6748;6514.283428822731;6526.804195874131;6532.510061745483;6544.051112588552;6547.214334279917 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: |5;yui.html;773.4160708664957;844.8056946902212;866.3762091294268;866.856968600181;894.0478390514098;16016.839831601696;16356.182190890453;16397.18014099296;16407.17964101796;16427.83572164278;15099.169545674984;15130.243487825592;15234.619134521643;15249.923750381233;15295.847041529581;843.161054750524;849.2017503546647;850.421713235;854.1757204297851;857.3569822633025;833.6165363932598;847.6016272352016;853.3514528958005;854.1287886355944;855.9015713192969;880.4673172730502;880.9207171354577;887.8002449448887;889.6308032166643;892.8839250897374;875.8554838451648;880.4453194487496;882.6778225947515;883.6642076011107;883.8099808541131;648.5913874259234;651.8696260747822;652.1489456259579;652.9542931994762;657.139147716493;13774;13899.722005559934;13915.165090094626;13943.930280348635;13944.233067181302;12225.144239903211;12529.937350313281;12606.180598261175;12744.235345879277;12803.565094448088;10423.843642345371;10620.203484738598;10705.571777128906;10716.732081697943;10812.567497300177;3557.5502161895943;3563.1448452371505;3575.0704816747575;3694.5935947045805;3727.850885964558;46988.7650561745;47761.76119119417;47881.76059119717;48039;48146.51853481455;9682.9031709682;10125.544350504246;10254.794904102026;10257.17942564593;10380.32527885685;17809.19858606366;17865.642687146443;17978.820211797974;18016.729749053775;18030.549236268937;7448.217937116633;7576.696932122653;7615.390768738488;7645.617719114022;7903.486273392211;4102.528209255974;4130.607592278729;4181.226473102486;4265.808038638269;4311.0300182458695;3000.339925216449;3040.4223197592387;3046.4516387050544;3053.3893221355816;3065.6934306569165;556.9805056823072;585.1193953100642;596.5287422935919;596.749365266589;604.6160687963087;584.9707514624251;630.1870586942885;658.3120638932231;684.8048306232699;747.704656660616;8925.5111394524;9018.368714189945;9025.593848276843;9089.409188402866;9271.721848344561;568.9203511508392;584.7018020809418;650.4016304999412;660.3990368764402;679.9592024478548;3830.2148059647707;4088.693347998912;4101.835926562963;4103.794810259535;4117.137997234693;53133.203001955;53597.46402536003;53672.463275367525;54053.72973135109;54214;49464;49631;49742.751286243336;50394.496055039715;50487.24269135966;45320.773396132805;45724;46009.539904600526;46394.5360546397;46513;8162.83674326522;8381.622826972678;8385.245327920517;8473.279771219515;8557.871631925651;34250.65749342475;34446.65553344435;34599.82700086483;34943.82528087343;35461.82269088638;39494.2101157981;39791.60208397879;42224;45390.31914521219;45753.08493830038;11393.829092563452;11507.712307192387;11534.365609891536;11596.362200079077;11649;11866.584669536525;11977;11992.880071199177;12599.87400125987;13090.345482726016;11445.198836081394;11791.941040294743;11866.169368144147;12157.392130393268;12210.57262995791;11476.558423811706;11757;11974;12120.454579543766;12404.379781010733;10942.17933654995;11429.142814288794;11539.307641541534;11552.133589980616;11797.882021180021;9756.609735610497;9837.458939758095;10019.849702254327;10056.798864022825;10359.430231337348;9423.858642120511;9563.61745530171;9566.426014439156;9807.313488055768;9861.309708320348 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,317 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: __startTimestamp1450408296494__endTimestamp 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5469.28 Median:5705.91 stddev:618.09 (10.8%) stddev-sans-first:594.04 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: Values: 3210.3 3224.4 3245.2 3262.3 3276.6 5290.6 5430.5 5432.6 5439.9 5450.8 5401.0 5408.0 5428.6 5478.6 5489.3 4977.5 5060.0 5106.8 5149.1 5168.6 4937.1 4993.6 5015.9 5047.5 5054.4 4888.6 4933.0 4962.5 5020.8 5042.2 4983.0 5012.2 5020.3 5027.6 5031.5 4996.1 5008.1 5021.2 5021.5 5033.5 5149.6 5169.8 5296.0 5304.5 5344.5 5837.0 5967.9 5993.3 6010.0 6018.6 6017.9 6019.7 6025.9 6028.6 6048.3 5421.8 5448.1 5498.9 5499.5 5549.7 6026.2 6052.7 6073.2 6074.3 6081.7 5590.0 5845.3 5846.0 5856.2 5857.8 5887.0 5942.0 6017.8 6020.3 6073.5 5817.9 5832.1 5840.2 5858.0 5865.0 5618.2 5737.1 5802.8 5850.2 5854.6 4459.7 4485.8 4514.5 4523.9 4558.6 4013.6 4037.9 4046.9 4054.8 4083.6 5311.4 5368.7 5468.2 5604.8 5606.4 5886.4 5898.9 5935.7 5936.1 5938.2 5476.6 5478.5 5522.6 5532.5 5533.1 4993.6 5096.0 5113.1 5122.4 5216.7 6038.2 6038.7 6045.4 6055.8 6058.8 5997.1 6010.3 6012.5 6014.6 6025.7 5883.1 5966.8 5995.7 6030.5 6038.0 5786.2 5796.6 5831.9 5873.7 5925.5 5903.0 5910.5 5915.0 5919.0 5949.4 5631.9 5720.9 5737.0 5757.7 5780.1 5700.1 5747.3 5762.5 5774.1 5812.7 5674.7 5705.9 5738.7 5745.5 5752.7 5581.4 5753.9 5819.8 5826.3 5853.7 5687.6 5753.5 5761.8 5763.6 5794.5 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,503 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48682.25 Median:19794.69 stddev:85394.15 (431.4%) stddev-sans-first:85557.38 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: Values: 594.4 599.1 601.5 616.4 621.7 36849.0 38097.0 38291.8 38328.0 38365.4 43328.4 43658.6 43734.6 43802.8 43878.8 8775.0 8905.0 8909.5 8910.0 8924.4 8602.2 8701.3 8729.4 8735.2 8748.1 8708.0 8762.0 8787.6 8802.8 8824.2 8731.0 8772.2 8793.3 8828.6 8835.6 8644.6 8712.0 8794.3 8795.1 8798.3 39701.0 39918.4 40170.8 40303.0 40335.0 3282.3 3319.2 3338.6 3386.6 3644.1 29352.9 29750.1 29798.1 29923.7 30920.8 3768.7 3871.7 3933.5 3970.5 3986.9 251882.0 252691.0 254705.0 255352.7 255664.0 54313.2 55610.0 61892.0 62036.4 62107.0 55171.4 55222.2 55258.4 55319.7 55437.0 16204.3 16291.8 16347.3 16366.6 16414.3 12213.0 12219.0 12244.2 12268.4 12296.3 20735.6 20861.4 20974.2 20996.9 21036.7 8105.5 8298.1 8425.2 8465.0 8470.8 952.4 963.3 965.5 1050.3 1061.9 27693.8 32670.0 33528.7 33843.0 33858.2 1069.4 1079.5 1080.1 1143.0 1157.5 15127.8 15138.4 15174.4 15440.5 15529.0 383655.0 388773.0 391092.0 392551.0 393794.0 54348.5 54816.5 54871.5 54928.0 54965.2 1410.6 1414.1 1431.1 1434.8 1530.3 28187.6 28358.3 28702.0 28922.7 29005.9 46027.8 47002.0 47114.8 47136.3 47202.8 315054.0 316267.0 317744.0 318584.0 318956.0 29506.8 30305.0 31617.2 32654.0 33275.0 40426.6 40639.6 41203.2 41561.8 41692.6 34092.3 34929.0 35004.1 35100.8 35373.5 34792.7 35475.3 35545.6 35665.5 35945.3 34047.8 34638.8 34656.1 34706.0 34875.0 30944.4 31485.5 31839.7 32184.7 32363.5 24306.8 24308.0 24473.8 24830.8 25061.4 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28724.75 Median:34161.24 stddev:88692.83 (259.6%) stddev-sans-first:88916.06 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: Values: 358.0 360.1 360.6 361.6 362.5 14281.7 14550.6 16565.7 16981.8 17556.6 16650.4 16781.4 17559.9 17869.3 17903.3 5277.9 5396.8 5398.6 5398.9 5399.4 5016.5 5154.1 5178.3 5187.4 5352.2 5073.4 5146.6 5158.2 5177.4 5410.4 5339.7 5358.5 5365.9 5379.5 5380.8 5054.0 5137.2 5151.3 5170.3 5200.3 12357.1 12610.8 12910.8 12926.7 12949.8 2036.6 2063.5 2114.2 2123.3 2196.7 9161.2 9430.7 9470.2 9618.4 10222.4 1820.4 1845.0 1852.6 1877.7 1878.9 52819.5 64434.7 64463.0 64501.4 64607.0 10397.9 10400.8 10435.9 10483.8 10501.2 13593.2 13628.9 13651.5 13666.9 13680.5 7576.7 7617.8 7696.8 7723.2 7735.2 7365.2 7389.4 7399.6 7428.8 7446.3 4064.7 4471.4 4485.3 4495.1 4518.7 1771.7 1836.1 1842.0 1859.3 1872.7 942.3 945.0 1003.0 1012.5 1079.6 16222.2 16569.0 16609.7 16645.2 16971.7 929.6 941.2 1011.0 1016.2 1051.1 9108.2 9219.4 9221.5 9252.9 9365.2 531875.0 535821.0 542627.0 549481.0 551611.0 81109.0 82785.0 82904.6 83210.2 83540.9 1164.9 1166.6 1179.5 1181.1 1182.5 13969.3 14126.6 14147.4 14174.1 14257.4 32625.3 33041.7 34040.8 34281.7 34928.8 43603.6 44618.3 45983.8 47092.3 47336.5 9383.2 9489.7 9555.4 9569.8 9763.8 10443.9 16514.3 17034.5 17214.3 17457.7 13962.9 14107.5 14668.2 14821.4 14879.6 13997.9 14167.1 14438.4 14466.9 14620.1 13969.9 14055.0 14272.9 14610.9 14758.3 8859.2 9071.9 9083.2 9646.4 10132.8 14429.4 14854.9 14890.5 14925.0 15058.4 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,504 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5251.84 Median:6422.74 stddev:2694.52 (42.0%) stddev-sans-first:2687.66 19:11:36 INFO - 2015-12-17 19:11:36,505 DEBUG : BROWSER_OUTPUT: Values: 1543.1 1569.1 1598.3 1724.4 1809.6 7208.2 7216.4 7364.1 7631.0 7670.0 7660.2 7670.1 7755.2 7766.9 7772.6 2210.8 2231.7 2235.5 2241.7 2243.4 2194.4 2202.3 2256.8 2262.3 2269.2 2169.3 2192.9 2197.1 2209.6 2249.4 2231.2 2271.5 2275.0 2276.6 2291.8 2250.5 2310.4 2312.7 2314.4 2315.9 7532.8 7576.2 7609.8 7624.9 7636.5 4539.2 4609.4 4619.0 4630.9 4647.4 3096.0 3114.1 3129.8 3144.2 3158.2 1048.0 1088.6 1094.6 1108.4 1127.6 10106.2 10124.3 10146.0 10160.0 10187.3 3954.0 3985.7 4023.2 4030.3 4103.3 8427.4 8428.5 8434.0 8435.5 8442.7 4583.3 4586.0 4611.5 4616.8 4619.9 4195.4 4218.5 4241.0 4244.1 4256.4 3273.5 3274.6 3275.6 3279.2 3328.8 1027.2 1040.8 1044.8 1056.6 1063.8 971.7 1021.7 1085.1 1227.8 1342.8 7044.5 7053.9 7062.6 7121.6 7134.8 629.5 645.5 649.0 651.8 783.5 5487.1 5550.4 5585.5 5592.7 5602.2 8775.9 8787.7 8932.6 9042.5 9117.1 8925.1 8965.7 9033.5 9050.7 9057.3 7044.4 7061.9 7080.9 7140.9 7143.9 6988.7 7006.9 7039.3 7044.7 7047.9 7881.4 8028.9 8255.7 8257.1 8337.7 7800.4 7888.5 7941.3 7954.1 8301.3 6495.6 6495.7 6542.5 6573.7 6575.8 6962.3 6982.3 7035.2 7069.8 7082.0 6611.3 6612.8 6653.5 6746.9 6766.8 6149.6 6338.6 6549.2 6779.0 6839.4 6486.2 6601.7 6610.2 6648.0 6863.8 6421.5 6424.0 6432.9 6438.0 6454.0 6195.9 6202.0 6392.9 6489.2 6494.5 19:11:36 INFO - 2015-12-17 19:11:36,505 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,506 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5596.17 Median:6371.88 stddev:1666.91 (26.2%) stddev-sans-first:1624.24 19:11:36 INFO - 2015-12-17 19:11:36,506 DEBUG : BROWSER_OUTPUT: Values: 340.5 349.0 350.8 352.9 355.2 6216.9 6227.8 6325.9 6363.9 6425.6 6127.2 6305.9 6313.4 6323.5 6329.1 4864.9 4880.0 4881.6 4884.2 4887.3 4882.6 4885.2 4895.3 4898.5 4905.1 4720.2 4827.1 4831.0 4842.7 4849.7 4774.6 4798.7 4802.9 4813.4 4847.5 4230.7 4234.6 4247.7 4257.1 4264.2 5881.9 5954.1 5955.0 5996.4 5999.0 5629.0 5660.0 5661.6 5664.7 5668.5 6690.0 6712.4 6713.2 6720.3 6733.4 4749.7 4753.8 4755.8 4755.9 4756.0 6822.9 6932.7 6953.2 6956.7 6974.3 4836.4 4849.9 4856.0 4856.6 4857.6 4904.8 4910.8 4912.0 4912.1 4928.8 5485.2 5629.2 5643.1 5650.8 5662.2 6664.1 6665.6 6672.7 6686.7 6710.3 1643.8 1695.2 1696.3 1697.1 1725.5 1100.5 1107.6 1114.7 1116.1 1118.4 6379.2 6387.3 6401.0 6414.1 6415.6 6895.8 6897.9 6900.6 6903.4 6906.0 6285.3 6364.6 6394.7 6396.3 6399.5 3728.5 3766.1 3766.3 3766.4 3771.4 7006.6 7015.8 7027.3 7036.9 7049.2 6965.8 7058.0 7071.0 7087.1 7102.7 6988.6 7008.0 7012.3 7012.8 7016.2 6860.8 6900.1 6922.9 6926.0 6938.7 6716.1 6839.4 6840.4 6843.4 6853.4 6942.7 6966.0 6974.2 6995.4 7004.3 4717.0 4729.0 4730.9 4731.7 4732.3 6778.7 6899.3 6900.6 6903.3 6920.9 6686.3 6706.8 6710.8 6713.7 6725.9 6634.2 6689.9 6690.5 6690.6 6692.5 6430.8 6547.1 6568.6 6568.6 6571.6 6670.1 6674.5 6741.8 6743.2 6748.0 6514.3 6526.8 6532.5 6544.1 6547.2 19:11:36 INFO - 2015-12-17 19:11:36,506 DEBUG : BROWSER_OUTPUT: 19:11:36 INFO - 2015-12-17 19:11:36,506 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13807.81 Median:45355.55 stddev:15401.56 (34.0%) stddev-sans-first:15413.66 19:11:36 INFO - 2015-12-17 19:11:36,507 DEBUG : BROWSER_OUTPUT: Values: 773.4 844.8 866.4 866.9 894.0 16016.8 16356.2 16397.2 16407.2 16427.8 15099.2 15130.2 15234.6 15249.9 15295.8 843.2 849.2 850.4 854.2 857.4 833.6 847.6 853.4 854.1 855.9 880.5 880.9 887.8 889.6 892.9 875.9 880.4 882.7 883.7 883.8 648.6 651.9 652.1 653.0 657.1 13774.0 13899.7 13915.2 13943.9 13944.2 12225.1 12529.9 12606.2 12744.2 12803.6 10423.8 10620.2 10705.6 10716.7 10812.6 3557.6 3563.1 3575.1 3694.6 3727.9 46988.8 47761.8 47881.8 48039.0 48146.5 9682.9 10125.5 10254.8 10257.2 10380.3 17809.2 17865.6 17978.8 18016.7 18030.5 7448.2 7576.7 7615.4 7645.6 7903.5 4102.5 4130.6 4181.2 4265.8 4311.0 3000.3 3040.4 3046.5 3053.4 3065.7 557.0 585.1 596.5 596.7 604.6 585.0 630.2 658.3 684.8 747.7 8925.5 9018.4 9025.6 9089.4 9271.7 568.9 584.7 650.4 660.4 680.0 3830.2 4088.7 4101.8 4103.8 4117.1 53133.2 53597.5 53672.5 54053.7 54214.0 49464.0 49631.0 49742.8 50394.5 50487.2 45320.8 45724.0 46009.5 46394.5 46513.0 8162.8 8381.6 8385.2 8473.3 8557.9 34250.7 34446.7 34599.8 34943.8 35461.8 39494.2 39791.6 42224.0 45390.3 45753.1 11393.8 11507.7 11534.4 11596.4 11649.0 11866.6 11977.0 11992.9 12599.9 13090.3 11445.2 11791.9 11866.2 12157.4 12210.6 11476.6 11757.0 11974.0 12120.5 12404.4 10942.2 11429.1 11539.3 11552.1 11797.9 9756.6 9837.5 10019.8 10056.8 10359.4 9423.9 9563.6 9566.4 9807.3 9861.3 19:11:36 INFO - 2015-12-17 19:11:36,507 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:11:36 INFO - 2015-12-17 19:11:36,507 DEBUG : BROWSER_OUTPUT: 19:11:37 INFO - 2015-12-17 19:11:37,134 INFO : Browser exited with error code: 0 19:11:37 INFO - 2015-12-17 19:11:37,152 INFO : Completed test dromaeo_css (00:18:30) 19:11:37 INFO - 2015-12-17 19:11:37,152 INFO : Starting test kraken 19:11:37 INFO - 2015-12-17 19:11:37,152 DEBUG : operating with platform_type : linux_ 19:11:37 INFO - 2015-12-17 19:11:37,152 INFO : Initialising browser for kraken test... 19:11:37 INFO - 2015-12-17 19:11:37,158 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzga_Fz/profile http://localhost:44482/getInfo.html 19:11:41 INFO - 2015-12-17 19:11:41,436 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:11:41 INFO - 2015-12-17 19:11:41,436 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:11:41 INFO - 2015-12-17 19:11:41,437 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:11:41 INFO - 2015-12-17 19:11:41,437 DEBUG : BROWSER_OUTPUT: __metrics 19:11:41 INFO - 2015-12-17 19:11:41,545 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] 19:11:42 INFO - 2015-12-17 19:11:42,445 INFO : Browser initialized. 19:11:42 INFO - 2015-12-17 19:11:42,446 INFO : Running cycle 1/1 for kraken test... 19:11:42 INFO - 2015-12-17 19:11:42,446 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzga_Fz/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:11:54 INFO - 2015-12-17 19:11:54,251 DEBUG : BROWSER_OUTPUT: RSS: Main: 150638592 19:11:54 INFO - 2015-12-17 19:11:54,251 DEBUG : BROWSER_OUTPUT: 19:11:56 INFO - 2015-12-17 19:11:56,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44482/tests/kraken/driver.html?testName=audio-beat-detection) 19:11:57 INFO - 2015-12-17 19:11:57,304 DEBUG : BROWSER_OUTPUT: RSS: Main: 151638016 19:11:57 INFO - 2015-12-17 19:11:57,305 DEBUG : BROWSER_OUTPUT: 19:12:00 INFO - 2015-12-17 19:12:00,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44482/tests/kraken/driver.html?testName=audio-dft) 19:12:00 INFO - 2015-12-17 19:12:00,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 151732224 19:12:00 INFO - 2015-12-17 19:12:00,583 DEBUG : BROWSER_OUTPUT: 19:12:03 INFO - 2015-12-17 19:12:03,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44482/tests/kraken/driver.html?testName=audio-fft) 19:12:04 INFO - 2015-12-17 19:12:04,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 146874368 19:12:04 INFO - 2015-12-17 19:12:04,043 DEBUG : BROWSER_OUTPUT: 19:12:06 INFO - 2015-12-17 19:12:06,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44482/tests/kraken/driver.html?testName=audio-oscillator) 19:12:06 INFO - 2015-12-17 19:12:06,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 146870272 19:12:06 INFO - 2015-12-17 19:12:06,909 DEBUG : BROWSER_OUTPUT: 19:12:09 INFO - 2015-12-17 19:12:09,122 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44482/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:12:09 INFO - 2015-12-17 19:12:09,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 148316160 19:12:09 INFO - 2015-12-17 19:12:09,696 DEBUG : BROWSER_OUTPUT: 19:12:12 INFO - 2015-12-17 19:12:12,675 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44482/tests/kraken/driver.html?testName=imaging-darkroom) 19:12:13 INFO - 2015-12-17 19:12:13,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 149303296 19:12:13 INFO - 2015-12-17 19:12:13,269 DEBUG : BROWSER_OUTPUT: 19:12:16 INFO - 2015-12-17 19:12:16,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44482/tests/kraken/driver.html?testName=imaging-desaturate) 19:12:17 INFO - 2015-12-17 19:12:17,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 149336064 19:12:17 INFO - 2015-12-17 19:12:17,232 DEBUG : BROWSER_OUTPUT: 19:12:20 INFO - 2015-12-17 19:12:20,010 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44482/tests/kraken/driver.html?testName=json-parse-financial) 19:12:20 INFO - 2015-12-17 19:12:20,592 DEBUG : BROWSER_OUTPUT: RSS: Main: 147529728 19:12:20 INFO - 2015-12-17 19:12:20,592 DEBUG : BROWSER_OUTPUT: 19:12:22 INFO - 2015-12-17 19:12:22,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44482/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:12:22 INFO - 2015-12-17 19:12:22,768 DEBUG : BROWSER_OUTPUT: RSS: Main: 148520960 19:12:22 INFO - 2015-12-17 19:12:22,768 DEBUG : BROWSER_OUTPUT: 19:12:24 INFO - 2015-12-17 19:12:24,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:12:25 INFO - 2015-12-17 19:12:25,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 149340160 19:12:25 INFO - 2015-12-17 19:12:25,196 DEBUG : BROWSER_OUTPUT: 19:12:27 INFO - 2015-12-17 19:12:27,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:12:27 INFO - 2015-12-17 19:12:27,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 149311488 19:12:27 INFO - 2015-12-17 19:12:27,859 DEBUG : BROWSER_OUTPUT: 19:12:30 INFO - 2015-12-17 19:12:30,346 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:12:30 INFO - 2015-12-17 19:12:30,939 DEBUG : BROWSER_OUTPUT: RSS: Main: 149946368 19:12:30 INFO - 2015-12-17 19:12:30,939 DEBUG : BROWSER_OUTPUT: 19:12:33 INFO - 2015-12-17 19:12:33,527 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:12:34 INFO - 2015-12-17 19:12:34,120 DEBUG : BROWSER_OUTPUT: RSS: Main: 149340160 19:12:34 INFO - 2015-12-17 19:12:34,120 DEBUG : BROWSER_OUTPUT: 19:12:35 INFO - 2015-12-17 19:12:35,829 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44482/tests/kraken/driver.html?testName=ai-astar) 19:12:36 INFO - 2015-12-17 19:12:36,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 149712896 19:12:36 INFO - 2015-12-17 19:12:36,075 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;105;110;111;111;123;109;110;110;112 19:12:36 INFO - 2015-12-17 19:12:36,079 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;132;164;167;128;157;157;123;176;131 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;163;162;164;166;162;165;168;172;163 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;73;72;71;73;72;141;72;72 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;93;138;92;93;94;142;92;94;130 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;111;110;110;110;110;109;110 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;149;149;148;148;149;150;146 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;96;96;97;97;97;95 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;59;60;61;63;62;62;64;59;62 19:12:36 INFO - 2015-12-17 19:12:36,080 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;68;68;69;68;68;69;70;70 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;84;86;92;84;84;90;83;84;84 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;119;121;126;128;127;117;124;123;133 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;158;152;153;150;141;152;150;152;141 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;69;71;69;67;70;71;66;67;70 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:12:36 INFO - 2015-12-17 19:12:36,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,734 19:12:36 INFO - 2015-12-17 19:12:36,082 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:12:36 INFO - 2015-12-17 19:12:36,082 DEBUG : BROWSER_OUTPUT: __startTimestamp1450408356077__endTimestamp 19:12:36 INFO - 2015-12-17 19:12:36,082 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,085 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:12:36 INFO - 2015-12-17 19:12:36,085 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:12:36 INFO - 2015-12-17 19:12:36,086 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,086 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:4.57 (4.1%) stddev-sans-first:4.84 19:12:36 INFO - 2015-12-17 19:12:36,086 DEBUG : BROWSER_OUTPUT: Values: 112.0 105.0 110.0 111.0 111.0 123.0 109.0 110.0 110.0 112.0 19:12:36 INFO - 2015-12-17 19:12:36,086 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,088 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.70 Median:157.00 stddev:18.69 (11.9%) stddev-sans-first:19.79 19:12:36 INFO - 2015-12-17 19:12:36,088 DEBUG : BROWSER_OUTPUT: Values: 152.0 132.0 164.0 167.0 128.0 157.0 157.0 123.0 176.0 131.0 19:12:36 INFO - 2015-12-17 19:12:36,088 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,088 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.90 Median:164.50 stddev:3.11 (1.9%) stddev-sans-first:3.28 19:12:36 INFO - 2015-12-17 19:12:36,088 DEBUG : BROWSER_OUTPUT: Values: 164.0 163.0 162.0 164.0 166.0 162.0 165.0 168.0 172.0 163.0 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.10 Median:72.00 stddev:21.76 (30.2%) stddev-sans-first:22.97 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 73.0 72.0 71.0 73.0 72.0 141.0 72.0 72.0 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:93.00 stddev:21.17 (22.8%) stddev-sans-first:22.06 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: Values: 95.0 93.0 138.0 92.0 93.0 94.0 142.0 92.0 94.0 130.0 19:12:36 INFO - 2015-12-17 19:12:36,089 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 111.0 110.0 110.0 110.0 110.0 109.0 110.0 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:1.06 (0.7%) stddev-sans-first:1.12 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 149.0 149.0 148.0 148.0 149.0 150.0 146.0 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 96.0 96.0 97.0 97.0 97.0 95.0 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.20 Median:62.00 stddev:1.69 (2.7%) stddev-sans-first:1.73 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: Values: 60.0 59.0 60.0 61.0 63.0 62.0 62.0 64.0 59.0 62.0 19:12:36 INFO - 2015-12-17 19:12:36,090 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 68.0 68.0 69.0 68.0 68.0 69.0 70.0 70.0 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.00 stddev:3.33 (4.0%) stddev-sans-first:3.16 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: Values: 81.0 84.0 86.0 92.0 84.0 84.0 90.0 83.0 84.0 84.0 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.00 stddev:4.75 (3.8%) stddev-sans-first:4.92 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: Values: 121.0 119.0 121.0 126.0 128.0 127.0 117.0 124.0 123.0 133.0 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.00 Median:152.00 stddev:5.94 (3.9%) stddev-sans-first:5.56 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: Values: 141.0 158.0 152.0 153.0 150.0 141.0 152.0 150.0 152.0 141.0 19:12:36 INFO - 2015-12-17 19:12:36,091 DEBUG : BROWSER_OUTPUT: 19:12:36 INFO - 2015-12-17 19:12:36,092 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:1.73 (2.5%) stddev-sans-first:1.83 19:12:36 INFO - 2015-12-17 19:12:36,092 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 71.0 69.0 67.0 70.0 71.0 66.0 67.0 70.0 19:12:36 INFO - 2015-12-17 19:12:36,092 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:12:36 INFO - 2015-12-17 19:12:36,092 DEBUG : BROWSER_OUTPUT: 19:12:37 INFO - 2015-12-17 19:12:37,174 INFO : Browser exited with error code: 0 19:12:37 INFO - 2015-12-17 19:12:37,222 INFO : Completed test kraken (00:01:00) 19:12:37 INFO - 2015-12-17 19:12:37,222 INFO : Starting test v8_7 19:12:37 INFO - 2015-12-17 19:12:37,222 DEBUG : operating with platform_type : linux_ 19:12:37 INFO - 2015-12-17 19:12:37,222 INFO : Initialising browser for v8_7 test... 19:12:37 INFO - 2015-12-17 19:12:37,228 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpILE1zB/profile http://localhost:44482/getInfo.html 19:12:41 INFO - 2015-12-17 19:12:41,433 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:12:41 INFO - 2015-12-17 19:12:41,433 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:12:41 INFO - 2015-12-17 19:12:41,433 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:12:41 INFO - 2015-12-17 19:12:41,433 DEBUG : BROWSER_OUTPUT: __metrics 19:12:41 INFO - 2015-12-17 19:12:41,550 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] 19:12:42 INFO - 2015-12-17 19:12:42,381 INFO : Browser initialized. 19:12:42 INFO - 2015-12-17 19:12:42,382 INFO : Running cycle 1/1 for v8_7 test... 19:12:42 INFO - 2015-12-17 19:12:42,382 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpILE1zB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:12:54 INFO - 2015-12-17 19:12:54,241 DEBUG : BROWSER_OUTPUT: RSS: Main: 151592960 19:12:54 INFO - 2015-12-17 19:12:54,241 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,603 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44482/tests/v8_7/run.html (next: http://localhost:44482/tests/v8_7/run.html) 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 147091456 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:13:15 INFO - 2015-12-17 19:13:15,862 DEBUG : BROWSER_OUTPUT: |0;Richards;166.27868307283006 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.5014137606032 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.58361391694723 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5218.75 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1050.420168067227 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |5;Earley;700.770847932726 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |6;Boyer;12084.33734939759 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |7;RegExp;25275 19:13:15 INFO - 2015-12-17 19:13:15,863 DEBUG : BROWSER_OUTPUT: |8;Splay;634.469696969697 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: __startTimestamp1450408395855__endTimestamp 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:13:15 INFO - 2015-12-17 19:13:15,864 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.28 Median:166.28 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: Values: 166.3 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.50 Median:188.50 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: Values: 188.5 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,865 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.58 Median:280.58 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: Values: 280.6 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5218.75 Median:5218.75 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: Values: 5218.8 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1050.42 Median:1050.42 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,866 DEBUG : BROWSER_OUTPUT: Values: 1050.4 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:700.77 Median:700.77 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: Values: 700.8 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12084.34 Median:12084.34 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: Values: 12084.3 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,867 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25275.00 Median:25275.00 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: Values: 25275.0 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:634.47 Median:634.47 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: Values: 634.5 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 19:13:15 INFO - 2015-12-17 19:13:15,868 DEBUG : BROWSER_OUTPUT: Values: 4854.4 19:13:15 INFO - 2015-12-17 19:13:15,869 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:13:15 INFO - 2015-12-17 19:13:15,869 DEBUG : BROWSER_OUTPUT: 19:13:16 INFO - 2015-12-17 19:13:16,678 INFO : Browser exited with error code: 0 19:13:16 INFO - 2015-12-17 19:13:16,690 INFO : Completed test v8_7 (00:00:39) 19:13:16 INFO - 2015-12-17 19:13:16,872 INFO : Completed test suite (00:20:10) 19:13:16 INFO - 2015-12-17 19:13:16,872 DEBUG : Working with test: dromaeo_css 19:13:16 INFO - 2015-12-17 19:13:16,872 DEBUG : Generating results file: dromaeo_css 19:13:16 INFO - 2015-12-17 19:13:16,873 DEBUG : Working with test: kraken 19:13:16 INFO - 2015-12-17 19:13:16,874 DEBUG : Generating results file: kraken 19:13:16 INFO - 2015-12-17 19:13:16,874 INFO : javascript benchmark 19:13:16 INFO - 2015-12-17 19:13:16,874 DEBUG : Working with test: v8_7 19:13:16 INFO - 2015-12-17 19:13:16,874 DEBUG : Generating results file: v8_7 19:13:16 INFO - 2015-12-17 19:13:16,874 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:13:16 INFO - 2015-12-17 19:13:16,930 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 19:13:16 INFO - 2015-12-17 19:13:16,930 DEBUG : process_Request line: dromaeo_css 7072.73 graph.html#tests=[[72,1,43]] 19:13:16 INFO - 2015-12-17 19:13:16,931 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:13:16 INFO - 2015-12-17 19:13:16,960 DEBUG : process_Request line: kraken 1522.00 graph.html#tests=[[232,1,43]] 19:13:16 INFO - 2015-12-17 19:13:16,960 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:13:17 INFO - 2015-12-17 19:13:17,002 DEBUG : process_Request line: v8_7 21342.94 graph.html#tests=[[230,1,43]] 19:13:17 INFO - 2015-12-17 19:13:17,004 INFO : javascript benchmark 19:13:17 INFO - 2015-12-17 19:13:17,007 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3210.3258315753696, 3224.435723748345, 3245.237369218232, 3262.265990152216, 3276.574045374101, 5290.603204759639, 5430.456954304567, 5432.592555558334, 5439.918401223985, 5450.809221677242, 5401.0, 5407.972960135204, 5428.5928555358405, 5478.643888147278, 5489.34127904652, 4977.477364876681, 5060.0, 5106.7701953412, 5149.124648809711, 5168.638195326329, 4937.061958227943, 4993.575546078588, 5015.949840501603, 5047.520485553867, 5054.444011158769, 4888.633352498541, 4932.975335123338, 4962.528559786814, 5020.774065167058, 5042.168042273039, 4983.0, 5012.248162775612, 5020.322256495399, 5027.648064635477, 5031.52200540948, 4996.125678006334, 5008.098542262392, 5021.196608542652, 5021.548060674557, 5033.546980771712, 5149.613778966593, 5169.767360468751, 5296.0, 5304.469553044703, 5344.492273234076, 5837.007708689491, 5967.940320596782, 5993.250843644544, 6010.0, 6018.578699491038, 6017.909731354033, 6019.729112189962, 6025.9397406026255, 6028.638281703068, 6048.304444988795, 5421.756020979065, 5448.101063324547, 5498.890022199534, 5499.50504454601, 5549.72251387429, 6026.186464827279, 6052.6671033092725, 6073.18012068374, 6074.331823499411, 6081.726322315506, 5590.0, 5845.269341332333, 5846.035404158351, 5856.238688970407, 5857.7656893724625, 5886.970565147147, 5942.0, 6017.849553761103, 6020.337762846083, 6073.453389194905, 5817.912731309034, 5832.12518122285, 5840.182374467578, 5857.970710146422, 5865.032269675456, 5618.185363122325, 5737.139429085587, 5802.82591522255, 5850.180974663551, 5854.560907931837, 4459.706685061361, 4485.798139083749, 4514.480834704051, 4523.864284071417, 4558.635309175258, 4013.578574249662, 4037.9394309085387, 4046.9477935736923, 4054.8175332110127, 4083.5508094109623, 5311.442298558673, 5368.651037682538, 5468.152436372368, 5604.803831865946, 5606.411326810708, 5886.352501224862, 5898.8820223595285, 5935.703214839241, 5936.1392598073035, 5938.168656388109, 5476.561875049986, 5478.534324582375, 5522.6134170607675, 5532.5020748132865, 5533.142362933751, 4993.625478089158, 5096.006278775603, 5113.079645663816, 5122.436531981479, 5216.713080780518, 6038.154658347835, 6038.698065096728, 6045.3652366501765, 6055.8486037848525, 6058.787942421994, 5997.13041608973, 6010.278766548042, 6012.518998480198, 6014.609050411709, 6025.728842202199, 5883.146943693055, 5966.791162309299, 5995.670238136944, 6030.547708921936, 6038.0, 5786.247787787561, 5796.565257605781, 5831.94168058314, 5873.735681894411, 5925.496332811586, 5902.970485147718, 5910.527157827278, 5915.024021036481, 5918.970405147947, 5949.405059494189, 5631.943680563142, 5720.856978575569, 5736.971315143564, 5757.683327417032, 5780.132980053026, 5700.144978253295, 5747.3103227612955, 5762.4525670061275, 5774.076147816497, 5812.680302583399, 5674.744636491285, 5705.914411283751, 5738.741756621045, 5745.540356771532, 5752.712364381848, 5581.413951535031, 5753.9136912945505, 5819.82540523785, 5826.271716035496, 5853.707314634336, 5687.630304030225, 5753.510951569164, 5761.798337058183, 5763.625364351304, 5794.536437084938], "unit": "score", "name": "dojo.html", "value": 5428.332343066394}, {"lowerIsBetter": false, "replicates": [594.4352864778461, 599.0863932502932, 601.4887345756108, 616.4236438929602, 621.7450845153488, 36849.0, 38097.0, 38291.80854095726, 38328.0, 38365.42451863221, 43328.3500747489, 43658.563414365926, 43734.56265437345, 43802.78098609502, 43878.78060609692, 8775.034746177931, 8905.0, 8909.509976951265, 8910.0, 8924.375293729408, 8602.18279263469, 8701.347398945072, 8729.388942774009, 8735.1701588349, 8748.081451447602, 8708.0, 8762.036176020632, 8787.604557794883, 8802.823943521158, 8824.249938755182, 8730.956345218265, 8772.166644168825, 8793.252573531257, 8828.602712877935, 8835.646574137027, 8644.567771611395, 8712.0, 8794.296456283482, 8795.07651696569, 8798.340124490689, 39701.0, 39918.401223981666, 40170.79914600438, 40303.0, 40335.0, 3282.3107147499054, 3319.170207448138, 3338.6327503974544, 3386.5766779152605, 3644.1071937375514, 29352.886638021282, 29750.10749677513, 29798.10605681833, 29923.70076299231, 30920.845395772878, 3768.6796622286865, 3871.651551360363, 3933.5476420211758, 3970.463987361698, 3986.920261594781, 251882.0, 252691.0, 254705.0, 255352.72323638448, 255664.0, 54313.185302220496, 55610.0, 61892.0, 62036.379636203965, 62107.0, 55171.448285517436, 55222.17166742502, 55258.447415526134, 55319.72340138354, 55437.0, 16204.270807813677, 16291.755623665655, 16347.34610615586, 16366.590835229215, 16414.26135823891, 12213.0, 12219.0, 12244.204126731735, 12268.44791984363, 12296.323702196287, 20735.58528829415, 20861.37415877526, 20974.21719153889, 20996.89501552513, 21036.684449733268, 8105.473144245605, 8298.128696486903, 8425.199606037417, 8464.957675211585, 8470.83058338842, 952.3857112162616, 963.2631037256505, 965.5365424596092, 1050.3172937590627, 1061.9415932123652, 27693.753781079497, 32670.0, 33528.66471335304, 33843.0, 33858.15354616154, 1069.4492336446735, 1079.5250089960393, 1080.098118071419, 1142.9941651346762, 1157.548556063136, 15127.848721512644, 15138.394464221528, 15174.393024279127, 15440.45958391451, 15529.0, 383655.0, 388773.0, 391092.0, 392551.0, 393794.0, 54348.45651543513, 54816.45183548114, 54871.451285487434, 54928.0, 54965.17552236719, 1410.6120816775265, 1414.1091112599202, 1431.0984080029514, 1434.8278206615273, 1530.3113598880548, 28187.577186341812, 28358.291042724097, 28702.0, 28922.710772892002, 29005.854970725013, 46027.76986115182, 47002.0, 47114.76442617765, 47136.29295560638, 47202.76398617985, 315054.0, 316267.0, 317744.0, 318584.0, 318956.0, 29506.78699097533, 30305.0, 31617.20956976094, 32654.0, 33275.0, 40426.59573404228, 40639.59360406358, 41203.175936480504, 41561.792191038854, 41692.58307416887, 34092.31815363729, 34929.0, 35004.12489687778, 35100.82449587736, 35373.469397958535, 34792.652073479956, 35475.290494190485, 35545.644543554234, 35665.46501802423, 35945.281094377446, 34047.82976085104, 34638.82680586581, 34656.133596660286, 34705.95882123541, 34875.0, 30944.381112377177, 31485.527717083805, 31839.6816031846, 32184.678153218167, 32363.51454728228, 24306.756932431155, 24308.0, 24473.755262447146, 24830.751692482845, 25061.373465663506], "unit": "score", "name": "ext.html", "value": 18811.72379861234}, {"lowerIsBetter": false, "replicates": [357.9749417540772, 360.1248965015014, 360.6303538872656, 361.62762317006974, 362.54138514778793, 14281.71436571268, 14550.636234094152, 16565.66868662626, 16981.83018169818, 17556.648867022668, 16650.417235396762, 16781.41265055723, 17559.91220043898, 17869.28522859084, 17903.28386864527, 5277.947220527793, 5396.811111611094, 5398.568114550846, 5398.946010539884, 5399.352077750676, 5016.47327030662, 5154.123798954168, 5178.275041494194, 5187.351581052369, 5352.197170424429, 5073.365829271341, 5146.6140039497, 5158.200478925773, 5177.378714554259, 5410.431904650014, 5339.652922560041, 5358.490943360374, 5365.892682146375, 5379.542738867201, 5380.757865896044, 5053.974730126362, 5137.2037334213255, 5151.278820965068, 5170.25031370453, 5200.349956255468, 12357.135000549964, 12610.81083783744, 12910.806337904938, 12926.676833079155, 12949.805752913713, 2036.5519585691125, 2063.515073957619, 2114.2071723103836, 2123.3417640531484, 2196.6924630551734, 9161.175494205487, 9430.669926552606, 9470.242380609534, 9618.374805637612, 10222.386656800616, 1820.3810704360449, 1844.97232541512, 1852.5739080011538, 1877.7371168036486, 1878.9060546972596, 52819.47180528184, 64434.677826611034, 64463.0, 64501.354986450475, 64607.0, 10397.948010259901, 10400.791984160274, 10435.94782026085, 10483.790324193473, 10501.212409069198, 13593.184408935496, 13628.86371136276, 13651.453941842414, 13666.931665341808, 13680.452781888613, 7576.696932122763, 7617.847643047219, 7696.769096927102, 7723.150453450116, 7735.226477352332, 7365.1530074042175, 7389.408847292204, 7399.593022383713, 7428.8485284780945, 7446.25537446251, 4064.6545043671617, 4471.3516540102155, 4485.349624304523, 4495.056038231943, 4518.706284091523, 1771.7430972508914, 1836.091134888239, 1842.023727424467, 1859.3399343233227, 1872.7190921361632, 942.34036174678, 944.9811003779886, 1002.979940401188, 1012.5241136665755, 1079.5843600213818, 16222.18889055566, 16569.0, 16609.6678066438, 16645.167741613113, 16971.660566788596, 929.6374413978554, 941.1999800169938, 1011.0, 1016.2022812092472, 1051.1223128687632, 9108.180263776292, 9219.44683319003, 9221.492817894947, 9252.90747092534, 9365.203957663674, 531875.0, 535821.0, 542627.0, 549481.0, 551611.0, 81109.0, 82785.0, 82904.58547707222, 83210.16789832024, 83540.91147721355, 1164.8602167739757, 1166.550877911993, 1179.5399794080488, 1181.0669571038954, 1182.526989204308, 13969.301534923416, 14126.576202713935, 14147.36336864823, 14174.14955102697, 14257.42970281177, 32625.347493049532, 33041.66958330386, 34040.82979585086, 34281.657183427844, 34928.82535587306, 43603.56396435995, 44618.3307250398, 45983.77008114938, 47092.29361559511, 47336.526634733214, 9383.249340052917, 9489.715308540755, 9555.4233551466, 9569.760755981157, 9763.804723905625, 10443.895561044596, 16514.339426422808, 17034.48896533106, 17214.311427542758, 17457.73813392775, 13962.860371396157, 14107.506237281646, 14668.193249371387, 14821.40714371413, 14879.553613391616, 13997.860021399656, 14167.079139856289, 14438.422463101359, 14466.855331446552, 14620.122792632475, 13969.860301396857, 14055.0, 14272.928635356757, 14610.926945365205, 14758.262086895827, 8859.202671759573, 9071.909280907106, 9083.18251357381, 9646.42121472714, 10132.848007280045, 14429.42282308696, 14854.851451485347, 14890.478833240784, 14925.0, 15058.397664093314], "unit": "score", "name": "jquery.html", "value": 8757.560838036756}, {"lowerIsBetter": false, "replicates": [1543.1127101916397, 1569.1056098024117, 1598.3287019451825, 1724.4395571439286, 1809.6290260496598, 7208.24313447087, 7216.350528452444, 7364.079490063742, 7631.0, 7670.041244844395, 7660.1573826879, 7670.117936437311, 7755.224477552242, 7766.883496747553, 7772.572508512044, 2210.8341874359407, 2231.6987206727126, 2235.4970131720315, 2241.652543855705, 2243.371855880356, 2194.4294483434264, 2202.3062735238427, 2256.8307376946714, 2262.3439202631216, 2269.1830940861278, 2169.2516081951703, 2192.8684278943233, 2197.121151539381, 2209.63541015733, 2249.4376405898524, 2231.15216217837, 2271.5229801741652, 2274.977250227493, 2276.5560715660454, 2291.8051965582945, 2250.5386395788823, 2310.364649721323, 2312.7455979842202, 2314.4329639238495, 2315.8842057897036, 7532.849343013164, 7576.204498527631, 7609.847803043908, 7624.923750762477, 7636.541807491512, 4539.16025535277, 4609.400777898885, 4618.976905115486, 4630.907381852344, 4647.442306923157, 3096.040227529473, 3114.112477943798, 3129.765267604916, 3144.24538110854, 3158.2262345725444, 1047.9738006549896, 1088.5972190289565, 1094.5512339940665, 1108.4346983038708, 1127.6391554702498, 10106.242031847567, 10124.291299608956, 10146.0, 10160.0, 10187.337823041478, 3954.0, 3985.70107241958, 4023.2355852387955, 4030.2544029354663, 4103.302438585448, 8427.367947403969, 8428.536430496259, 8434.0, 8435.493870367798, 8442.704505342406, 4583.2666773316105, 4585.95414045862, 4611.515790841981, 4616.838410655611, 4619.86140415788, 4195.4336164618, 4218.493780746268, 4241.0, 4244.129953359616, 4256.404103425523, 3273.525338825857, 3274.574305340338, 3275.5905511811025, 3279.196596833791, 3328.7503437242303, 1027.2398425165325, 1040.77623310987, 1044.8014877173314, 1056.6354607660344, 1063.7500187455942, 971.7473456901257, 1021.6526381030485, 1085.0722632149557, 1227.8035514317667, 1342.8187194728587, 7044.471664625174, 7053.92946070543, 7062.576245425188, 7121.643917804089, 7134.821629459305, 629.4586655476287, 645.5094128462403, 648.9810996735081, 651.8012006338017, 783.506390973683, 5487.067198576252, 5550.417206193372, 5585.497305242467, 5592.664440133605, 5602.159676048625, 8775.912240877637, 8787.692430764893, 8932.553372331358, 9042.502662353501, 9117.08829117083, 8925.107489251153, 8965.686200982935, 9033.548322583845, 9050.728478145666, 9057.275417966546, 7044.436445084381, 7061.89407158893, 7080.858382832314, 7140.9285907140265, 7143.892841607276, 6988.685509151998, 7006.929930700831, 7039.331263529956, 7044.718211271696, 7047.859042819217, 7881.369490440866, 8028.879566806386, 8255.669773209005, 8257.091719910684, 8337.749867503984, 7800.375969922506, 7888.48724832884, 7941.324987376138, 7954.085280192852, 8301.335893128657, 6495.610263384212, 6495.675216239264, 6542.509311801538, 6573.737050518117, 6575.769848055295, 6962.268961759145, 6982.301769822977, 7035.226125126334, 7069.752558660628, 7081.964590177016, 6611.305812889577, 6612.776636322295, 6653.500987425866, 6746.8650626988165, 6766.7969960901255, 6149.600275982047, 6338.619682819046, 6549.181352330958, 6779.017042528904, 6839.350261725128, 6486.156799616019, 6601.669916504252, 6610.206775187008, 6647.966760166169, 6863.828404289933, 6421.518386121153, 6423.96788016057, 6432.903506447314, 6437.967810160919, 6454.031895215755, 6195.94668906278, 6202.0, 6392.872142557216, 6489.1888513935755, 6494.545381823227], "unit": "score", "name": "mootools.html", "value": 4299.910392376591}, {"lowerIsBetter": false, "replicates": [340.49436586668793, 349.0418800392922, 350.81231541125493, 352.9223570814423, 355.2344697177583, 6216.937830621692, 6227.844303892405, 6325.905111423321, 6363.904541431876, 6425.6144631322095, 6127.203463549743, 6305.887527678234, 6313.431791138796, 6323.462505687011, 6329.113924050636, 4864.927026094611, 4879.9512004880025, 4881.560659540641, 4884.242942343943, 4887.340209071783, 4882.633802464829, 4885.2427873679635, 4895.265710143472, 4898.534639209287, 4905.141600219965, 4720.197566413718, 4827.10698520773, 4830.951690483085, 4842.733649649268, 4849.660523763338, 4774.570288674001, 4798.664093513438, 4802.879928001793, 4813.350197723333, 4847.539483749073, 4230.661547076257, 4234.640055595278, 4247.660187185018, 4257.063446041866, 4264.189803937242, 5881.882362352771, 5954.136650185742, 5954.970225148846, 5996.400359964013, 5998.97000514999, 5629.043062679354, 5660.0, 5661.60368774186, 5664.716764161775, 5668.546516278729, 6690.0, 6712.362325579063, 6713.227978782454, 6720.294369091224, 6733.360330768618, 4749.691270067435, 4753.83361582343, 4755.83354582588, 4755.881102972453, 4756.0, 6822.863542729167, 6932.6533673316135, 6953.165620125566, 6956.686949087303, 6974.302569743036, 4836.419629644465, 4849.927251091236, 4856.028794241166, 4856.562909338177, 4857.562819346205, 4904.779284932187, 4910.803567857317, 4912.04215178044, 4912.091263116335, 4928.802847886116, 5485.204645326406, 5629.155626655953, 5643.068893632587, 5650.830475085671, 5662.207290979266, 6664.133662623828, 6665.566738162004, 6672.666366681647, 6686.665666716644, 6710.328967103251, 1643.7945256842895, 1695.2371432855264, 1696.2536483947024, 1697.109017765683, 1725.4651058172008, 1100.5487750022453, 1107.5514416661265, 1114.6990312615549, 1116.1405717597404, 1118.3904771899333, 6379.170707807955, 6387.265464471552, 6401.007843784221, 6414.102025716404, 6415.615063096229, 6895.793126206222, 6897.862042759117, 6900.6204658743245, 6903.447724182053, 6905.965470172617, 6285.308616052231, 6364.586301890362, 6394.744210231539, 6396.264429590564, 6399.520035997226, 3728.515293011891, 3766.1149629837514, 3766.322062028861, 3766.4350347447553, 3771.396576547739, 7006.614636195059, 7015.789526314219, 7027.297270272932, 7036.859262814817, 7049.224585295613, 6965.756198533027, 7057.964710176519, 7071.0, 7087.149542054986, 7102.68037938294, 6988.58893681679, 7007.964960175166, 7012.298770123049, 7012.7545535906, 7016.158061032606, 6860.794176174722, 6900.102986611709, 6922.8615427692175, 6925.965370173117, 6938.722451102101, 6716.093327400738, 6839.350261725128, 6840.384365407137, 6843.3840954314355, 6853.383195512428, 6942.652867356713, 6966.0, 6974.163100427778, 6995.370416662321, 7004.299570042955, 4716.976415117902, 4728.952710472989, 4730.929036064531, 4731.668783185281, 4732.337472753748, 6778.661066946732, 6899.275576064641, 6900.551464154814, 6903.309669033057, 6920.9307906920285, 6686.264510903699, 6706.765263215764, 6710.798676039727, 6713.6643167842385, 6725.865482690221, 6634.170728658918, 6689.866202676017, 6690.498212633975, 6690.594975055098, 6692.5315227935525, 6430.807075787734, 6547.083408322934, 6568.6058836469965, 6568.6058836469965, 6571.638559879252, 6670.099536562502, 6674.464873079316, 6741.764038258833, 6743.224529179011, 6748.0, 6514.283428822731, 6526.804195874131, 6532.510061745483, 6544.051112588552, 6547.214334279917], "unit": "score", "name": "prototype.html", "value": 5048.733289117591}, {"lowerIsBetter": false, "replicates": [773.4160708664957, 844.8056946902212, 866.3762091294268, 866.856968600181, 894.0478390514098, 16016.839831601696, 16356.182190890453, 16397.18014099296, 16407.17964101796, 16427.83572164278, 15099.169545674984, 15130.243487825592, 15234.619134521643, 15249.923750381233, 15295.847041529581, 843.161054750524, 849.2017503546647, 850.421713235, 854.1757204297851, 857.3569822633025, 833.6165363932598, 847.6016272352016, 853.3514528958005, 854.1287886355944, 855.9015713192969, 880.4673172730502, 880.9207171354577, 887.8002449448887, 889.6308032166643, 892.8839250897374, 875.8554838451648, 880.4453194487496, 882.6778225947515, 883.6642076011107, 883.8099808541131, 648.5913874259234, 651.8696260747822, 652.1489456259579, 652.9542931994762, 657.139147716493, 13774.0, 13899.722005559934, 13915.165090094626, 13943.930280348635, 13944.233067181302, 12225.144239903211, 12529.937350313281, 12606.180598261175, 12744.235345879277, 12803.565094448088, 10423.843642345371, 10620.203484738598, 10705.571777128906, 10716.732081697943, 10812.567497300177, 3557.5502161895943, 3563.1448452371505, 3575.0704816747575, 3694.5935947045805, 3727.850885964558, 46988.7650561745, 47761.76119119417, 47881.76059119717, 48039.0, 48146.51853481455, 9682.9031709682, 10125.544350504246, 10254.794904102026, 10257.17942564593, 10380.32527885685, 17809.19858606366, 17865.642687146443, 17978.820211797974, 18016.729749053775, 18030.549236268937, 7448.217937116633, 7576.696932122653, 7615.390768738488, 7645.617719114022, 7903.486273392211, 4102.528209255974, 4130.607592278729, 4181.226473102486, 4265.808038638269, 4311.0300182458695, 3000.339925216449, 3040.4223197592387, 3046.4516387050544, 3053.3893221355816, 3065.6934306569165, 556.9805056823072, 585.1193953100642, 596.5287422935919, 596.749365266589, 604.6160687963087, 584.9707514624251, 630.1870586942885, 658.3120638932231, 684.8048306232699, 747.704656660616, 8925.5111394524, 9018.368714189945, 9025.593848276843, 9089.409188402866, 9271.721848344561, 568.9203511508392, 584.7018020809418, 650.4016304999412, 660.3990368764402, 679.9592024478548, 3830.2148059647707, 4088.693347998912, 4101.835926562963, 4103.794810259535, 4117.137997234693, 53133.203001955, 53597.46402536003, 53672.463275367525, 54053.72973135109, 54214.0, 49464.0, 49631.0, 49742.751286243336, 50394.496055039715, 50487.24269135966, 45320.773396132805, 45724.0, 46009.539904600526, 46394.5360546397, 46513.0, 8162.83674326522, 8381.622826972678, 8385.245327920517, 8473.279771219515, 8557.871631925651, 34250.65749342475, 34446.65553344435, 34599.82700086483, 34943.82528087343, 35461.82269088638, 39494.2101157981, 39791.60208397879, 42224.0, 45390.31914521219, 45753.08493830038, 11393.829092563452, 11507.712307192387, 11534.365609891536, 11596.362200079077, 11649.0, 11866.584669536525, 11977.0, 11992.880071199177, 12599.87400125987, 13090.345482726016, 11445.198836081394, 11791.941040294743, 11866.169368144147, 12157.392130393268, 12210.57262995791, 11476.558423811706, 11757.0, 11974.0, 12120.454579543766, 12404.379781010733, 10942.17933654995, 11429.142814288794, 11539.307641541534, 11552.133589980616, 11797.882021180021, 9756.609735610497, 9837.458939758095, 10019.849702254327, 10056.798864022825, 10359.430231337348, 9423.858642120511, 9563.61745530171, 9566.426014439156, 9807.313488055768, 9861.309708320348], "unit": "score", "name": "yui.html", "value": 6447.076140162818}], "name": "dromaeo_css", "value": 7072.727217260141}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 105.0, 110.0, 111.0, 111.0, 123.0, 109.0, 110.0, 110.0, 112.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [152.0, 132.0, 164.0, 167.0, 128.0, 157.0, 157.0, 123.0, 176.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 148.7}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 162.0, 164.0, 166.0, 162.0, 165.0, 168.0, 172.0, 163.0], "unit": "score", "name": "audio-dft", "value": 164.9}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 73.0, 72.0, 71.0, 73.0, 72.0, 141.0, 72.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.1}, {"lowerIsBetter": true, "replicates": [95.0, 93.0, 138.0, 92.0, 93.0, 94.0, 142.0, 92.0, 94.0, 130.0], "unit": "score", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 149.0, 149.0, 148.0, 148.0, 149.0, 150.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 148.3}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 97.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [60.0, 59.0, 60.0, 61.0, 63.0, 62.0, 62.0, 64.0, 59.0, 62.0], "unit": "score", "name": "json-parse-financial", "value": 61.2}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 69.0, 70.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.8}, {"lowerIsBetter": true, "replicates": [81.0, 84.0, 86.0, 92.0, 84.0, 84.0, 90.0, 83.0, 84.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.2}, {"lowerIsBetter": true, "replicates": [121.0, 119.0, 121.0, 126.0, 128.0, 127.0, 117.0, 124.0, 123.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [141.0, 158.0, 152.0, 153.0, 150.0, 141.0, 152.0, 150.0, 152.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.0}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 71.0, 69.0, 67.0, 70.0, 71.0, 66.0, 67.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.9}], "name": "kraken", "value": 1522.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.27868307283006], "unit": "score", "name": "Richards", "value": 212.306228}, {"lowerIsBetter": false, "replicates": [188.5014137606032], "unit": "score", "name": "DeltaBlue", "value": 350.7559900000001}, {"lowerIsBetter": false, "replicates": [280.58361391694723], "unit": "score", "name": "Encrypt", "value": 948.6690839999999}, {"lowerIsBetter": false, "replicates": [5218.75], "unit": "score", "name": "Decrypt", "value": 51.00474251497002}, {"lowerIsBetter": false, "replicates": [1050.420168067227], "unit": "score", "name": "RayTrace", "value": 704.469528}, {"lowerIsBetter": false, "replicates": [700.770847932726], "unit": "score", "name": "Earley", "value": 951.0427009999997}, {"lowerIsBetter": false, "replicates": [12084.33734939759], "unit": "score", "name": "Boyer", "value": 55.150976071784655}, {"lowerIsBetter": false, "replicates": [25275.0], "unit": "score", "name": "RegExp", "value": 36.042927794263136}, {"lowerIsBetter": false, "replicates": [634.469696969697], "unit": "score", "name": "Splay", "value": 128.4395462686567}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 21342.94349924856}]} 19:13:17 INFO - RETURN: dromaeo_css: 7072.73 19:13:17 INFO - RETURN: kraken: 1522.00 19:13:17 INFO - RETURN: v8_7: 21342.94 19:13:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "21342.94"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1522.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "7072.73"}}} 19:13:17 INFO - Return code: 0 19:13:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:13:17 INFO - # TBPL SUCCESS # 19:13:17 INFO - Running post-action listener: _resource_record_post_action 19:13:17 INFO - Running post-run listener: _resource_record_post_run 19:13:18 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 0; Write bytes: 556113920; Read time: 0; Write time: 543444 19:13:18 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234532864; Read time: 0; Write time: 323384 19:13:18 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 0; Write bytes: 321581056; Read time: 0; Write time: 220060 19:13:18 INFO - Running post-run listener: _upload_blobber_files 19:13:18 INFO - Blob upload gear active. 19:13:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:13:18 INFO - Copying logs to upload dir... 19:13:18 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1261.587516 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-12-17 19:13:18.114174) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 19:13:18.117518) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009913 build_url: 'https://queue.taskcluster.net/v1/task/tx4kQS6xSSmruzF-v6WbTQ/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 19:13:18.170937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 19:13:18.171433) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450407134.161021-1470341953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 19:13:18.224514) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 19:13:18.225186) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 19:13:18.225877) ========= ========= Total master_lag: 0.23 =========