builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-061 starttime: 1453211176.62 results: success (0) buildid: 20160119030232 builduid: eec43e3591ec44058424218e1eb07a6e revision: b67316254602a63bf4e568198a5c7d3288a9db27 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.619528) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.620025) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.620363) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006316 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.644662) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.644986) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.671379) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.671755) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.026972 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.718612) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.718922) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.719313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.719638) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-19 05:46:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2016-01-19 05:46:17 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161385 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.893871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:16.894231) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.104035 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.011167) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.011530) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b67316254602a63bf4e568198a5c7d3288a9db27 --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 b67316254602a63bf4e568198a5c7d3288a9db27 --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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-19 05:46:17,426 truncating revision to first 12 chars 2016-01-19 05:46:17,426 Setting DEBUG logging. 2016-01-19 05:46:17,426 attempt 1/10 2016-01-19 05:46:17,426 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b67316254602?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-19 05:46:17,678 unpacking tar archive at: mozilla-central-b67316254602/testing/mozharness/ program finished with exit code 0 elapsedTime=0.539322 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.564912) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.565263) ========= echo '%(revision)s' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', '%(revision)s'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False %(revision)s program finished with exit code 0 elapsedTime=0.003928 script_repo_revision: '%(revision)s' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.614482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.614823) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.625242) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.625558) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/%(revision)s ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-19 05:46:17.626014) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-19 05:46:17.626396) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --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', '--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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:46:18 INFO - MultiFileLogger online at 20160119 05:46:18 in /builds/slave/test-pgo 05:46:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:46:18 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:46:18 INFO - {'append_to_log': False, 05:46:18 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:46:18 INFO - 'blob_upload_branch': 'Firefox', 05:46:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:46:18 INFO - 'branch': 'Firefox', 05:46:18 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:18 INFO - 'config_files': ('talos/linux_config.py',), 05:46:18 INFO - 'default_actions': ('clobber', 05:46:18 INFO - 'read-buildbot-config', 05:46:18 INFO - 'download-and-extract', 05:46:18 INFO - 'populate-webroot', 05:46:18 INFO - 'create-virtualenv', 05:46:18 INFO - 'install', 05:46:18 INFO - 'run-tests'), 05:46:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:18 INFO - 'download_minidump_stackwalk': True, 05:46:18 INFO - 'download_symbols': 'ondemand', 05:46:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:18 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:18 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:18 INFO - 'installer_path': 'installer.exe', 05:46:18 INFO - 'log_level': 'info', 05:46:18 INFO - 'log_name': 'talos', 05:46:18 INFO - 'log_to_console': True, 05:46:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:46:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:46:18 INFO - 'opt_config_files': (), 05:46:18 INFO - 'pip_index': False, 05:46:18 INFO - 'sps_profile': False, 05:46:18 INFO - 'sps_profile_interval': 0, 05:46:18 INFO - 'suite': 'dromaeojs-e10s', 05:46:18 INFO - 'system_bits': '32', 05:46:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:18 INFO - 'title': 'talos-linux64-ix-061', 05:46:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:18 INFO - 'use_talos_json': True, 05:46:18 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:46:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:18 INFO - 'work_dir': 'build'} 05:46:18 INFO - ##### 05:46:18 INFO - ##### Running clobber step. 05:46:18 INFO - ##### 05:46:18 INFO - Running pre-action listener: _resource_record_pre_action 05:46:18 INFO - Running main action method: clobber 05:46:18 INFO - rmtree: /builds/slave/test-pgo/build 05:46:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:46:19 INFO - Running post-action listener: _resource_record_post_action 05:46:19 INFO - ##### 05:46:19 INFO - ##### Running read-buildbot-config step. 05:46:19 INFO - ##### 05:46:19 INFO - Running pre-action listener: _resource_record_pre_action 05:46:19 INFO - Running main action method: read_buildbot_config 05:46:19 INFO - Using buildbot properties: 05:46:19 INFO - { 05:46:19 INFO - "project": "", 05:46:19 INFO - "product": "firefox", 05:46:19 INFO - "script_repo_revision": "production", 05:46:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:46:19 INFO - "repository": "", 05:46:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:46:19 INFO - "buildid": "20160119030232", 05:46:19 INFO - "stage_platform": "linux64-pgo", 05:46:19 INFO - "basedir": "/builds/slave/test-pgo", 05:46:19 INFO - "nightly_build": "True", 05:46:19 INFO - "buildnumber": 74, 05:46:19 INFO - "slavename": "talos-linux64-ix-061", 05:46:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:46:19 INFO - "platform": "ubuntu64_hw", 05:46:19 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:46:19 INFO - "branch": "mozilla-central", 05:46:19 INFO - "repo_path": "mozilla-central", 05:46:19 INFO - "revision": "b67316254602a63bf4e568198a5c7d3288a9db27", 05:46:19 INFO - "pgo_build": "True", 05:46:19 INFO - "builduid": "eec43e3591ec44058424218e1eb07a6e", 05:46:19 INFO - "slavebuilddir": "test" 05:46:19 INFO - } 05:46:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:46:19 INFO - Running post-action listener: _resource_record_post_action 05:46:19 INFO - ##### 05:46:19 INFO - ##### Running download-and-extract step. 05:46:19 INFO - ##### 05:46:19 INFO - Running pre-action listener: _resource_record_pre_action 05:46:19 INFO - Running main action method: download_and_extract 05:46:19 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:19 INFO - https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:46:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/test_packages.json 05:46:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/test_packages.json 05:46:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:46:22 INFO - Downloaded 1448 bytes. 05:46:22 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:46:22 INFO - Using the following test package requirements: 05:46:22 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:22 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:46:22 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:46:22 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'jsshell-linux-x86_64.zip'], 05:46:22 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:46:22 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:22 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:46:22 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:46:22 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:46:22 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:46:22 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:46:22 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:46:22 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:22 INFO - https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:46:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:46:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:31 INFO - Downloaded 22484120 bytes. 05:46:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:46:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:46:32 INFO - Return code: 0 05:46:32 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 05:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:32 INFO - https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:46:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:46:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:46:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:34 INFO - Downloaded 22484120 bytes. 05:46:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:46:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:46:36 INFO - Return code: 0 05:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:36 INFO - https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:37 INFO - Downloaded 11132144 bytes. 05:46:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:46:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 05:46:37 INFO - Return code: 0 05:46: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')]} 05:46:37 INFO - https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:46:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:46:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:46:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cctg1QTUS8KRZRNaEiIqRg/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 05:46:38 INFO - Downloaded 55944020 bytes. 05:46:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:46:38 INFO - mkdir: /builds/slave/test-pgo/properties 05:46:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:46:38 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:46:38 INFO - Contents: 05:46:38 INFO - build_url:https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:46:38 INFO - Running post-action listener: _resource_record_post_action 05:46:38 INFO - Running post-action listener: set_extra_try_arguments 05:46:38 INFO - ##### 05:46:38 INFO - ##### Running populate-webroot step. 05:46:38 INFO - ##### 05:46:38 INFO - Running pre-action listener: _resource_record_pre_action 05:46:38 INFO - Running main action method: populate_webroot 05:46:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:38 INFO - '--tppagecycles', 05:46:38 INFO - '7'], 05:46:38 INFO - 'tests': ['tsvgm']}}, 05:46:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:46:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:46:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'tests': ['damp', 'tps']}, 05:46:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['damp', 'tps']}, 05:46:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:46:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:38 INFO - 'other': {'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'other_l64': {'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'other_nol64': {'tests': ['a11yr', 05:46:38 INFO - 'ts_paint', 05:46:38 INFO - 'tpaint', 05:46:38 INFO - 'sessionrestore', 05:46:38 INFO - 'sessionrestore_no_auto_restore']}, 05:46:38 INFO - 'svgr': {'tests': ['tsvgx', 05:46:38 INFO - 'tsvgr_opacity', 05:46:38 INFO - 'tart', 05:46:38 INFO - 'tscrollx', 05:46:38 INFO - 'cart']}, 05:46:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['tsvgx', 05:46:38 INFO - 'tsvgr_opacity', 05:46:38 INFO - 'tart', 05:46:38 INFO - 'tscrollx', 05:46:38 INFO - 'cart']}, 05:46:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'tests': ['tp5o']}, 05:46:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'talos_options': ['--e10s'], 05:46:38 INFO - 'tests': ['tp5o']}, 05:46:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'talos_options': ['--xperf_path', 05:46:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:38 INFO - 'tests': ['tp5n']}, 05:46:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:38 INFO - 'talos_options': ['--e10s', 05:46:38 INFO - '--xperf_path', 05:46:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:38 INFO - 'tests': ['tp5n']}}, 05:46:38 INFO - 'talos.zip': {'path': '', 05:46:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:38 INFO - Running post-action listener: _resource_record_post_action 05:46:38 INFO - ##### 05:46:38 INFO - ##### Running create-virtualenv step. 05:46:38 INFO - ##### 05:46:38 INFO - Running pre-action listener: _resource_record_pre_action 05:46:38 INFO - Running main action method: create_virtualenv 05:46:38 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:46:38 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 05:46:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:46:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:38 INFO - Using real prefix '/usr' 05:46:38 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:46:39 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:41 INFO - Installing pip.................done. 05:46:41 INFO - Return code: 0 05:46:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:46:41 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')]} 05:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 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')]} 05:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:41 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 05:46:41 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 05:46:41 INFO - Using env: {'DISPLAY': ':0', 05:46:41 INFO - 'HOME': '/home/cltbld', 05:46:41 INFO - 'LANG': 'en_US.UTF-8', 05:46:41 INFO - 'LANGUAGE': 'en_US:en', 05:46:41 INFO - 'LOGNAME': 'cltbld', 05:46:41 INFO - 'MAIL': '/var/mail/cltbld', 05:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:41 INFO - 'MOZ_NO_REMOTE': '1', 05:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:41 INFO - 'NO_EM_RESTART': '1', 05:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:41 INFO - 'SHELL': '/bin/bash', 05:46:41 INFO - 'SHLVL': '1', 05:46:41 INFO - 'TERM': 'linux', 05:46:41 INFO - 'TMOUT': '86400', 05:46:41 INFO - 'USER': 'cltbld', 05:46:41 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:41 INFO - '_': '/tools/buildbot/bin/python'} 05:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:41 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 05:46:41 INFO - Cleaning up... 05:46:41 INFO - Return code: 0 05:46:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:46:41 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')]} 05:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 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')]} 05:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:41 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:41 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 05:46:41 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 05:46:41 INFO - Using env: {'DISPLAY': ':0', 05:46:41 INFO - 'HOME': '/home/cltbld', 05:46:41 INFO - 'LANG': 'en_US.UTF-8', 05:46:41 INFO - 'LANGUAGE': 'en_US:en', 05:46:41 INFO - 'LOGNAME': 'cltbld', 05:46:41 INFO - 'MAIL': '/var/mail/cltbld', 05:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:41 INFO - 'MOZ_NO_REMOTE': '1', 05:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:41 INFO - 'NO_EM_RESTART': '1', 05:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:41 INFO - 'SHELL': '/bin/bash', 05:46:41 INFO - 'SHLVL': '1', 05:46:41 INFO - 'TERM': 'linux', 05:46:41 INFO - 'TMOUT': '86400', 05:46:41 INFO - 'USER': 'cltbld', 05:46:41 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:41 INFO - '_': '/tools/buildbot/bin/python'} 05:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:41 INFO - Downloading/unpacking psutil>=0.7.1 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:43 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:46:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:46:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:43 INFO - Installing collected packages: psutil 05:46:43 INFO - Running setup.py install for psutil 05:46:43 INFO - building 'psutil._psutil_linux' extension 05:46:43 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 05:46:43 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 05:46:43 INFO - building 'psutil._psutil_posix' extension 05:46:43 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 05:46:43 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 05:46:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:43 INFO - Successfully installed psutil 05:46:43 INFO - Cleaning up... 05:46:43 INFO - Return code: 0 05:46:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:46: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')]} 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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')]} 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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', '--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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:43 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 05:46:43 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 05:46:43 INFO - Using env: {'DISPLAY': ':0', 05:46:43 INFO - 'HOME': '/home/cltbld', 05:46:43 INFO - 'LANG': 'en_US.UTF-8', 05:46:43 INFO - 'LANGUAGE': 'en_US:en', 05:46:43 INFO - 'LOGNAME': 'cltbld', 05:46:43 INFO - 'MAIL': '/var/mail/cltbld', 05:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:43 INFO - 'MOZ_NO_REMOTE': '1', 05:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:43 INFO - 'NO_EM_RESTART': '1', 05:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:43 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:43 INFO - 'SHELL': '/bin/bash', 05:46:43 INFO - 'SHLVL': '1', 05:46:43 INFO - 'TERM': 'linux', 05:46:43 INFO - 'TMOUT': '86400', 05:46:43 INFO - 'USER': 'cltbld', 05:46:43 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:43 INFO - '_': '/tools/buildbot/bin/python'} 05:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:46:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:46:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:46:45 INFO - Installing collected packages: mozsystemmonitor 05:46:45 INFO - Running setup.py install for mozsystemmonitor 05:46:45 INFO - Successfully installed mozsystemmonitor 05:46:45 INFO - Cleaning up... 05:46:46 INFO - Return code: 0 05:46:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:46: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')]} 05:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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')]} 05:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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', '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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:46: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 blobuploader==1.2.4 05:46:46 INFO - Using env: {'DISPLAY': ':0', 05:46:46 INFO - 'HOME': '/home/cltbld', 05:46:46 INFO - 'LANG': 'en_US.UTF-8', 05:46:46 INFO - 'LANGUAGE': 'en_US:en', 05:46:46 INFO - 'LOGNAME': 'cltbld', 05:46:46 INFO - 'MAIL': '/var/mail/cltbld', 05:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:46 INFO - 'MOZ_NO_REMOTE': '1', 05:46:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:46 INFO - 'NO_EM_RESTART': '1', 05:46:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:46 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:46 INFO - 'SHELL': '/bin/bash', 05:46:46 INFO - 'SHLVL': '1', 05:46:46 INFO - 'TERM': 'linux', 05:46:46 INFO - 'TMOUT': '86400', 05:46:46 INFO - 'USER': 'cltbld', 05:46:46 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:46 INFO - '_': '/tools/buildbot/bin/python'} 05:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:46 INFO - Downloading/unpacking blobuploader==1.2.4 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:46:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:46:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:46:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:48 INFO - Downloading docopt-0.6.1.tar.gz 05:46:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:46:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:48 INFO - Running setup.py install for blobuploader 05:46:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:48 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:46:48 INFO - Running setup.py install for requests 05:46:49 INFO - Running setup.py install for docopt 05:46:49 INFO - Successfully installed blobuploader requests docopt 05:46:49 INFO - Cleaning up... 05:46:49 INFO - Return code: 0 05:46:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:49 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 05:46:49 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 05:46:49 INFO - Using env: {'DISPLAY': ':0', 05:46:49 INFO - 'HOME': '/home/cltbld', 05:46:49 INFO - 'LANG': 'en_US.UTF-8', 05:46:49 INFO - 'LANGUAGE': 'en_US:en', 05:46:49 INFO - 'LOGNAME': 'cltbld', 05:46:49 INFO - 'MAIL': '/var/mail/cltbld', 05:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:49 INFO - 'MOZ_NO_REMOTE': '1', 05:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:49 INFO - 'NO_EM_RESTART': '1', 05:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:49 INFO - 'SHELL': '/bin/bash', 05:46:49 INFO - 'SHLVL': '1', 05:46:49 INFO - 'TERM': 'linux', 05:46:49 INFO - 'TMOUT': '86400', 05:46:49 INFO - 'USER': 'cltbld', 05:46:49 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:49 INFO - '_': '/tools/buildbot/bin/python'} 05:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:49 INFO - Running setup.py (path:/tmp/pip-CEefOK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:49 INFO - Running setup.py (path:/tmp/pip-4wUw45-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:49 INFO - Running setup.py (path:/tmp/pip-7VwnW3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:49 INFO - Running setup.py (path:/tmp/pip-z9JMkB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:49 INFO - Running setup.py (path:/tmp/pip-yy8RM8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:49 INFO - Running setup.py (path:/tmp/pip-vKGyWm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:49 INFO - Running setup.py (path:/tmp/pip-vzYwYC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:49 INFO - Running setup.py (path:/tmp/pip-IWzu8D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:50 INFO - Running setup.py (path:/tmp/pip-bbTh2a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:50 INFO - Running setup.py (path:/tmp/pip-U1fdtE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:50 INFO - Running setup.py (path:/tmp/pip-qhGbjM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:50 INFO - Running setup.py (path:/tmp/pip-MnXEUW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:50 INFO - Running setup.py (path:/tmp/pip-1ykdf6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:50 INFO - Running setup.py (path:/tmp/pip-dER7gv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:50 INFO - Running setup.py (path:/tmp/pip-PknRXg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:50 INFO - Running setup.py (path:/tmp/pip-QRFyJH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:50 INFO - Running setup.py (path:/tmp/pip-Xg07G6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:50 INFO - Running setup.py install for manifestparser 05:46:50 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:46:50 INFO - Running setup.py install for mozcrash 05:46:50 INFO - Running setup.py install for mozdebug 05:46:50 INFO - Running setup.py install for mozdevice 05:46:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for mozfile 05:46:51 INFO - Running setup.py install for mozhttpd 05:46:51 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for mozinfo 05:46:51 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for mozInstall 05:46:51 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for mozleak 05:46:51 INFO - Running setup.py install for mozlog 05:46:51 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for moznetwork 05:46:51 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:46:51 INFO - Running setup.py install for mozprocess 05:46:51 INFO - Running setup.py install for mozprofile 05:46:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:46:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:46:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:46:52 INFO - Running setup.py install for mozrunner 05:46:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:46:52 INFO - Running setup.py install for mozscreenshot 05:46:52 INFO - Running setup.py install for moztest 05:46:52 INFO - Running setup.py install for mozversion 05:46:52 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:46:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:52 INFO - Cleaning up... 05:46:52 INFO - Return code: 0 05:46:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:52 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')]} 05:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:52 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')]} 05:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:52 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:52 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 05:46:52 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 05:46:52 INFO - Using env: {'DISPLAY': ':0', 05:46:52 INFO - 'HOME': '/home/cltbld', 05:46:52 INFO - 'LANG': 'en_US.UTF-8', 05:46:52 INFO - 'LANGUAGE': 'en_US:en', 05:46:52 INFO - 'LOGNAME': 'cltbld', 05:46:52 INFO - 'MAIL': '/var/mail/cltbld', 05:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:52 INFO - 'MOZ_NO_REMOTE': '1', 05:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:52 INFO - 'NO_EM_RESTART': '1', 05:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:52 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:52 INFO - 'SHELL': '/bin/bash', 05:46:52 INFO - 'SHLVL': '1', 05:46:52 INFO - 'TERM': 'linux', 05:46:52 INFO - 'TMOUT': '86400', 05:46:52 INFO - 'USER': 'cltbld', 05:46:52 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:52 INFO - '_': '/tools/buildbot/bin/python'} 05:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:52 INFO - Running setup.py (path:/tmp/pip-TchYTA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:46:52 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)) 05:46:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:52 INFO - Running setup.py (path:/tmp/pip-Gr8TwK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:46:52 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)) 05:46:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:52 INFO - Running setup.py (path:/tmp/pip-vP5si_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:46:52 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)) 05:46:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:52 INFO - Running setup.py (path:/tmp/pip-LtDdfB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:53 INFO - Running setup.py (path:/tmp/pip-Tqxvd4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:53 INFO - Running setup.py (path:/tmp/pip-zdnnzc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:53 INFO - Running setup.py (path:/tmp/pip-94FzCe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:53 INFO - Running setup.py (path:/tmp/pip-04H5o0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:53 INFO - Running setup.py (path:/tmp/pip-tu8Mad-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:53 INFO - Running setup.py (path:/tmp/pip-ufmb3r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:53 INFO - Running setup.py (path:/tmp/pip-ttJtPo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:53 INFO - Running setup.py (path:/tmp/pip-FPqcJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:53 INFO - Running setup.py (path:/tmp/pip-ofhhQQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:53 INFO - Running setup.py (path:/tmp/pip-meitq4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:53 INFO - Running setup.py (path:/tmp/pip-t3wBqW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:53 INFO - Running setup.py (path:/tmp/pip-YBbLsV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:46:53 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)) 05:46:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:53 INFO - Running setup.py (path:/tmp/pip-PKSNaE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:46:53 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)) 05:46:53 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)) 05:46:53 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)) 05:46:53 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)) 05:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:55 INFO - Downloading blessings-1.5.1.tar.gz 05:46:55 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 05:46:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:55 INFO - Installing collected packages: blessings 05:46:55 INFO - Running setup.py install for blessings 05:46:55 INFO - Successfully installed blessings 05:46:55 INFO - Cleaning up... 05:46:55 INFO - Return code: 0 05:46:55 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:46:55 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:46:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:46:56 INFO - Reading from file tmpfile_stdout 05:46:56 INFO - Current package versions: 05:46:56 INFO - argparse == 1.2.1 05:46:56 INFO - blessings == 1.5.1 05:46:56 INFO - blobuploader == 1.2.4 05:46:56 INFO - docopt == 0.6.1 05:46:56 INFO - manifestparser == 1.1 05:46:56 INFO - mozInstall == 1.12 05:46:56 INFO - mozcrash == 0.16 05:46:56 INFO - mozdebug == 0.1 05:46:56 INFO - mozdevice == 0.48 05:46:56 INFO - mozfile == 1.2 05:46:56 INFO - mozhttpd == 0.7 05:46:56 INFO - mozinfo == 0.9 05:46:56 INFO - mozleak == 0.1 05:46:56 INFO - mozlog == 3.1 05:46:56 INFO - moznetwork == 0.27 05:46:56 INFO - mozprocess == 0.22 05:46:56 INFO - mozprofile == 0.28 05:46:56 INFO - mozrunner == 6.11 05:46:56 INFO - mozscreenshot == 0.1 05:46:56 INFO - mozsystemmonitor == 0.0 05:46:56 INFO - moztest == 0.7 05:46:56 INFO - mozversion == 1.4 05:46:56 INFO - psutil == 3.1.1 05:46:56 INFO - requests == 1.2.3 05:46:56 INFO - wsgiref == 0.1.2 05:46:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:56 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:56 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 05:46:56 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 05:46:56 INFO - Using env: {'DISPLAY': ':0', 05:46:56 INFO - 'HOME': '/home/cltbld', 05:46:56 INFO - 'LANG': 'en_US.UTF-8', 05:46:56 INFO - 'LANGUAGE': 'en_US:en', 05:46:56 INFO - 'LOGNAME': 'cltbld', 05:46:56 INFO - 'MAIL': '/var/mail/cltbld', 05:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:56 INFO - 'MOZ_NO_REMOTE': '1', 05:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:56 INFO - 'NO_EM_RESTART': '1', 05:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:56 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:56 INFO - 'SHELL': '/bin/bash', 05:46:56 INFO - 'SHLVL': '1', 05:46:56 INFO - 'TERM': 'linux', 05:46:56 INFO - 'TMOUT': '86400', 05:46:56 INFO - 'USER': 'cltbld', 05:46:56 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:56 INFO - '_': '/tools/buildbot/bin/python'} 05:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:46:56 INFO - Cleaning up... 05:46:56 INFO - Return code: 0 05:46:56 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:56 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:56 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 05:46:56 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 05:46:56 INFO - Using env: {'DISPLAY': ':0', 05:46:56 INFO - 'HOME': '/home/cltbld', 05:46:56 INFO - 'LANG': 'en_US.UTF-8', 05:46:56 INFO - 'LANGUAGE': 'en_US:en', 05:46:56 INFO - 'LOGNAME': 'cltbld', 05:46:56 INFO - 'MAIL': '/var/mail/cltbld', 05:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:56 INFO - 'MOZ_NO_REMOTE': '1', 05:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:56 INFO - 'NO_EM_RESTART': '1', 05:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:56 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:56 INFO - 'SHELL': '/bin/bash', 05:46:56 INFO - 'SHLVL': '1', 05:46:56 INFO - 'TERM': 'linux', 05:46:56 INFO - 'TMOUT': '86400', 05:46:56 INFO - 'USER': 'cltbld', 05:46:56 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:56 INFO - '_': '/tools/buildbot/bin/python'} 05:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:56 INFO - Downloading/unpacking jsonschema 05:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:58 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 05:46:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:46:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:58 INFO - warning: no files found matching '*.txt' 05:46:58 INFO - no previously-included directories found matching 'build' 05:46:58 INFO - no previously-included directories found matching 'dist' 05:46:58 INFO - no previously-included directories found matching '.git*' 05:46:58 INFO - Installing collected packages: jsonschema, functools32 05:46:58 INFO - Running setup.py install for functools32 05:46:58 INFO - warning: no files found matching '*.txt' 05:46:58 INFO - no previously-included directories found matching 'build' 05:46:58 INFO - no previously-included directories found matching 'dist' 05:46:58 INFO - no previously-included directories found matching '.git*' 05:46:58 INFO - Successfully installed jsonschema functools32 05:46:58 INFO - Cleaning up... 05:46:58 INFO - Return code: 0 05:46:58 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:46:58 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')]} 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 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')]} 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 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 0x7f55f665af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16588a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1728630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, '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': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', '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 05:46:58 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 05:46:58 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 05:46:58 INFO - Using env: {'DISPLAY': ':0', 05:46:58 INFO - 'HOME': '/home/cltbld', 05:46:58 INFO - 'LANG': 'en_US.UTF-8', 05:46:58 INFO - 'LANGUAGE': 'en_US:en', 05:46:58 INFO - 'LOGNAME': 'cltbld', 05:46:58 INFO - 'MAIL': '/var/mail/cltbld', 05:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:58 INFO - 'MOZ_NO_REMOTE': '1', 05:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:58 INFO - 'NO_EM_RESTART': '1', 05:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:46:58 INFO - 'PWD': '/builds/slave/test-pgo', 05:46:58 INFO - 'SHELL': '/bin/bash', 05:46:58 INFO - 'SHLVL': '1', 05:46:58 INFO - 'TERM': 'linux', 05:46:58 INFO - 'TMOUT': '86400', 05:46:58 INFO - 'USER': 'cltbld', 05:46:58 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:58 INFO - '_': '/tools/buildbot/bin/python'} 05:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:58 INFO - Downloading/unpacking flake8 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:47:00 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 05:47:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:47:00 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 05:47:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:00 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 05:47:01 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 05:47:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:47:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:47:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:47:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:47:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:47:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:47:01 INFO - no previously-included directories found matching 'docs/_build' 05:47:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:47:01 INFO - Running setup.py install for pep8 05:47:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:47:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:47:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:47:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:47:01 INFO - no previously-included directories found matching 'docs/_build' 05:47:01 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:47:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:47:01 INFO - Cleaning up... 05:47:01 INFO - Return code: 0 05:47:01 INFO - Running post-action listener: _resource_record_post_action 05:47:01 INFO - Running post-action listener: _start_resource_monitoring 05:47:01 INFO - Starting resource monitoring. 05:47:01 INFO - ##### 05:47:01 INFO - ##### Running install step. 05:47:01 INFO - ##### 05:47:01 INFO - Running pre-action listener: _resource_record_pre_action 05:47:01 INFO - Running main action method: install 05:47:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:47:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:47:01 INFO - Reading from file tmpfile_stdout 05:47:01 INFO - Detecting whether we're running mozinstall >=1.0... 05:47:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:47:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:47:01 INFO - Reading from file tmpfile_stdout 05:47:01 INFO - Output received: 05:47:01 INFO - Usage: mozinstall [options] installer 05:47:01 INFO - Options: 05:47:01 INFO - -h, --help show this help message and exit 05:47:01 INFO - -d DEST, --destination=DEST 05:47:01 INFO - Directory to install application into. [default: 05:47:01 INFO - "/builds/slave/test-pgo"] 05:47:01 INFO - --app=APP Application being installed. [default: firefox] 05:47:01 INFO - mkdir: /builds/slave/test-pgo/build/application 05:47:01 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'] 05:47:01 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 05:47:17 INFO - Reading from file tmpfile_stdout 05:47:17 INFO - Output received: 05:47:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:47:17 INFO - Running post-action listener: _resource_record_post_action 05:47:17 INFO - ##### 05:47:17 INFO - ##### Running run-tests step. 05:47:17 INFO - ##### 05:47:17 INFO - Running pre-action listener: _resource_record_pre_action 05:47:17 INFO - Running main action method: run_tests 05:47:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:47:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:47:17 INFO - Python 2.7.3 05:47:17 INFO - Return code: 0 05:47:17 INFO - grabbing minidump binary from tooltool 05:47: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')]} 05:47:17 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 0x17b9840>, '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 0x17266b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1726b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:47:17 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 05:47:17 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 05:47:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:18 INFO - Return code: 0 05:47:18 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:47:18 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:47:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:47:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:47:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:47:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:47:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:47:18 INFO - Return code: 0 05:47:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 05:47:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:47:18 INFO - Using env: {'DISPLAY': ':0', 05:47:18 INFO - 'HOME': '/home/cltbld', 05:47:18 INFO - 'LANG': 'en_US.UTF-8', 05:47:18 INFO - 'LANGUAGE': 'en_US:en', 05:47:18 INFO - 'LOGNAME': 'cltbld', 05:47:18 INFO - 'MAIL': '/var/mail/cltbld', 05:47:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:47:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:18 INFO - 'MOZ_NO_REMOTE': '1', 05:47:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:47:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:47:18 INFO - 'NO_EM_RESTART': '1', 05:47:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:47:18 INFO - 'PWD': '/builds/slave/test-pgo', 05:47:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:47:18 INFO - 'SHELL': '/bin/bash', 05:47:18 INFO - 'SHLVL': '1', 05:47:18 INFO - 'TERM': 'linux', 05:47:18 INFO - 'TMOUT': '86400', 05:47:18 INFO - 'USER': 'cltbld', 05:47:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923', 05:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:18 INFO - '_': '/tools/buildbot/bin/python'} 05:47:18 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:47:19 INFO - mozversion application_buildid: 20160119030232 05:47:19 INFO - mozversion application_changeset: b67316254602a63bf4e568198a5c7d3288a9db27 05:47:19 INFO - mozversion application_display_name: Nightly 05:47:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:19 INFO - mozversion application_name: Firefox 05:47:19 INFO - mozversion application_remotingname: firefox 05:47:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:47:19 INFO - mozversion application_vendor: Mozilla 05:47:19 INFO - mozversion application_version: 46.0a1 05:47:19 INFO - mozversion platform_buildid: 20160119030232 05:47:19 INFO - mozversion platform_changeset: b67316254602a63bf4e568198a5c7d3288a9db27 05:47:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:47:19 INFO - mozversion platform_version: 46.0a1 05:47:19 INFO - using testdate: 1453211239 05:47:19 INFO - actual date: 1453211239 05:47:19 INFO - starting webserver on 'localhost:55473' 05:47:19 INFO - SUITE-START | Running 3 tests 05:47:19 INFO - TEST-START | dromaeo_css 05:47:19 INFO - operating with platform_type : linux_ 05:47:19 INFO - Initialising browser for dromaeo_css test... 05:47:19 INFO - TEST-INFO | started process 11194 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjXJkI1/profile http://localhost:55473/getInfo.html) 05:47:22 INFO - PROCESS | 11194 | 05:47:22 INFO - PROCESS | 11194 | (process:11279): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:47:23 INFO - PROCESS | 11194 | __metrics Screen width/height:1600/1200 05:47:23 INFO - PROCESS | 11194 | colorDepth:24 05:47:23 INFO - PROCESS | 11194 | Browser inner width/height: 1024/697 05:47:23 INFO - PROCESS | 11194 | __metrics 05:47:23 INFO - PROCESS | 11194 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:47:24 INFO - TEST-INFO | 11194: exit 0 05:47:24 INFO - Browser initialized. 05:47:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:47:24 INFO - TEST-INFO | started process 11328 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjXJkI1/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:25 INFO - PROCESS | 11328 | -- checkerboarding starts below -- 05:47:25 INFO - PROCESS | 11328 | RENDERTRACE 0.055454 rect red 0 0 10 10 // viewport 05:47:25 INFO - PROCESS | 11328 | RENDERTRACE 0.574255 rect red 0 0 10 10 // viewport 05:47:25 INFO - PROCESS | 11328 | RENDERTRACE 17.1549 rect red 0 0 10 10 // viewport 05:47:25 INFO - PROCESS | 11328 | RENDERTRACE 48.0554 rect brown 0 0 10 10 // page 05:47:25 INFO - PROCESS | 11328 | RENDERTRACE 48.0769 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 05:47:25 INFO - PROCESS | 11328 | Checkerboarded for 3 frames (50.4044 ms), 100 peak, 1713 severity. 05:47:26 INFO - PROCESS | 11328 | 05:47:26 INFO - PROCESS | 11328 | (process:11387): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:47:26 INFO - PROCESS | 11328 | -- checkerboarding starts below -- 05:47:26 INFO - PROCESS | 11328 | RENDERTRACE 0.014086 rect red 0 0 1024 768 // viewport 05:47:26 INFO - PROCESS | 11328 | RENDERTRACE 2.79149 rect brown 0 0 1024 768 // page 05:47:26 INFO - PROCESS | 11328 | RENDERTRACE 2.80391 rect lightgreen 0 0 1024 768 // painted displayport (this layertree updated) 05:47:26 INFO - PROCESS | 11328 | Checkerboarded for 1 frames (16.0728 ms), 786432 peak, 6358 severity. 05:47:36 INFO - PROCESS | 11328 | RSS: Main: 150413312 05:47:36 INFO - PROCESS | 11328 | 05:48:26 INFO - PROCESS | 11328 | 1453211306314 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] 05:50:34 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55473/tests/dromaeo/cssquery-ext.html) 05:50:36 INFO - PROCESS | 11328 | RSS: Main: 155832320 05:50:36 INFO - PROCESS | 11328 | 05:53:38 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-ext.html (next: http://localhost:55473/tests/dromaeo/cssquery-jquery.html) 05:53:38 INFO - PROCESS | 11328 | RSS: Main: 151076864 05:53:38 INFO - PROCESS | 11328 | 05:56:40 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55473/tests/dromaeo/cssquery-mootools.html) 05:56:41 INFO - PROCESS | 11328 | RSS: Main: 150794240 05:56:41 INFO - PROCESS | 11328 | 05:59:43 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55473/tests/dromaeo/cssquery-prototype.html) 05:59:43 INFO - PROCESS | 11328 | RSS: Main: 150429696 05:59:43 INFO - PROCESS | 11328 | 06:02:46 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55473/tests/dromaeo/cssquery-yui.html) 06:02:46 INFO - PROCESS | 11328 | RSS: Main: 151195648 06:02:46 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | Cycle 1(1): loaded http://localhost:55473/tests/dromaeo/cssquery-yui.html (next: http://localhost:55473/tests/dromaeo/cssquery-dojo.html) 06:05:49 INFO - PROCESS | 11328 | RSS: Main: 153358336 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | __start_tp_report 06:05:49 INFO - PROCESS | 11328 | _x_x_mozilla_page_load 06:05:49 INFO - PROCESS | 11328 | _x_x_mozilla_page_load_details 06:05:49 INFO - PROCESS | 11328 | |i|pagename|runs| 06:05:49 INFO - PROCESS | 11328 | |0;dojo.html;3195.472746996745;3231.33757579696;3260.3316320154363;3267.3628642414737;3287.079617707043;5214.895702085956;5229.555487783538;5243.344581927255;5284.8150314739;5292.550133238675;5249.1601343785005;5258.684478931267;5261.447548007462;5358.33020872391;5392.78428862845;4797.688150270239;4850.88429661179;4863.173260545698;4901.803927842882;4906.681065730734;4734.479207287196;4901.191303434946;4901.779419926112;4913.238448040559;4927.457979622257;4774.618030557547;4838.419389673227;4846.151923413424;4848.927266091011;4893.730844803535;4694.483606803249;4725.432948046257;4757.61939044879;4761.59526440253;4815.5906747926465;4671.929921051187;4677.4854765975715;4720.575148236675;4754.524547545252;4795.2807078938085;5159.509846564571;5176.7411629418375;5192.71440070796;5194.402643695986;5203.0114278287;5687.232223649796;5726.198332233491;5737.426257374271;5761.683107429089;5785.537157027427;5610.915836262459;5705.4294570543025;5711.828645140652;5776.624519406225;5832.387599302055;5264.42091369949;5325.707086110262;5336.119540275849;5337.4662533746705;5341.866453338659;5802.448767367094;5809.970950145223;5810.709464526757;5811.651300921958;5813.418658134153;5398.514133727984;5491.28613280271;5583.911137328263;5609.270794796732;5659.745311460994;5695.031844586348;5698.088305871041;5706.42935706426;5712.171735098471;5718.828435146952;5416.539594134464;5611.102223644198;5668.631538949955;5668.858278543069;5670.177824215466;5552.69460179694;5649.4633009864;5669.036263835075;5703.458171473786;5748.310202775694;4384.101259241847;4405.867823965286;4406.537313582027;4418;4448.955510444919;3966.0481484444103;3980.164165525272;4006.1186538961383;4013.3177359848896;4043.555208927016;5228.581713462914;5297.708626025528;5328.893422131613;5382.407935127211;5385.946140538623;5656.971715141398;5680.517156041783;5696.344920334214;5704.315482142087;5721.799737009268;5211.270422140903;5344.3853956795465;5345.518903298722;5360.758765855546;5390.191471279261;4835.2505361669;4919.852404427873;4920.458749537548;4931.802727890844;4965.478624744422;5868;5893.174955506232;5893.263342082239;5893.6463812171405;5894.469497745224;5620.4098569649595;5647.6893770843;5671.376148623648;5695.288088988877;5744.396838331915;5915.349311575637;5917.082852157922;5923.170756094064;5937.435943585352;6000.0999850022845;5541.113421852484;5600.523955463831;5601.215829783752;5603;5615.859603509945;5609.186667933209;5683.573731970201;5684.261046064151;5703.828885133453;5710.314762228559;5468.863278417913;5515.338159420895;5573.108302671553;5625.493705566355;5662.292213473316;5522.6134170607675;5526.86182845416;5531.446855314436;5531.529819965348;5534.861628459321;5420.891582168256;5463.699496527729;5469.453054694499;5471.06991811401;5529.861753456034;5503.752331145028;5554.2779438672715;5559;5581.051221292309;5599.580031497572;5471.2887324647545;5472.288602481652;5473.562115030868;5488.69812160335;5504.366997795145 06:05:49 INFO - PROCESS | 11328 | |1;ext.html;525.4167873660239;529.8940211957608;545.3483087710185;549.3846891481542;563.5322682173796;36003.639963600355;37033.444498332516;37064.99999999996;37112.81443592785;37154.8142259289;39194;39672.60327396725;39681;39828.80085599568;39852.20295594086;8412.284955778767;8557.400981931267;8568.485890846538;8571.185737354941;8602.741917742447;8455.661773529051;8569.95715021424;8580.957095214515;8762.079981601935;8775.341849361292;8636.352273579476;8757.7810554736;8775.824483510325;8803.119688031178;8803.295736341077;8685.218330350268;8751.474911505331;8753.474791512466;8774.824503509926;8775;8616.052234254226;8749.343799215085;8749.387542871938;8758.562071896444;8835;39456.80271598652;39742.40386394206;39784;39812.20375592501;39925.40111898324;3564.8930532084078;3679.1537946272506;3950.7036972227197;4017.6384125428854;4036.495438070241;30037;30457.23856903573;30565;30581.08256752301;31153.532697009563;3933.6656384207377;3961.9281912915194;3969.8412063517426;3978.781167035812;4137.50349958004;219400;221204;224134;224166;224792;53733.73133134309;55291.170632440546;60898.39101609016;62585.68707156435;62938.68530657318;57288.713556431954;57291.71354143203;57337;57547;57580.71209643925;16138.515844524683;16159.757603635955;16241.2691428886;16338.591535211715;16367.83632163663;12359.814602780965;12383.133180677447;12444.440000199831;12527;12561;20113;20462.693059604117;20509.38471845847;20588.485287867923;20662;8039.196080392031;8108.878366824502;8155.34757219421;8269.917300827035;8349.8330033399;841.2260720137489;959.1128206409044;1007.033248081835;1031.5564307347763;1087.3041253597703;28088.157355279374;30767.2308192297;32840.67159328376;34050.48924266138;34370.82814585911;1000.1448761309122;1013.716159475348;1034.5551412892528;1047.3401756893109;1053.7365658585354;13972.371243294076;14005;14100.858991409954;14108.788368174486;14273.714525709427;295803;298596.50701746787;298682;300482;300956;53395.73302133464;54494.182587261224;54619;54663.18005229925;54853.434526755635;1362.271184916065;1372.1904076594858;1385.4804448331874;1386.3207028555935;1395.0260381559979;26183;26727.86636066807;27052.32369190786;27208.319792005357;31404.528932066496;49108.26337604867;49746.50253497563;49815.25277120918;49835.50164498309;49877;287055;287951;289109;290708;290802;42451.575484246;43186.56813431825;44103.558964409945;44259;44549;38877.80561097289;39804.80097599609;40619.1411815877;42561.78719106385;42975.355369670106;36122.45816312705;36488.63511364959;36639.45040824443;41408;42589.148217036105;40806.59193408028;41044.79477602593;41312;42275.3658695126;42531.1493770129;36896.63103368932;37099.44350834793;37268;39367.21265574615;39618.2076358477;37891.62108378881;39177.35431485311;40102.79948600355;40330.59669403268;40344.19311613693;27097.322566935985;27426.5886011706;27754.5836812452;28193.71806281911;28516.287092822844 06:05:49 INFO - PROCESS | 11328 | |2;jquery.html;364.2405584356616;367.470841987538;368.85430255049255;369.1288559000756;371.33716316375273;15623.765643515357;16965.15174241289;17207.22567484463;17617.55956101098;18232.817671823293;16239.431619893307;16247.918760406212;16678.749818752727;16800.159992000412;16833.32666693331;4756.429228492577;4970.627202959774;4979.203327467588;4986.675866068712;4989.825356112537;4803.039392121572;4914;4952.3561936948145;4955.529224723646;5006.724630145341;4936;4949.381327334083;5463.535599474049;5677.460641239076;5713.371529131792;5473.261109750192;5517.503424691757;5520.089185284423;5641.464060914206;5686.658800471964;5315.415304316522;5332.786688532454;5340.279062326614;5354.116570765819;5366.7853285868905;12625.684357891034;15056.32246548908;15515.45695900638;18944;19046.90476547622;2053.671412573981;2064.989675051615;2075.9481012974647;2199.9560008799895;2247.6291411917014;9642.566084526214;9892.268852950776;10037.598496060149;11042.558297668164;12450.626481205578;1797.505685936365;1813.3200049981258;1840.3650740494509;1864.5897902461436;1869.9252029918787;56751.432485675025;57169.71415142939;57267.71366143143;59442.405575944555;59937.7003114986;9909.157721593601;10226.59093636261;10246.924072972379;10340.37957722539;10409;13368.933155334162;13424.060315777802;13565.864341356657;13595;13615.183089014492;7398.630068496554;7410.444216683771;7496.700131994659;7730.690772369155;7818.843623127505;7078.823029424306;7233.819154521179;7269.491135620561;7321.707131714672;7356.816079598053;4489.708168969007;4490.550944905549;4507.233770259064;4509.909801804011;4529.09418116378;1847.3164928976364;1909.6849019911838;1931.6329897319465;2936.8678409471627;2942.8528573571234;913.59345091435;925.6760133953062;938.1931538876561;1009.9899001009895;1035.1822060572063;16312.184390780414;16442.095684737455;16735.49793506197;16821.158942052847;17111.315547378214;843.6751850537464;900.5497251374313;901.3014913442134;932.4778124250441;951.6050838901916;8653.17794809492;9244;9741.610335586638;10076;10083.848742268872;514630;516355;523640;525887;526163;67944.66027669929;70744.64627676932;71480.64259678773;74258;74965;1087.9782404352027;1121.8934201250868;1126.938018408979;1150.5972909481782;1165.557088306421;14750.12372587378;15247.771283430535;15359.539213823604;15501.84498155004;15760.842391576396;33960.66039339575;34095.31809363848;34972.300553989284;35463.645363546035;35497.1125721859;44690.55309446864;45090.549094509945;45765;46214.76892615516;46624.533754663375;3007.879684812583;3044.7868649194343;5456.536194423518;6400.239934017996;6781.711474819768;8026.077001144709;9384.718458446258;10181.694549163536;10455.895441045797;10598.099161571352;8848.955755221441;9083.727488175366;9119;9150.816983660423;9414.15272625467;9552.617895284111;9706.174975127193;9717.501699388073;9752.414855108433;9828.950855245677;9485.762855928657;9516.47659378713;9573.952130239304;9744.269179811401;10014.39913605186;7402.851942961003;7418.740344087931;7574.545527268381;7578.924210757822;7639.465237433326;9021.323400745101;9421.387609805615;9482.762930926781;9520.381175223589;9592.232621390132 06:05:49 INFO - PROCESS | 11328 | |3;mootools.html;1514.5153550863722;1519.7188520123775;1637;1752.535578071812;1752.97370539442;7749;7808.960955195216;7915.604219789009;7929.048514178299;7980.640871160797;7935.722249721261;8005.239502247292;8062.556559389232;8092.595370231495;8094.514329140241;2370.8933098010543;2374.287713685896;2374.893129809163;2391.258709799959;2394.365493144318;2320.3039088273536;2372.051179528194;2374.833761636686;2379.0721618568773;2391.5336509380677;2299.091858715806;2334.4630734930975;2356.552255071531;2357.740648528669;2363.61000434928;2295.2769877488536;2346.190564255338;2360.386299562109;2369.7037870266217;2385.5586716457424;2347.448349637834;2348.9295321140394;2351.2005917987967;2355.646653002046;2360.3980984848927;7720.382369410434;7740.690372385098;7775.650095745705;7777.961110194413;7785.493942893694;4787.305840653085;4894.216925291937;4901.240307752304;4902.730349830758;4910.410750709902;3230.3216324571868;3244.4160051190775;3349.2129349602824;3351.6648335166287;3361.1429085583263;1015.8780946286419;1029.3720830293514;1051.458498873081;1065.3341661461586;1079.7084787107515;11011.944940275327;11103.278286911325;11106.555737770479;11109.888901110966;11115.72210694739;4243.321068629005;4266.360045993064;4359.12817436514;4370.519242883281;4392.5607439256455;9074.500902450429;9076.047015063456;9091.136342047495;9091.954540227256;9138.451692898314;4784.856454306377;4791.113643975876;4834.492378300298;4839.225723884161;4854.393200849894;4352.630026447724;4357.54245804186;4365.257906155961;4381;4390.495093064274;3579.83890724918;3648.9817550912076;3653.908652283661;3657.2868290683596;3674.3202507536193;1117.6926345254958;1137.7895089408487;1139.1797905508022;1149.988500114988;1162.436218434059;1307.1503522710125;1313.1267706974754;1334.126147373472;1337.5920344295084;1350.257358452847;7504.137024242173;7514;7692.000039994765;7706.38348932084;7713.1515533291295;1104.2601457023814;1111.149970272742;1121.2450840933743;1131.3155540897803;1141.6004398460473;5890.7938222162875;5907;5923.526117910556;5974.074018527135;6000.969995149996;9228.40015398997;9722.319437639297;9731.90268097324;9746.561404736804;9760.511974401252;9720.951395242979;9755;9809.460479673686;9810.166135878388;9811.656592019388;7369.926300736924;7660.080790305087;7664.846703066019;7665.118511371152;7683.6926522939575;7282.12614486265;7284.053073100463;7352.816179595553;7377.631118444164;7378.040854688856;8714.825703486022;8721.476711397336;8750.212480876751;8842.867356989778;8954.507502087448;8471.279941205066;9098.272138229058;9116.453012819253;9121.543922803701;9158.679446219618;7152.141742990874;7175.103112110986;7233.638318083969;7257.0565826442225;7264.636768161677;7428.4800063995;7554.395648348009;7563.924360756322;7567.356774673995;7633.427492938163;6935.7225710971;7093.290670932865;7201.063861697946;7243.348098671145;7305.780826575211;6981.476389270927;6998.930010700032;7152.356287934111;7238.23998480152;7322.560646361236;6746.291639377795;7251.637418128967;7253;7265.12818461788;7305.561666300039;6499.447546958372;6547.410733034049;6558.114654521579;6560.245571759308;6575.145231119924;6577.210734711865;6603.405693487609;6611.140551728245;6632;6633.6351500666 06:05:49 INFO - PROCESS | 11328 | |4;prototype.html;338.6562638921495;341.45878782130325;342.4418198336711;343.6151510308455;350.0967503840093;6035.245594300702;6106.572539922202;6160.691965401728;6169.876602467959;6177.8764424711535;5938.643681379121;6061.272647282332;6064.787732429366;6073.210482637251;6105.664188469633;4692.7653617319165;4713.670043096985;4754.8098076077;4756.643251756115;4758.310045043467;4747.1692453820615;4749.90500189996;4750.833720819772;4751.073540659573;4759.048190361941;4466.798994045275;4605.217113090783;4605.516420775803;4611.907761844761;4727.78724957376;4561.908761824761;4571.725696458223;4602.769861506928;4610.515895830923;4617;3971.702122340836;3975.0658595230097;3991.4212439196153;4000;4072.816723247461;5701.344345400291;5729.684867332295;5778.739956701958;5784.82645520635;5791.3629500755305;5359.38367087786;5372.838814835562;5376.569874410038;5390.9191362129595;5395.082835917903;6401.807945761635;6440.6135631862235;6453.709583068773;6456.225252969627;6493.285738568754;4405.7797110144365;4502;4511.481179664348;4512.34570987205;4526.864194074183;6665.600063996128;6795.694193761293;6812.523123381366;6820.294926268433;6832.282610325944;4603.562661547147;4615.561521655437;4627.282771170474;4636.4436267647425;4639.907201856012;4613.100445413179;4620.306953956933;4687.835925742583;4689.531046895351;4691.390119284471;5422.3764267109;5423;5426.864328391822;5435.891282174334;5439.184122381675;6529;6558.672066396756;6566.343365663395;6566.80299591013;6586.473082153416;1658.5604814724109;1673.380849085846;1674.589725517256;1677.270387381493;1680.7478878168372;1109.916756243285;1116.0346300450053;1123.3989815448697;1135.4549816088158;1142.2917790969486;6194.721237544321;6208.937910620926;6223.96888015557;6237.282712488122;6262.279837818618;6793.252742198354;6845.794626161123;6847.931520684829;6855.108835851307;6859.314068593101;6144.139820425144;6204.844878877974;6234.937650623527;6246.15676883624;6252.062190671436;3665.138692407282;3696.31618150643;3697.1681371691157;3702.0929872181487;3702.407614781622;7025.718971241093;7129.928700713031;7137.357637812622;7141.714331426685;7153.427725781925;6948.861022779617;6992.61540615271;7000.369966703021;7000.684969176297;7011.1586609607175;7001.579905205704;7110.964445177844;7112.431005519649;7141.82145446368;7151.499395042401;6755.5946643199595;6766.15423072116;6810.250872404131;6812.557183783038;6841.144856892888;6735.326467353226;6737.73049078051;6745.831354215988;6746.224184218878;6749.3250674932115;6844.794656160323;7038.2609825969585;7131.536450130725;7143.892841607484;7170.314975980745;4600.355950167046;4637.25803871379;4661.044485880454;4682.4381074271305;4686.953130468788;6769.763058293133;6795.286494917962;6808.455323574003;6808.5021295314955;6824.454043676395;6483.157189565515;6579.111819904251;6594.274629790623;6627.204735431779;6630;6581.572197807127;6592;6594.802155935329;6601.250668572736;6602.3397660233595;6433.5496515243485;6439.034144878306;6456.257530384066;6467.870642587028;6493.740250389931;6389.169407976933;6526.836829079311;6580.802575922538;6592.901106483502;6630.569013014138;6427.646479443489;6476.255230648348;6478.3197764235965;6488.448481879093;6506.967465162644 06:05:49 INFO - PROCESS | 11328 | |5;yui.html;728.4136270302405;805.4436883766143;814.4136221920215;815.1755705484313;840.1472505407016;13417.396217170226;13520.864791352084;13560.254186019789;13602.795958060613;13610.047296689223;12559;12670.23978561287;12708.61874143775;12742.490300387974;12783.552575659854;814.8044469327364;829.7427797382801;829.8962629671291;831.8585840407145;832.1054866019024;814.3892080939296;816.302061737215;818.3003531980161;819;819.4223072733708;868.110187058264;868.7871471489494;869.3045563549167;871.4509858788986;871.5206636350014;859.2867919626726;860.359032520776;862.3661608717588;865.3596338709341;872.5026734761188;639.4788247578256;642.8939225027865;644.1014784375769;646.2858541311876;646.5958775765147;13871.4451421943;13887.79168312476;13903.721925561433;13912.095713778572;13998.720025599532;12320.568780092743;12553.93723031388;12647.747045059048;12668.113232073758;12687.492500299977;10133.949330253376;10138.391696498235;10175;10249.897501024969;10471.16230701542;3554.129238336624;3578.946315805265;3585.175409655768;3609.368360536922;3722.1997270586953;44350.33474497853;44957.32564011542;47401.52598474006;49345;49457.752711236215;10425.791484170275;10455.21585881062;10631.734206644742;10634.09610183143;10636.46817659114;17962.371317003843;18014.909925450287;18032.188551515213;18068.457946261635;18070.819291806914;7513.962430187814;7716.151223365426;7729.729459469004;7765.922340776633;7832.427100733159;4389.275769498061;4398.934015989698;4428.092241090569;4447.066116115652;4477.865664030084;3241.3517296540313;3286.112749557606;3288.7204587609845;3289.0297362278184;3295.044437113258;599.2658992733866;605.9272887253558;617.5831313863124;621.6177051113623;629.0689779126918;605.2464681471596;641.5701480008405;648.2577448821073;649.9740010399626;703.8944158376182;8516.70191543293;8978.371513994089;9884.604615815288;9960.80078398428;9972.252081093802;553.5156737854378;570.8201916396322;573.4752701278284;574.0585439878603;646.9061986012096;4022.255882661717;4051.6150965658217;4379.912401752011;4506.481754598198;4508.391367165391;54946.175807362924;55100;56884.43115568791;57189.71405142947;59159;45035;45089.54910450919;45169;45191.322130168075;47167;44929.10141797211;45861.31208031882;46676.29985550219;46749;48960.26559601609;8459.450135741157;8784.560771961504;9005.954970225106;9194.770130746518;9245.306602004743;31634.841825790725;32867.178320542174;33873;34526.65473345234;34533.48199776955;38474.422883657324;43036.569634304506;44027.33958990682;44278;45596;10918.72703182395;11075.169362298022;11105.888941110485;11217.60738374153;11413.714657133638;12293.877061229274;12401;12403.13178077562;12751.362431878555;13012.41444135035;11435.256708313933;11534.250273732181;11579.478923448296;11893.286402815858;12078.154529183224;11493.770124597628;11728;11885.405729713653;11977.461014254206;12226.938865305616;11228.550857965916;11283.492242849255;11312.773744525228;11650.475728592064;11696.649100526998;9140.771480712769;9174.26605871542;9225.835914542144;9259.907400925904;9733;9462.716118516455;9464.195543378615;9514;10080.143187828824;10138.746531336776 06:05:49 INFO - PROCESS | 11328 | __end_tp_report 06:05:49 INFO - PROCESS | 11328 | __start_cc_report 06:05:49 INFO - PROCESS | 11328 | _x_x_mozilla_cycle_collect,319 06:05:49 INFO - PROCESS | 11328 | __end_cc_report 06:05:49 INFO - PROCESS | 11328 | __startTimestamp1453212349361__endTimestamp 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | ------- Summary: start ------- 06:05:49 INFO - PROCESS | 11328 | Number of tests: 6 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#0] dojo.html Cycles:165 Average:5279.71 Median:5471.29 stddev:572.27 (10.5%) stddev-sans-first:550.17 06:05:49 INFO - PROCESS | 11328 | Values: 3195.5 3231.3 3260.3 3267.4 3287.1 5214.9 5229.6 5243.3 5284.8 5292.6 5249.2 5258.7 5261.4 5358.3 5392.8 4797.7 4850.9 4863.2 4901.8 4906.7 4734.5 4901.2 4901.8 4913.2 4927.5 4774.6 4838.4 4846.2 4848.9 4893.7 4694.5 4725.4 4757.6 4761.6 4815.6 4671.9 4677.5 4720.6 4754.5 4795.3 5159.5 5176.7 5192.7 5194.4 5203.0 5687.2 5726.2 5737.4 5761.7 5785.5 5610.9 5705.4 5711.8 5776.6 5832.4 5264.4 5325.7 5336.1 5337.5 5341.9 5802.4 5810.0 5810.7 5811.7 5813.4 5398.5 5491.3 5583.9 5609.3 5659.7 5695.0 5698.1 5706.4 5712.2 5718.8 5416.5 5611.1 5668.6 5668.9 5670.2 5552.7 5649.5 5669.0 5703.5 5748.3 4384.1 4405.9 4406.5 4418.0 4449.0 3966.0 3980.2 4006.1 4013.3 4043.6 5228.6 5297.7 5328.9 5382.4 5385.9 5657.0 5680.5 5696.3 5704.3 5721.8 5211.3 5344.4 5345.5 5360.8 5390.2 4835.3 4919.9 4920.5 4931.8 4965.5 5868.0 5893.2 5893.3 5893.6 5894.5 5620.4 5647.7 5671.4 5695.3 5744.4 5915.3 5917.1 5923.2 5937.4 6000.1 5541.1 5600.5 5601.2 5603.0 5615.9 5609.2 5683.6 5684.3 5703.8 5710.3 5468.9 5515.3 5573.1 5625.5 5662.3 5522.6 5526.9 5531.4 5531.5 5534.9 5420.9 5463.7 5469.5 5471.1 5529.9 5503.8 5554.3 5559.0 5581.1 5599.6 5471.3 5472.3 5473.6 5488.7 5504.4 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#1] ext.html Cycles:180 Average:45413.79 Median:39645.41 stddev:70892.00 (178.8%) stddev-sans-first:71010.75 06:05:49 INFO - PROCESS | 11328 | Values: 525.4 529.9 545.3 549.4 563.5 36003.6 37033.4 37065.0 37112.8 37154.8 39194.0 39672.6 39681.0 39828.8 39852.2 8412.3 8557.4 8568.5 8571.2 8602.7 8455.7 8570.0 8581.0 8762.1 8775.3 8636.4 8757.8 8775.8 8803.1 8803.3 8685.2 8751.5 8753.5 8774.8 8775.0 8616.1 8749.3 8749.4 8758.6 8835.0 39456.8 39742.4 39784.0 39812.2 39925.4 3564.9 3679.2 3950.7 4017.6 4036.5 30037.0 30457.2 30565.0 30581.1 31153.5 3933.7 3961.9 3969.8 3978.8 4137.5 219400.0 221204.0 224134.0 224166.0 224792.0 53733.7 55291.2 60898.4 62585.7 62938.7 57288.7 57291.7 57337.0 57547.0 57580.7 16138.5 16159.8 16241.3 16338.6 16367.8 12359.8 12383.1 12444.4 12527.0 12561.0 20113.0 20462.7 20509.4 20588.5 20662.0 8039.2 8108.9 8155.3 8269.9 8349.8 841.2 959.1 1007.0 1031.6 1087.3 28088.2 30767.2 32840.7 34050.5 34370.8 1000.1 1013.7 1034.6 1047.3 1053.7 13972.4 14005.0 14100.9 14108.8 14273.7 295803.0 298596.5 298682.0 300482.0 300956.0 53395.7 54494.2 54619.0 54663.2 54853.4 1362.3 1372.2 1385.5 1386.3 1395.0 26183.0 26727.9 27052.3 27208.3 31404.5 49108.3 49746.5 49815.3 49835.5 49877.0 287055.0 287951.0 289109.0 290708.0 290802.0 42451.6 43186.6 44103.6 44259.0 44549.0 38877.8 39804.8 40619.1 42561.8 42975.4 36122.5 36488.6 36639.5 41408.0 42589.1 40806.6 41044.8 41312.0 42275.4 42531.1 36896.6 37099.4 37268.0 39367.2 39618.2 37891.6 39177.4 40102.8 40330.6 40344.2 27097.3 27426.6 27754.6 28193.7 28516.3 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#2] jquery.html Cycles:180 Average:27023.56 Median:4942.69 stddev:85227.45 (1724.3%) stddev-sans-first:85443.03 06:05:49 INFO - PROCESS | 11328 | Values: 364.2 367.5 368.9 369.1 371.3 15623.8 16965.2 17207.2 17617.6 18232.8 16239.4 16247.9 16678.7 16800.2 16833.3 4756.4 4970.6 4979.2 4986.7 4989.8 4803.0 4914.0 4952.4 4955.5 5006.7 4936.0 4949.4 5463.5 5677.5 5713.4 5473.3 5517.5 5520.1 5641.5 5686.7 5315.4 5332.8 5340.3 5354.1 5366.8 12625.7 15056.3 15515.5 18944.0 19046.9 2053.7 2065.0 2075.9 2200.0 2247.6 9642.6 9892.3 10037.6 11042.6 12450.6 1797.5 1813.3 1840.4 1864.6 1869.9 56751.4 57169.7 57267.7 59442.4 59937.7 9909.2 10226.6 10246.9 10340.4 10409.0 13368.9 13424.1 13565.9 13595.0 13615.2 7398.6 7410.4 7496.7 7730.7 7818.8 7078.8 7233.8 7269.5 7321.7 7356.8 4489.7 4490.6 4507.2 4509.9 4529.1 1847.3 1909.7 1931.6 2936.9 2942.9 913.6 925.7 938.2 1010.0 1035.2 16312.2 16442.1 16735.5 16821.2 17111.3 843.7 900.5 901.3 932.5 951.6 8653.2 9244.0 9741.6 10076.0 10083.8 514630.0 516355.0 523640.0 525887.0 526163.0 67944.7 70744.6 71480.6 74258.0 74965.0 1088.0 1121.9 1126.9 1150.6 1165.6 14750.1 15247.8 15359.5 15501.8 15760.8 33960.7 34095.3 34972.3 35463.6 35497.1 44690.6 45090.5 45765.0 46214.8 46624.5 3007.9 3044.8 5456.5 6400.2 6781.7 8026.1 9384.7 10181.7 10455.9 10598.1 8849.0 9083.7 9119.0 9150.8 9414.2 9552.6 9706.2 9717.5 9752.4 9829.0 9485.8 9516.5 9574.0 9744.3 10014.4 7402.9 7418.7 7574.5 7578.9 7639.5 9021.3 9421.4 9482.8 9520.4 9592.2 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#3] mootools.html Cycles:180 Average:5621.61 Median:6523.43 stddev:2900.77 (44.5%) stddev-sans-first:2892.48 06:05:49 INFO - PROCESS | 11328 | Values: 1514.5 1519.7 1637.0 1752.5 1753.0 7749.0 7809.0 7915.6 7929.0 7980.6 7935.7 8005.2 8062.6 8092.6 8094.5 2370.9 2374.3 2374.9 2391.3 2394.4 2320.3 2372.1 2374.8 2379.1 2391.5 2299.1 2334.5 2356.6 2357.7 2363.6 2295.3 2346.2 2360.4 2369.7 2385.6 2347.4 2348.9 2351.2 2355.6 2360.4 7720.4 7740.7 7775.7 7778.0 7785.5 4787.3 4894.2 4901.2 4902.7 4910.4 3230.3 3244.4 3349.2 3351.7 3361.1 1015.9 1029.4 1051.5 1065.3 1079.7 11011.9 11103.3 11106.6 11109.9 11115.7 4243.3 4266.4 4359.1 4370.5 4392.6 9074.5 9076.0 9091.1 9092.0 9138.5 4784.9 4791.1 4834.5 4839.2 4854.4 4352.6 4357.5 4365.3 4381.0 4390.5 3579.8 3649.0 3653.9 3657.3 3674.3 1117.7 1137.8 1139.2 1150.0 1162.4 1307.2 1313.1 1334.1 1337.6 1350.3 7504.1 7514.0 7692.0 7706.4 7713.2 1104.3 1111.1 1121.2 1131.3 1141.6 5890.8 5907.0 5923.5 5974.1 6001.0 9228.4 9722.3 9731.9 9746.6 9760.5 9721.0 9755.0 9809.5 9810.2 9811.7 7369.9 7660.1 7664.8 7665.1 7683.7 7282.1 7284.1 7352.8 7377.6 7378.0 8714.8 8721.5 8750.2 8842.9 8954.5 8471.3 9098.3 9116.5 9121.5 9158.7 7152.1 7175.1 7233.6 7257.1 7264.6 7428.5 7554.4 7563.9 7567.4 7633.4 6935.7 7093.3 7201.1 7243.3 7305.8 6981.5 6998.9 7152.4 7238.2 7322.6 6746.3 7251.6 7253.0 7265.1 7305.6 6499.4 6547.4 6558.1 6560.2 6575.1 6577.2 6603.4 6611.1 6632.0 6633.6 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#4] prototype.html Cycles:180 Average:5473.50 Median:6186.30 stddev:1665.54 (26.9%) stddev-sans-first:1625.01 06:05:49 INFO - PROCESS | 11328 | Values: 338.7 341.5 342.4 343.6 350.1 6035.2 6106.6 6160.7 6169.9 6177.9 5938.6 6061.3 6064.8 6073.2 6105.7 4692.8 4713.7 4754.8 4756.6 4758.3 4747.2 4749.9 4750.8 4751.1 4759.0 4466.8 4605.2 4605.5 4611.9 4727.8 4561.9 4571.7 4602.8 4610.5 4617.0 3971.7 3975.1 3991.4 4000.0 4072.8 5701.3 5729.7 5778.7 5784.8 5791.4 5359.4 5372.8 5376.6 5390.9 5395.1 6401.8 6440.6 6453.7 6456.2 6493.3 4405.8 4502.0 4511.5 4512.3 4526.9 6665.6 6795.7 6812.5 6820.3 6832.3 4603.6 4615.6 4627.3 4636.4 4639.9 4613.1 4620.3 4687.8 4689.5 4691.4 5422.4 5423.0 5426.9 5435.9 5439.2 6529.0 6558.7 6566.3 6566.8 6586.5 1658.6 1673.4 1674.6 1677.3 1680.7 1109.9 1116.0 1123.4 1135.5 1142.3 6194.7 6208.9 6224.0 6237.3 6262.3 6793.3 6845.8 6847.9 6855.1 6859.3 6144.1 6204.8 6234.9 6246.2 6252.1 3665.1 3696.3 3697.2 3702.1 3702.4 7025.7 7129.9 7137.4 7141.7 7153.4 6948.9 6992.6 7000.4 7000.7 7011.2 7001.6 7111.0 7112.4 7141.8 7151.5 6755.6 6766.2 6810.3 6812.6 6841.1 6735.3 6737.7 6745.8 6746.2 6749.3 6844.8 7038.3 7131.5 7143.9 7170.3 4600.4 4637.3 4661.0 4682.4 4687.0 6769.8 6795.3 6808.5 6808.5 6824.5 6483.2 6579.1 6594.3 6627.2 6630.0 6581.6 6592.0 6594.8 6601.3 6602.3 6433.5 6439.0 6456.3 6467.9 6493.7 6389.2 6526.8 6580.8 6592.9 6630.6 6427.6 6476.3 6478.3 6488.4 6507.0 06:05:49 INFO - PROCESS | 11328 | 06:05:49 INFO - PROCESS | 11328 | [#5] yui.html Cycles:180 Average:13616.47 Median:44943.21 stddev:15319.80 (34.1%) stddev-sans-first:15332.20 06:05:49 INFO - PROCESS | 11328 | Values: 728.4 805.4 814.4 815.2 840.1 13417.4 13520.9 13560.3 13602.8 13610.0 12559.0 12670.2 12708.6 12742.5 12783.6 814.8 829.7 829.9 831.9 832.1 814.4 816.3 818.3 819.0 819.4 868.1 868.8 869.3 871.5 871.5 859.3 860.4 862.4 865.4 872.5 639.5 642.9 644.1 646.3 646.6 13871.4 13887.8 13903.7 13912.1 13998.7 12320.6 12553.9 12647.7 12668.1 12687.5 10133.9 10138.4 10175.0 10249.9 10471.2 3554.1 3578.9 3585.2 3609.4 3722.2 44350.3 44957.3 47401.5 49345.0 49457.8 10425.8 10455.2 10631.7 10634.1 10636.5 17962.4 18014.9 18032.2 18068.5 18070.8 7514.0 7716.2 7729.7 7765.9 7832.4 4389.3 4398.9 4428.1 4447.1 4477.9 3241.4 3286.1 3288.7 3289.0 3295.0 599.3 605.9 617.6 621.6 629.1 605.2 641.6 648.3 650.0 703.9 8516.7 8978.4 9884.6 9960.8 9972.3 553.5 570.8 573.5 574.1 646.9 4022.3 4051.6 4379.9 4506.5 4508.4 54946.2 55100.0 56884.4 57189.7 59159.0 45035.0 45089.5 45169.0 45191.3 47167.0 44929.1 45861.3 46676.3 46749.0 48960.3 8459.5 8784.6 9006.0 9194.8 9245.3 31634.8 32867.2 33873.0 34526.7 34533.5 38474.4 43036.6 44027.3 44278.0 45596.0 10918.7 11075.2 11105.9 11217.6 11413.7 12293.9 12401.0 12403.1 12751.4 13012.4 11435.3 11534.3 11579.5 11893.3 12078.2 11493.8 11728.0 11885.4 11977.5 12226.9 11228.6 11283.5 11312.8 11650.5 11696.6 9140.8 9174.3 9225.8 9259.9 9733.0 9462.7 9464.2 9514.0 10080.1 10138.7 06:05:49 INFO - PROCESS | 11328 | -------- Summary: end -------- 06:05:49 INFO - PROCESS | 11328 | 06:05:50 INFO - TEST-INFO | 11328: exit 0 06:05:50 INFO - TEST-OK | dromaeo_css | took 1110871ms 06:05:50 INFO - TEST-START | kraken 06:05:50 INFO - operating with platform_type : linux_ 06:05:50 INFO - Initialising browser for kraken test... 06:05:50 INFO - TEST-INFO | started process 11570 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzVPzN2/profile http://localhost:55473/getInfo.html) 06:05:53 INFO - PROCESS | 11570 | 06:05:53 INFO - PROCESS | 11570 | (process:11642): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:05:54 INFO - PROCESS | 11570 | __metrics Screen width/height:1600/1200 06:05:54 INFO - PROCESS | 11570 | colorDepth:24 06:05:54 INFO - PROCESS | 11570 | Browser inner width/height: 1024/697 06:05:54 INFO - PROCESS | 11570 | __metrics 06:05:54 INFO - PROCESS | 11570 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 06:05:55 INFO - TEST-INFO | 11570: exit 0 06:05:55 INFO - Browser initialized. 06:05:55 INFO - Running cycle 1/1 for kraken test... 06:05:55 INFO - TEST-INFO | started process 11691 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzVPzN2/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:56 INFO - PROCESS | 11691 | -- checkerboarding starts below -- 06:05:56 INFO - PROCESS | 11691 | RENDERTRACE 0.051629 rect red 0 0 10 10 // viewport 06:05:56 INFO - PROCESS | 11691 | RENDERTRACE 3.80482 rect red 0 0 10 10 // viewport 06:05:56 INFO - PROCESS | 11691 | RENDERTRACE 68.9608 rect brown 0 0 10 10 // page 06:05:56 INFO - PROCESS | 11691 | RENDERTRACE 68.9854 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 06:05:56 INFO - PROCESS | 11691 | Checkerboarded for 2 frames (70.3315 ms), 100 peak, 379 severity. 06:05:56 INFO - PROCESS | 11691 | 06:05:56 INFO - PROCESS | 11691 | (process:11749): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:06:07 INFO - PROCESS | 11691 | RSS: Main: 150347776 06:06:07 INFO - PROCESS | 11691 | 06:06:09 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55473/tests/kraken/driver.html?testName=audio-beat-detection) 06:06:10 INFO - PROCESS | 11691 | RSS: Main: 150654976 06:06:10 INFO - PROCESS | 11691 | 06:06:13 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55473/tests/kraken/driver.html?testName=audio-dft) 06:06:13 INFO - PROCESS | 11691 | RSS: Main: 154578944 06:06:13 INFO - PROCESS | 11691 | 06:06:16 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55473/tests/kraken/driver.html?testName=audio-fft) 06:06:17 INFO - PROCESS | 11691 | RSS: Main: 148316160 06:06:17 INFO - PROCESS | 11691 | 06:06:19 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55473/tests/kraken/driver.html?testName=audio-oscillator) 06:06:19 INFO - PROCESS | 11691 | RSS: Main: 148029440 06:06:19 INFO - PROCESS | 11691 | 06:06:22 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55473/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:22 INFO - PROCESS | 11691 | RSS: Main: 148979712 06:06:22 INFO - PROCESS | 11691 | 06:06:25 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55473/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:26 INFO - PROCESS | 11691 | RSS: Main: 147722240 06:06:26 INFO - PROCESS | 11691 | 06:06:29 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55473/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:30 INFO - PROCESS | 11691 | RSS: Main: 148504576 06:06:30 INFO - PROCESS | 11691 | 06:06:33 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55473/tests/kraken/driver.html?testName=json-parse-financial) 06:06:33 INFO - PROCESS | 11691 | RSS: Main: 148242432 06:06:33 INFO - PROCESS | 11691 | 06:06:35 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55473/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:35 INFO - PROCESS | 11691 | RSS: Main: 149901312 06:06:35 INFO - PROCESS | 11691 | 06:06:37 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:38 INFO - PROCESS | 11691 | RSS: Main: 150003712 06:06:38 INFO - PROCESS | 11691 | 06:06:40 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:40 INFO - PROCESS | 11691 | RSS: Main: 150376448 06:06:40 INFO - PROCESS | 11691 | 06:06:43 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:43 INFO - PROCESS | 11691 | RSS: Main: 148520960 06:06:43 INFO - PROCESS | 11691 | 06:06:46 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:47 INFO - PROCESS | 11691 | RSS: Main: 148860928 06:06:47 INFO - PROCESS | 11691 | 06:06:48 INFO - PROCESS | 11691 | Cycle 1(1): loaded http://localhost:55473/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55473/tests/kraken/driver.html?testName=ai-astar) 06:06:49 INFO - PROCESS | 11691 | RSS: Main: 150093824 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | __start_tp_report 06:06:49 INFO - PROCESS | 11691 | _x_x_mozilla_page_load 06:06:49 INFO - PROCESS | 11691 | _x_x_mozilla_page_load_details 06:06:49 INFO - PROCESS | 11691 | |i|pagename|runs| 06:06:49 INFO - PROCESS | 11691 | |0;ai-astar;111;106;109;110;111;126;111;110;110;110 06:06:49 INFO - PROCESS | 11691 | |1;audio-beat-detection;153;128;161;170;127;159;155;128;175;127 06:06:49 INFO - PROCESS | 11691 | |2;audio-dft;162;161;164;165;161;162;162;164;169;163 06:06:49 INFO - PROCESS | 11691 | |3;audio-fft;73;73;73;74;74;76;135;74;73;79 06:06:49 INFO - PROCESS | 11691 | |4;audio-oscillator;96;95;143;96;96;97;143;96;98;144 06:06:49 INFO - PROCESS | 11691 | |5;imaging-gaussian-blur;110;110;111;110;111;110;110;110;110;110 06:06:49 INFO - PROCESS | 11691 | |6;imaging-darkroom;149;147;146;146;149;148;147;147;148;146 06:06:49 INFO - PROCESS | 11691 | |7;imaging-desaturate;98;96;98;96;97;97;96;96;97;97 06:06:49 INFO - PROCESS | 11691 | |8;json-parse-financial;61;60;60;58;60;65;61;60;68;57 06:06:49 INFO - PROCESS | 11691 | |9;json-stringify-tinderbox;70;67;67;68;68;68;68;74;71;68 06:06:49 INFO - PROCESS | 11691 | |10;stanford-crypto-aes;83;82;86;82;83;83;82;89;82;83 06:06:49 INFO - PROCESS | 11691 | |11;stanford-crypto-ccm;115;126;119;117;120;123;123;121;115;121 06:06:49 INFO - PROCESS | 11691 | |12;stanford-crypto-pbkdf2;148;153;158;145;141;140;146;141;145;146 06:06:49 INFO - PROCESS | 11691 | |13;stanford-crypto-sha256-iterative;67;67;67;66;69;65;67;67;69;68 06:06:49 INFO - PROCESS | 11691 | __end_tp_report 06:06:49 INFO - PROCESS | 11691 | __start_cc_report 06:06:49 INFO - PROCESS | 11691 | _x_x_mozilla_cycle_collect,727 06:06:49 INFO - PROCESS | 11691 | __end_cc_report 06:06:49 INFO - PROCESS | 11691 | __startTimestamp1453212409075__endTimestamp 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | ------- Summary: start ------- 06:06:49 INFO - PROCESS | 11691 | Number of tests: 14 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#0] ai-astar Cycles:10 Average:111.40 Median:110.50 stddev:5.34 (4.8%) stddev-sans-first:5.66 06:06:49 INFO - PROCESS | 11691 | Values: 111.0 106.0 109.0 110.0 111.0 126.0 111.0 110.0 110.0 110.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:157.00 stddev:19.03 (12.1%) stddev-sans-first:20.10 06:06:49 INFO - PROCESS | 11691 | Values: 153.0 128.0 161.0 170.0 127.0 159.0 155.0 128.0 175.0 127.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:2.41 (1.5%) stddev-sans-first:2.51 06:06:49 INFO - PROCESS | 11691 | Values: 162.0 161.0 164.0 165.0 161.0 162.0 162.0 164.0 169.0 163.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#3] audio-fft Cycles:10 Average:80.40 Median:74.00 stddev:19.28 (26.0%) stddev-sans-first:20.26 06:06:49 INFO - PROCESS | 11691 | Values: 73.0 73.0 73.0 74.0 74.0 76.0 135.0 74.0 73.0 79.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:96.00 stddev:22.74 (23.7%) stddev-sans-first:23.52 06:06:49 INFO - PROCESS | 11691 | Values: 96.0 95.0 143.0 96.0 96.0 97.0 143.0 96.0 98.0 144.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:06:49 INFO - PROCESS | 11691 | Values: 110.0 110.0 111.0 110.0 111.0 110.0 110.0 110.0 110.0 110.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:1.16 (0.8%) stddev-sans-first:1.05 06:06:49 INFO - PROCESS | 11691 | Values: 149.0 147.0 146.0 146.0 149.0 148.0 147.0 147.0 148.0 146.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.71 06:06:49 INFO - PROCESS | 11691 | Values: 98.0 96.0 98.0 96.0 97.0 97.0 96.0 96.0 97.0 97.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:60.50 stddev:3.23 (5.3%) stddev-sans-first:3.43 06:06:49 INFO - PROCESS | 11691 | Values: 61.0 60.0 60.0 58.0 60.0 65.0 61.0 60.0 68.0 57.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:68.00 stddev:2.18 (3.2%) stddev-sans-first:2.28 06:06:49 INFO - PROCESS | 11691 | Values: 70.0 67.0 67.0 68.0 68.0 68.0 68.0 74.0 71.0 68.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.00 stddev:2.27 (2.7%) stddev-sans-first:2.40 06:06:49 INFO - PROCESS | 11691 | Values: 83.0 82.0 86.0 82.0 83.0 83.0 82.0 89.0 82.0 83.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#11] stanford-crypto-ccm Cycles:10 Average:120.00 Median:121.00 stddev:3.59 (3.0%) stddev-sans-first:3.32 06:06:49 INFO - PROCESS | 11691 | Values: 115.0 126.0 119.0 117.0 120.0 123.0 123.0 121.0 115.0 121.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.30 Median:146.00 stddev:5.62 (3.8%) stddev-sans-first:5.93 06:06:49 INFO - PROCESS | 11691 | Values: 148.0 153.0 158.0 145.0 141.0 140.0 146.0 141.0 145.0 146.0 06:06:49 INFO - PROCESS | 11691 | 06:06:49 INFO - PROCESS | 11691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 06:06:49 INFO - PROCESS | 11691 | Values: 67.0 67.0 67.0 66.0 69.0 65.0 67.0 67.0 69.0 68.0 06:06:49 INFO - PROCESS | 11691 | -------- Summary: end -------- 06:06:49 INFO - PROCESS | 11691 | 06:06:50 INFO - TEST-INFO | 11691: exit 0 06:06:50 INFO - TEST-OK | kraken | took 60066ms 06:06:50 INFO - TEST-START | v8_7 06:06:50 INFO - operating with platform_type : linux_ 06:06:50 INFO - Initialising browser for v8_7 test... 06:06:50 INFO - TEST-INFO | started process 11977 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7H1YCG/profile http://localhost:55473/getInfo.html) 06:06:53 INFO - PROCESS | 11977 | 06:06:53 INFO - PROCESS | 11977 | (process:12049): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:06:54 INFO - PROCESS | 11977 | __metrics Screen width/height:1600/1200 06:06:54 INFO - PROCESS | 11977 | colorDepth:24 06:06:54 INFO - PROCESS | 11977 | Browser inner width/height: 1024/697 06:06:54 INFO - PROCESS | 11977 | __metrics 06:06:54 INFO - PROCESS | 11977 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 06:06:55 INFO - TEST-INFO | 11977: exit 0 06:06:55 INFO - Browser initialized. 06:06:55 INFO - Running cycle 1/1 for v8_7 test... 06:06:55 INFO - TEST-INFO | started process 12098 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7H1YCG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:56 INFO - PROCESS | 12098 | -- checkerboarding starts below -- 06:06:56 INFO - PROCESS | 12098 | RENDERTRACE 0.050179 rect red 0 0 10 10 // viewport 06:06:56 INFO - PROCESS | 12098 | RENDERTRACE 87.8223 rect brown 0 0 10 10 // page 06:06:56 INFO - PROCESS | 12098 | RENDERTRACE 87.8524 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 06:06:56 INFO - PROCESS | 12098 | Checkerboarded for 1 frames (100.043 ms), 100 peak, 1 severity. 06:06:57 INFO - PROCESS | 12098 | 06:06:57 INFO - PROCESS | 12098 | (process:12154): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 06:07:07 INFO - PROCESS | 12098 | RSS: Main: 149798912 06:07:07 INFO - PROCESS | 12098 | 06:07:29 INFO - PROCESS | 12098 | Cycle 1(1): loaded http://localhost:55473/tests/v8_7/run.html (next: http://localhost:55473/tests/v8_7/run.html) 06:07:30 INFO - PROCESS | 12098 | RSS: Main: 146853888 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | __start_tp_report 06:07:30 INFO - PROCESS | 12098 | _x_x_mozilla_page_load 06:07:30 INFO - PROCESS | 12098 | _x_x_mozilla_page_load_details 06:07:30 INFO - PROCESS | 12098 | |i|pagename|runs| 06:07:30 INFO - PROCESS | 12098 | |0;Richards;167.81339150864238 06:07:30 INFO - PROCESS | 12098 | |1;DeltaBlue;187.79342723004694 06:07:30 INFO - PROCESS | 12098 | |2;Encrypt;279.09572983533354 06:07:30 INFO - PROCESS | 12098 | |3;Decrypt;5202.072538860104 06:07:30 INFO - PROCESS | 12098 | |4;RayTrace;1058.2010582010582 06:07:30 INFO - PROCESS | 12098 | |5;Earley;704.2253521126761 06:07:30 INFO - PROCESS | 12098 | |6;Boyer;12219.512195121952 06:07:30 INFO - PROCESS | 12098 | |7;RegExp;42541.666666666664 06:07:30 INFO - PROCESS | 12098 | |8;Splay;600.3594967046135 06:07:30 INFO - PROCESS | 12098 | |9;NavierStokes;4826.923076923077 06:07:30 INFO - PROCESS | 12098 | __end_tp_report 06:07:30 INFO - PROCESS | 12098 | __startTimestamp1453212450159__endTimestamp 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | ------- Summary: start ------- 06:07:30 INFO - PROCESS | 12098 | Number of tests: 10 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#0] Richards Cycles:1 Average:167.81 Median:167.81 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 167.8 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#1] DeltaBlue Cycles:1 Average:187.79 Median:187.79 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 187.8 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 279.1 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 5202.1 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#4] RayTrace Cycles:1 Average:1058.20 Median:1058.20 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 1058.2 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#5] Earley Cycles:1 Average:704.23 Median:704.23 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 704.2 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#6] Boyer Cycles:1 Average:12219.51 Median:12219.51 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 12219.5 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#7] RegExp Cycles:1 Average:42541.67 Median:42541.67 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 42541.7 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#8] Splay Cycles:1 Average:600.36 Median:600.36 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 600.4 06:07:30 INFO - PROCESS | 12098 | 06:07:30 INFO - PROCESS | 12098 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 06:07:30 INFO - PROCESS | 12098 | Values: 4826.9 06:07:30 INFO - PROCESS | 12098 | -------- Summary: end -------- 06:07:30 INFO - PROCESS | 12098 | 06:07:31 INFO - TEST-INFO | 12098: exit 0 06:07:31 INFO - TEST-OK | v8_7 | took 40956ms 06:07:31 INFO - SUITE-END | took 1211s 06:07:31 INFO - Completed test suite (00:20:12) 06:07:31 INFO - Working with test: dromaeo_css 06:07:31 INFO - Generating results file: dromaeo_css 06:07:31 INFO - Working with test: kraken 06:07:31 INFO - Generating results file: kraken 06:07:31 INFO - javascript benchmark 06:07:31 INFO - Working with test: v8_7 06:07:31 INFO - Generating results file: v8_7 06:07:31 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:31 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 06:07:31 INFO - process_Request line: dromaeo_css 6993.90 graph.html#tests=[[72,1,43]] 06:07:31 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:31 INFO - process_Request line: kraken 1515.00 graph.html#tests=[[232,1,43]] 06:07:31 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:07:31 INFO - process_Request line: v8_7 20374.72 graph.html#tests=[[230,1,43]] 06:07:31 INFO - RETURN: dromaeo_css: 6993.90 06:07:31 INFO - RETURN: kraken: 1515.00 06:07:31 INFO - RETURN: v8_7: 20374.72 06:07:31 INFO - javascript benchmark 06:07:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3195.472746996745, 3231.33757579696, 3260.3316320154363, 3267.3628642414737, 3287.079617707043, 5214.895702085956, 5229.555487783538, 5243.344581927255, 5284.8150314739, 5292.550133238675, 5249.1601343785005, 5258.684478931267, 5261.447548007462, 5358.33020872391, 5392.78428862845, 4797.688150270239, 4850.88429661179, 4863.173260545698, 4901.803927842882, 4906.681065730734, 4734.479207287196, 4901.191303434946, 4901.779419926112, 4913.238448040559, 4927.457979622257, 4774.618030557547, 4838.419389673227, 4846.151923413424, 4848.927266091011, 4893.730844803535, 4694.483606803249, 4725.432948046257, 4757.61939044879, 4761.59526440253, 4815.5906747926465, 4671.929921051187, 4677.4854765975715, 4720.575148236675, 4754.524547545252, 4795.2807078938085, 5159.509846564571, 5176.7411629418375, 5192.71440070796, 5194.402643695986, 5203.0114278287, 5687.232223649796, 5726.198332233491, 5737.426257374271, 5761.683107429089, 5785.537157027427, 5610.915836262459, 5705.4294570543025, 5711.828645140652, 5776.624519406225, 5832.387599302055, 5264.42091369949, 5325.707086110262, 5336.119540275849, 5337.4662533746705, 5341.866453338659, 5802.448767367094, 5809.970950145223, 5810.709464526757, 5811.651300921958, 5813.418658134153, 5398.514133727984, 5491.28613280271, 5583.911137328263, 5609.270794796732, 5659.745311460994, 5695.031844586348, 5698.088305871041, 5706.42935706426, 5712.171735098471, 5718.828435146952, 5416.539594134464, 5611.102223644198, 5668.631538949955, 5668.858278543069, 5670.177824215466, 5552.69460179694, 5649.4633009864, 5669.036263835075, 5703.458171473786, 5748.310202775694, 4384.101259241847, 4405.867823965286, 4406.537313582027, 4418.0, 4448.955510444919, 3966.0481484444103, 3980.164165525272, 4006.1186538961383, 4013.3177359848896, 4043.555208927016, 5228.581713462914, 5297.708626025528, 5328.893422131613, 5382.407935127211, 5385.946140538623, 5656.971715141398, 5680.517156041783, 5696.344920334214, 5704.315482142087, 5721.799737009268, 5211.270422140903, 5344.3853956795465, 5345.518903298722, 5360.758765855546, 5390.191471279261, 4835.2505361669, 4919.852404427873, 4920.458749537548, 4931.802727890844, 4965.478624744422, 5868.0, 5893.174955506232, 5893.263342082239, 5893.6463812171405, 5894.469497745224, 5620.4098569649595, 5647.6893770843, 5671.376148623648, 5695.288088988877, 5744.396838331915, 5915.349311575637, 5917.082852157922, 5923.170756094064, 5937.435943585352, 6000.0999850022845, 5541.113421852484, 5600.523955463831, 5601.215829783752, 5603.0, 5615.859603509945, 5609.186667933209, 5683.573731970201, 5684.261046064151, 5703.828885133453, 5710.314762228559, 5468.863278417913, 5515.338159420895, 5573.108302671553, 5625.493705566355, 5662.292213473316, 5522.6134170607675, 5526.86182845416, 5531.446855314436, 5531.529819965348, 5534.861628459321, 5420.891582168256, 5463.699496527729, 5469.453054694499, 5471.06991811401, 5529.861753456034, 5503.752331145028, 5554.2779438672715, 5559.0, 5581.051221292309, 5599.580031497572, 5471.2887324647545, 5472.288602481652, 5473.562115030868, 5488.69812160335, 5504.366997795145], "unit": "score", "name": "dojo.html", "value": 5243.935124082914}, {"lowerIsBetter": false, "replicates": [525.4167873660239, 529.8940211957608, 545.3483087710185, 549.3846891481542, 563.5322682173796, 36003.639963600355, 37033.444498332516, 37064.99999999996, 37112.81443592785, 37154.8142259289, 39194.0, 39672.60327396725, 39681.0, 39828.80085599568, 39852.20295594086, 8412.284955778767, 8557.400981931267, 8568.485890846538, 8571.185737354941, 8602.741917742447, 8455.661773529051, 8569.95715021424, 8580.957095214515, 8762.079981601935, 8775.341849361292, 8636.352273579476, 8757.7810554736, 8775.824483510325, 8803.119688031178, 8803.295736341077, 8685.218330350268, 8751.474911505331, 8753.474791512466, 8774.824503509926, 8775.0, 8616.052234254226, 8749.343799215085, 8749.387542871938, 8758.562071896444, 8835.0, 39456.80271598652, 39742.40386394206, 39784.0, 39812.20375592501, 39925.40111898324, 3564.8930532084078, 3679.1537946272506, 3950.7036972227197, 4017.6384125428854, 4036.495438070241, 30037.0, 30457.23856903573, 30565.0, 30581.08256752301, 31153.532697009563, 3933.6656384207377, 3961.9281912915194, 3969.8412063517426, 3978.781167035812, 4137.50349958004, 219400.0, 221204.0, 224134.0, 224166.0, 224792.0, 53733.73133134309, 55291.170632440546, 60898.39101609016, 62585.68707156435, 62938.68530657318, 57288.713556431954, 57291.71354143203, 57337.0, 57547.0, 57580.71209643925, 16138.515844524683, 16159.757603635955, 16241.2691428886, 16338.591535211715, 16367.83632163663, 12359.814602780965, 12383.133180677447, 12444.440000199831, 12527.0, 12561.0, 20113.0, 20462.693059604117, 20509.38471845847, 20588.485287867923, 20662.0, 8039.196080392031, 8108.878366824502, 8155.34757219421, 8269.917300827035, 8349.8330033399, 841.2260720137489, 959.1128206409044, 1007.033248081835, 1031.5564307347763, 1087.3041253597703, 28088.157355279374, 30767.2308192297, 32840.67159328376, 34050.48924266138, 34370.82814585911, 1000.1448761309122, 1013.716159475348, 1034.5551412892528, 1047.3401756893109, 1053.7365658585354, 13972.371243294076, 14005.0, 14100.858991409954, 14108.788368174486, 14273.714525709427, 295803.0, 298596.50701746787, 298682.0, 300482.0, 300956.0, 53395.73302133464, 54494.182587261224, 54619.0, 54663.18005229925, 54853.434526755635, 1362.271184916065, 1372.1904076594858, 1385.4804448331874, 1386.3207028555935, 1395.0260381559979, 26183.0, 26727.86636066807, 27052.32369190786, 27208.319792005357, 31404.528932066496, 49108.26337604867, 49746.50253497563, 49815.25277120918, 49835.50164498309, 49877.0, 287055.0, 287951.0, 289109.0, 290708.0, 290802.0, 42451.575484246, 43186.56813431825, 44103.558964409945, 44259.0, 44549.0, 38877.80561097289, 39804.80097599609, 40619.1411815877, 42561.78719106385, 42975.355369670106, 36122.45816312705, 36488.63511364959, 36639.45040824443, 41408.0, 42589.148217036105, 40806.59193408028, 41044.79477602593, 41312.0, 42275.3658695126, 42531.1493770129, 36896.63103368932, 37099.44350834793, 37268.0, 39367.21265574615, 39618.2076358477, 37891.62108378881, 39177.35431485311, 40102.79948600355, 40330.59669403268, 40344.19311613693, 27097.322566935985, 27426.5886011706, 27754.5836812452, 28193.71806281911, 28516.287092822844], "unit": "score", "name": "ext.html", "value": 18918.102326965432}, {"lowerIsBetter": false, "replicates": [364.2405584356616, 367.470841987538, 368.85430255049255, 369.1288559000756, 371.33716316375273, 15623.765643515357, 16965.15174241289, 17207.22567484463, 17617.55956101098, 18232.817671823293, 16239.431619893307, 16247.918760406212, 16678.749818752727, 16800.159992000412, 16833.32666693331, 4756.429228492577, 4970.627202959774, 4979.203327467588, 4986.675866068712, 4989.825356112537, 4803.039392121572, 4914.0, 4952.3561936948145, 4955.529224723646, 5006.724630145341, 4936.0, 4949.381327334083, 5463.535599474049, 5677.460641239076, 5713.371529131792, 5473.261109750192, 5517.503424691757, 5520.089185284423, 5641.464060914206, 5686.658800471964, 5315.415304316522, 5332.786688532454, 5340.279062326614, 5354.116570765819, 5366.7853285868905, 12625.684357891034, 15056.32246548908, 15515.45695900638, 18944.0, 19046.90476547622, 2053.671412573981, 2064.989675051615, 2075.9481012974647, 2199.9560008799895, 2247.6291411917014, 9642.566084526214, 9892.268852950776, 10037.598496060149, 11042.558297668164, 12450.626481205578, 1797.505685936365, 1813.3200049981258, 1840.3650740494509, 1864.5897902461436, 1869.9252029918787, 56751.432485675025, 57169.71415142939, 57267.71366143143, 59442.405575944555, 59937.7003114986, 9909.157721593601, 10226.59093636261, 10246.924072972379, 10340.37957722539, 10409.0, 13368.933155334162, 13424.060315777802, 13565.864341356657, 13595.0, 13615.183089014492, 7398.630068496554, 7410.444216683771, 7496.700131994659, 7730.690772369155, 7818.843623127505, 7078.823029424306, 7233.819154521179, 7269.491135620561, 7321.707131714672, 7356.816079598053, 4489.708168969007, 4490.550944905549, 4507.233770259064, 4509.909801804011, 4529.09418116378, 1847.3164928976364, 1909.6849019911838, 1931.6329897319465, 2936.8678409471627, 2942.8528573571234, 913.59345091435, 925.6760133953062, 938.1931538876561, 1009.9899001009895, 1035.1822060572063, 16312.184390780414, 16442.095684737455, 16735.49793506197, 16821.158942052847, 17111.315547378214, 843.6751850537464, 900.5497251374313, 901.3014913442134, 932.4778124250441, 951.6050838901916, 8653.17794809492, 9244.0, 9741.610335586638, 10076.0, 10083.848742268872, 514630.0, 516355.0, 523640.0, 525887.0, 526163.0, 67944.66027669929, 70744.64627676932, 71480.64259678773, 74258.0, 74965.0, 1087.9782404352027, 1121.8934201250868, 1126.938018408979, 1150.5972909481782, 1165.557088306421, 14750.12372587378, 15247.771283430535, 15359.539213823604, 15501.84498155004, 15760.842391576396, 33960.66039339575, 34095.31809363848, 34972.300553989284, 35463.645363546035, 35497.1125721859, 44690.55309446864, 45090.549094509945, 45765.0, 46214.76892615516, 46624.533754663375, 3007.879684812583, 3044.7868649194343, 5456.536194423518, 6400.239934017996, 6781.711474819768, 8026.077001144709, 9384.718458446258, 10181.694549163536, 10455.895441045797, 10598.099161571352, 8848.955755221441, 9083.727488175366, 9119.0, 9150.816983660423, 9414.15272625467, 9552.617895284111, 9706.174975127193, 9717.501699388073, 9752.414855108433, 9828.950855245677, 9485.762855928657, 9516.47659378713, 9573.952130239304, 9744.269179811401, 10014.39913605186, 7402.851942961003, 7418.740344087931, 7574.545527268381, 7578.924210757822, 7639.465237433326, 9021.323400745101, 9421.387609805615, 9482.762930926781, 9520.381175223589, 9592.232621390132], "unit": "score", "name": "jquery.html", "value": 8108.564057601875}, {"lowerIsBetter": false, "replicates": [1514.5153550863722, 1519.7188520123775, 1637.0, 1752.535578071812, 1752.97370539442, 7749.0, 7808.960955195216, 7915.604219789009, 7929.048514178299, 7980.640871160797, 7935.722249721261, 8005.239502247292, 8062.556559389232, 8092.595370231495, 8094.514329140241, 2370.8933098010543, 2374.287713685896, 2374.893129809163, 2391.258709799959, 2394.365493144318, 2320.3039088273536, 2372.051179528194, 2374.833761636686, 2379.0721618568773, 2391.5336509380677, 2299.091858715806, 2334.4630734930975, 2356.552255071531, 2357.740648528669, 2363.61000434928, 2295.2769877488536, 2346.190564255338, 2360.386299562109, 2369.7037870266217, 2385.5586716457424, 2347.448349637834, 2348.9295321140394, 2351.2005917987967, 2355.646653002046, 2360.3980984848927, 7720.382369410434, 7740.690372385098, 7775.650095745705, 7777.961110194413, 7785.493942893694, 4787.305840653085, 4894.216925291937, 4901.240307752304, 4902.730349830758, 4910.410750709902, 3230.3216324571868, 3244.4160051190775, 3349.2129349602824, 3351.6648335166287, 3361.1429085583263, 1015.8780946286419, 1029.3720830293514, 1051.458498873081, 1065.3341661461586, 1079.7084787107515, 11011.944940275327, 11103.278286911325, 11106.555737770479, 11109.888901110966, 11115.72210694739, 4243.321068629005, 4266.360045993064, 4359.12817436514, 4370.519242883281, 4392.5607439256455, 9074.500902450429, 9076.047015063456, 9091.136342047495, 9091.954540227256, 9138.451692898314, 4784.856454306377, 4791.113643975876, 4834.492378300298, 4839.225723884161, 4854.393200849894, 4352.630026447724, 4357.54245804186, 4365.257906155961, 4381.0, 4390.495093064274, 3579.83890724918, 3648.9817550912076, 3653.908652283661, 3657.2868290683596, 3674.3202507536193, 1117.6926345254958, 1137.7895089408487, 1139.1797905508022, 1149.988500114988, 1162.436218434059, 1307.1503522710125, 1313.1267706974754, 1334.126147373472, 1337.5920344295084, 1350.257358452847, 7504.137024242173, 7514.0, 7692.000039994765, 7706.38348932084, 7713.1515533291295, 1104.2601457023814, 1111.149970272742, 1121.2450840933743, 1131.3155540897803, 1141.6004398460473, 5890.7938222162875, 5907.0, 5923.526117910556, 5974.074018527135, 6000.969995149996, 9228.40015398997, 9722.319437639297, 9731.90268097324, 9746.561404736804, 9760.511974401252, 9720.951395242979, 9755.0, 9809.460479673686, 9810.166135878388, 9811.656592019388, 7369.926300736924, 7660.080790305087, 7664.846703066019, 7665.118511371152, 7683.6926522939575, 7282.12614486265, 7284.053073100463, 7352.816179595553, 7377.631118444164, 7378.040854688856, 8714.825703486022, 8721.476711397336, 8750.212480876751, 8842.867356989778, 8954.507502087448, 8471.279941205066, 9098.272138229058, 9116.453012819253, 9121.543922803701, 9158.679446219618, 7152.141742990874, 7175.103112110986, 7233.638318083969, 7257.0565826442225, 7264.636768161677, 7428.4800063995, 7554.395648348009, 7563.924360756322, 7567.356774673995, 7633.427492938163, 6935.7225710971, 7093.290670932865, 7201.063861697946, 7243.348098671145, 7305.780826575211, 6981.476389270927, 6998.930010700032, 7152.356287934111, 7238.23998480152, 7322.560646361236, 6746.291639377795, 7251.637418128967, 7253.0, 7265.12818461788, 7305.561666300039, 6499.447546958372, 6547.410733034049, 6558.114654521579, 6560.245571759308, 6575.145231119924, 6577.210734711865, 6603.405693487609, 6611.140551728245, 6632.0, 6633.6351500666], "unit": "score", "name": "mootools.html", "value": 4631.215239964978}, {"lowerIsBetter": false, "replicates": [338.6562638921495, 341.45878782130325, 342.4418198336711, 343.6151510308455, 350.0967503840093, 6035.245594300702, 6106.572539922202, 6160.691965401728, 6169.876602467959, 6177.8764424711535, 5938.643681379121, 6061.272647282332, 6064.787732429366, 6073.210482637251, 6105.664188469633, 4692.7653617319165, 4713.670043096985, 4754.8098076077, 4756.643251756115, 4758.310045043467, 4747.1692453820615, 4749.90500189996, 4750.833720819772, 4751.073540659573, 4759.048190361941, 4466.798994045275, 4605.217113090783, 4605.516420775803, 4611.907761844761, 4727.78724957376, 4561.908761824761, 4571.725696458223, 4602.769861506928, 4610.515895830923, 4617.0, 3971.702122340836, 3975.0658595230097, 3991.4212439196153, 4000.0, 4072.816723247461, 5701.344345400291, 5729.684867332295, 5778.739956701958, 5784.82645520635, 5791.3629500755305, 5359.38367087786, 5372.838814835562, 5376.569874410038, 5390.9191362129595, 5395.082835917903, 6401.807945761635, 6440.6135631862235, 6453.709583068773, 6456.225252969627, 6493.285738568754, 4405.7797110144365, 4502.0, 4511.481179664348, 4512.34570987205, 4526.864194074183, 6665.600063996128, 6795.694193761293, 6812.523123381366, 6820.294926268433, 6832.282610325944, 4603.562661547147, 4615.561521655437, 4627.282771170474, 4636.4436267647425, 4639.907201856012, 4613.100445413179, 4620.306953956933, 4687.835925742583, 4689.531046895351, 4691.390119284471, 5422.3764267109, 5423.0, 5426.864328391822, 5435.891282174334, 5439.184122381675, 6529.0, 6558.672066396756, 6566.343365663395, 6566.80299591013, 6586.473082153416, 1658.5604814724109, 1673.380849085846, 1674.589725517256, 1677.270387381493, 1680.7478878168372, 1109.916756243285, 1116.0346300450053, 1123.3989815448697, 1135.4549816088158, 1142.2917790969486, 6194.721237544321, 6208.937910620926, 6223.96888015557, 6237.282712488122, 6262.279837818618, 6793.252742198354, 6845.794626161123, 6847.931520684829, 6855.108835851307, 6859.314068593101, 6144.139820425144, 6204.844878877974, 6234.937650623527, 6246.15676883624, 6252.062190671436, 3665.138692407282, 3696.31618150643, 3697.1681371691157, 3702.0929872181487, 3702.407614781622, 7025.718971241093, 7129.928700713031, 7137.357637812622, 7141.714331426685, 7153.427725781925, 6948.861022779617, 6992.61540615271, 7000.369966703021, 7000.684969176297, 7011.1586609607175, 7001.579905205704, 7110.964445177844, 7112.431005519649, 7141.82145446368, 7151.499395042401, 6755.5946643199595, 6766.15423072116, 6810.250872404131, 6812.557183783038, 6841.144856892888, 6735.326467353226, 6737.73049078051, 6745.831354215988, 6746.224184218878, 6749.3250674932115, 6844.794656160323, 7038.2609825969585, 7131.536450130725, 7143.892841607484, 7170.314975980745, 4600.355950167046, 4637.25803871379, 4661.044485880454, 4682.4381074271305, 4686.953130468788, 6769.763058293133, 6795.286494917962, 6808.455323574003, 6808.5021295314955, 6824.454043676395, 6483.157189565515, 6579.111819904251, 6594.274629790623, 6627.204735431779, 6630.0, 6581.572197807127, 6592.0, 6594.802155935329, 6601.250668572736, 6602.3397660233595, 6433.5496515243485, 6439.034144878306, 6456.257530384066, 6467.870642587028, 6493.740250389931, 6389.169407976933, 6526.836829079311, 6580.802575922538, 6592.901106483502, 6630.569013014138, 6427.646479443489, 6476.255230648348, 6478.3197764235965, 6488.448481879093, 6506.967465162644], "unit": "score", "name": "prototype.html", "value": 4933.649058099134}, {"lowerIsBetter": false, "replicates": [728.4136270302405, 805.4436883766143, 814.4136221920215, 815.1755705484313, 840.1472505407016, 13417.396217170226, 13520.864791352084, 13560.254186019789, 13602.795958060613, 13610.047296689223, 12559.0, 12670.23978561287, 12708.61874143775, 12742.490300387974, 12783.552575659854, 814.8044469327364, 829.7427797382801, 829.8962629671291, 831.8585840407145, 832.1054866019024, 814.3892080939296, 816.302061737215, 818.3003531980161, 819.0, 819.4223072733708, 868.110187058264, 868.7871471489494, 869.3045563549167, 871.4509858788986, 871.5206636350014, 859.2867919626726, 860.359032520776, 862.3661608717588, 865.3596338709341, 872.5026734761188, 639.4788247578256, 642.8939225027865, 644.1014784375769, 646.2858541311876, 646.5958775765147, 13871.4451421943, 13887.79168312476, 13903.721925561433, 13912.095713778572, 13998.720025599532, 12320.568780092743, 12553.93723031388, 12647.747045059048, 12668.113232073758, 12687.492500299977, 10133.949330253376, 10138.391696498235, 10175.0, 10249.897501024969, 10471.16230701542, 3554.129238336624, 3578.946315805265, 3585.175409655768, 3609.368360536922, 3722.1997270586953, 44350.33474497853, 44957.32564011542, 47401.52598474006, 49345.0, 49457.752711236215, 10425.791484170275, 10455.21585881062, 10631.734206644742, 10634.09610183143, 10636.46817659114, 17962.371317003843, 18014.909925450287, 18032.188551515213, 18068.457946261635, 18070.819291806914, 7513.962430187814, 7716.151223365426, 7729.729459469004, 7765.922340776633, 7832.427100733159, 4389.275769498061, 4398.934015989698, 4428.092241090569, 4447.066116115652, 4477.865664030084, 3241.3517296540313, 3286.112749557606, 3288.7204587609845, 3289.0297362278184, 3295.044437113258, 599.2658992733866, 605.9272887253558, 617.5831313863124, 621.6177051113623, 629.0689779126918, 605.2464681471596, 641.5701480008405, 648.2577448821073, 649.9740010399626, 703.8944158376182, 8516.70191543293, 8978.371513994089, 9884.604615815288, 9960.80078398428, 9972.252081093802, 553.5156737854378, 570.8201916396322, 573.4752701278284, 574.0585439878603, 646.9061986012096, 4022.255882661717, 4051.6150965658217, 4379.912401752011, 4506.481754598198, 4508.391367165391, 54946.175807362924, 55100.0, 56884.43115568791, 57189.71405142947, 59159.0, 45035.0, 45089.54910450919, 45169.0, 45191.322130168075, 47167.0, 44929.10141797211, 45861.31208031882, 46676.29985550219, 46749.0, 48960.26559601609, 8459.450135741157, 8784.560771961504, 9005.954970225106, 9194.770130746518, 9245.306602004743, 31634.841825790725, 32867.178320542174, 33873.0, 34526.65473345234, 34533.48199776955, 38474.422883657324, 43036.569634304506, 44027.33958990682, 44278.0, 45596.0, 10918.72703182395, 11075.169362298022, 11105.888941110485, 11217.60738374153, 11413.714657133638, 12293.877061229274, 12401.0, 12403.13178077562, 12751.362431878555, 13012.41444135035, 11435.256708313933, 11534.250273732181, 11579.478923448296, 11893.286402815858, 12078.154529183224, 11493.770124597628, 11728.0, 11885.405729713653, 11977.461014254206, 12226.938865305616, 11228.550857965916, 11283.492242849255, 11312.773744525228, 11650.475728592064, 11696.649100526998, 9140.771480712769, 9174.26605871542, 9225.835914542144, 9259.907400925904, 9733.0, 9462.716118516455, 9464.195543378615, 9514.0, 10080.143187828824, 10138.746531336776], "unit": "score", "name": "yui.html", "value": 6367.026598997268}], "name": "dromaeo_css", "value": 6993.897225547043}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 109.0, 110.0, 111.0, 126.0, 111.0, 110.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.4}, {"lowerIsBetter": true, "replicates": [153.0, 128.0, 161.0, 170.0, 127.0, 159.0, 155.0, 128.0, 175.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 148.3}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 164.0, 165.0, 161.0, 162.0, 162.0, 164.0, 169.0, 163.0], "unit": "score", "name": "audio-dft", "value": 163.3}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 74.0, 74.0, 76.0, 135.0, 74.0, 73.0, 79.0], "unit": "score", "name": "audio-fft", "value": 80.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 143.0, 96.0, 96.0, 97.0, 143.0, 96.0, 98.0, 144.0], "unit": "score", "name": "audio-oscillator", "value": 110.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [149.0, 147.0, 146.0, 146.0, 149.0, 148.0, 147.0, 147.0, 148.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 147.3}, {"lowerIsBetter": true, "replicates": [98.0, 96.0, 98.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.8}, {"lowerIsBetter": true, "replicates": [61.0, 60.0, 60.0, 58.0, 60.0, 65.0, 61.0, 60.0, 68.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 61.0}, {"lowerIsBetter": true, "replicates": [70.0, 67.0, 67.0, 68.0, 68.0, 68.0, 68.0, 74.0, 71.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 86.0, 82.0, 83.0, 83.0, 82.0, 89.0, 82.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.5}, {"lowerIsBetter": true, "replicates": [115.0, 126.0, 119.0, 117.0, 120.0, 123.0, 123.0, 121.0, 115.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.0}, {"lowerIsBetter": true, "replicates": [148.0, 153.0, 158.0, 145.0, 141.0, 140.0, 146.0, 141.0, 145.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.3}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 66.0, 69.0, 65.0, 67.0, 67.0, 69.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.2}], "name": "kraken", "value": 1515.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.81339150864238], "unit": "score", "name": "Richards", "value": 210.36461799999995}, {"lowerIsBetter": false, "replicates": [187.79342723004694], "unit": "score", "name": "DeltaBlue", "value": 352.0783499999999}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1058.2010582010582], "unit": "score", "name": "RayTrace", "value": 699.2896049999997}, {"lowerIsBetter": false, "replicates": [704.2253521126761], "unit": "score", "name": "Earley", "value": 946.3774600000002}, {"lowerIsBetter": false, "replicates": [12219.512195121952], "unit": "score", "name": "Boyer", "value": 54.540884231536886}, {"lowerIsBetter": false, "replicates": [42541.666666666664], "unit": "score", "name": "RegExp", "value": 21.413947110675796}, {"lowerIsBetter": false, "replicates": [600.3594967046135], "unit": "score", "name": "Splay", "value": 135.73700499001993}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 20374.723593832725}]} 06:07:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "20374.72"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1515.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6993.90"}}} 06:07:31 INFO - Return code: 0 06:07:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:07:31 INFO - # TBPL SUCCESS # 06:07:31 INFO - Running post-action listener: _resource_record_post_action 06:07:31 INFO - Running post-run listener: _resource_record_post_run 06:07:32 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 16384; Write bytes: 553242624; Read time: 40; Write time: 439792 06:07:32 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236642304; Read time: 0; Write time: 226440 06:07:32 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 16384; Write bytes: 315736064; Read time: 40; Write time: 213316 06:07:32 INFO - Running post-run listener: _upload_blobber_files 06:07:32 INFO - Blob upload gear active. 06:07:32 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 06:07:32 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:07:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 06:07:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 06:07:33 INFO - (blobuploader) - INFO - Open directory for files ... 06:07:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:07:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:33 INFO - (blobuploader) - INFO - Done attempting. 06:07:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:07:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:07:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:34 INFO - (blobuploader) - INFO - Done attempting. 06:07:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:07:34 INFO - Return code: 0 06:07:34 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 06:07:34 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 06:07:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96ee2f75ebb8ce17edd1e676cf6ce38784c8a0236cd8e74310f9ca3bc3caf5b46e5a23f520f3bb6219acaa637764933c5fa3b72a90b496b01d36ee5800948572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a0f7bc1469b532409f688f8e6e6931e3c3ee8364f2b165f786c619291ec68b9ed9aaf23ae8c53bc775808feb2172e2a7b7c6d02db773fab0436fc41ddc8aa3"} 06:07:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 06:07:34 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 06:07:34 INFO - Contents: 06:07:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96ee2f75ebb8ce17edd1e676cf6ce38784c8a0236cd8e74310f9ca3bc3caf5b46e5a23f520f3bb6219acaa637764933c5fa3b72a90b496b01d36ee5800948572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a0f7bc1469b532409f688f8e6e6931e3c3ee8364f2b165f786c619291ec68b9ed9aaf23ae8c53bc775808feb2172e2a7b7c6d02db773fab0436fc41ddc8aa3"} 06:07:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:07:34 INFO - Copying logs to upload dir... 06:07:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 06:07:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.819652 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-01-19 06:07:34.451320) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-19 06:07:34.455067) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96ee2f75ebb8ce17edd1e676cf6ce38784c8a0236cd8e74310f9ca3bc3caf5b46e5a23f520f3bb6219acaa637764933c5fa3b72a90b496b01d36ee5800948572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a0f7bc1469b532409f688f8e6e6931e3c3ee8364f2b165f786c619291ec68b9ed9aaf23ae8c53bc775808feb2172e2a7b7c6d02db773fab0436fc41ddc8aa3"} build_url:https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/cctg1QTUS8KRZRNaEiIqRg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96ee2f75ebb8ce17edd1e676cf6ce38784c8a0236cd8e74310f9ca3bc3caf5b46e5a23f520f3bb6219acaa637764933c5fa3b72a90b496b01d36ee5800948572", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89a0f7bc1469b532409f688f8e6e6931e3c3ee8364f2b165f786c619291ec68b9ed9aaf23ae8c53bc775808feb2172e2a7b7c6d02db773fab0436fc41ddc8aa3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-19 06:07:34.479284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 06:07:34.479585) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1453209584.464095-111043923 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004680 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-19 06:07:34.561443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-19 06:07:34.561764) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-19 06:07:34.562046) ========= ========= Total master_lag: 0.22 =========