builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-050 starttime: 1453711572.76 results: success (0) buildid: 20160124223547 builduid: 94bb63ddabbc448a9415b6a1eff54db0 revision: 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.757538) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.758195) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.758549) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006342 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.782695) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.782989) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.810396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.810667) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009218 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.858063) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.858390) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.858771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:12.859046) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-25 00:46:13-- 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% 222M=0s 2016-01-25 00:46:13 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167302 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.039681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.039992) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.121538 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.170415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.170775) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 --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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-25 00:46:13,361 truncating revision to first 12 chars 2016-01-25 00:46:13,361 Setting DEBUG logging. 2016-01-25 00:46:13,361 attempt 1/10 2016-01-25 00:46:13,361 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/9807e39dc5bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-25 00:46:13,614 unpacking tar archive at: mozilla-inbound-9807e39dc5bc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.527304 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.709125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.709442) ========= echo 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'9807e39dc5bc53e34d0885a3af68f42f04a0fdf3'] 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 program finished with exit code 0 elapsedTime=0.004585 script_repo_revision: '9807e39dc5bc53e34d0885a3af68f42f04a0fdf3' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.759223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.759529) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-25 00:46:13.769455) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-25 00:46:13.769766) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:46:14 INFO - MultiFileLogger online at 20160125 00:46:14 in /builds/slave/test-pgo 00:46:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 00:46:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 00:46:14 INFO - {'append_to_log': False, 00:46:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 00:46:14 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 00:46:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 00:46:14 INFO - 'branch': 'Mozilla-Inbound', 00:46:14 INFO - 'buildbot_json_path': 'buildprops.json', 00:46:14 INFO - 'config_files': ('talos/linux_config.py',), 00:46:14 INFO - 'default_actions': ('clobber', 00:46:14 INFO - 'read-buildbot-config', 00:46:14 INFO - 'download-and-extract', 00:46:14 INFO - 'populate-webroot', 00:46:14 INFO - 'create-virtualenv', 00:46:14 INFO - 'install', 00:46:14 INFO - 'run-tests'), 00:46:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:46:14 INFO - 'download_minidump_stackwalk': True, 00:46:14 INFO - 'download_symbols': 'ondemand', 00:46:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:46:14 INFO - 'tooltool.py': '/tools/tooltool.py', 00:46:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:46:14 INFO - '/tools/misc-python/virtualenv.py')}, 00:46:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:46:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:46:14 INFO - 'installer_path': 'installer.exe', 00:46:14 INFO - 'log_level': 'info', 00:46:14 INFO - 'log_name': 'talos', 00:46:14 INFO - 'log_to_console': True, 00:46:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:46:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:46:14 INFO - 'opt_config_files': (), 00:46:14 INFO - 'pip_index': False, 00:46:14 INFO - 'sps_profile': False, 00:46:14 INFO - 'sps_profile_interval': 0, 00:46:14 INFO - 'suite': 'dromaeojs-e10s', 00:46:14 INFO - 'system_bits': '32', 00:46:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:46:14 INFO - 'title': 'talos-linux64-ix-050', 00:46:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:46:14 INFO - 'use_talos_json': True, 00:46:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 00:46:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:46:14 INFO - 'work_dir': 'build'} 00:46:14 INFO - ##### 00:46:14 INFO - ##### Running clobber step. 00:46:14 INFO - ##### 00:46:14 INFO - Running pre-action listener: _resource_record_pre_action 00:46:14 INFO - Running main action method: clobber 00:46:14 INFO - rmtree: /builds/slave/test-pgo/build 00:46:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 00:46:15 INFO - Running post-action listener: _resource_record_post_action 00:46:15 INFO - ##### 00:46:15 INFO - ##### Running read-buildbot-config step. 00:46:15 INFO - ##### 00:46:15 INFO - Running pre-action listener: _resource_record_pre_action 00:46:15 INFO - Running main action method: read_buildbot_config 00:46:15 INFO - Using buildbot properties: 00:46:15 INFO - { 00:46:15 INFO - "project": "", 00:46:15 INFO - "product": "firefox", 00:46:15 INFO - "script_repo_revision": "production", 00:46:15 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 00:46:15 INFO - "repository": "", 00:46:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 00:46:15 INFO - "buildid": "20160124223547", 00:46:15 INFO - "pgo_build": "True", 00:46:15 INFO - "basedir": "/builds/slave/test-pgo", 00:46:15 INFO - "buildnumber": 345, 00:46:15 INFO - "slavename": "talos-linux64-ix-050", 00:46:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 00:46:15 INFO - "platform": "ubuntu64_hw", 00:46:15 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 00:46:15 INFO - "branch": "mozilla-inbound", 00:46:15 INFO - "repo_path": "integration/mozilla-inbound", 00:46:15 INFO - "revision": "9807e39dc5bc53e34d0885a3af68f42f04a0fdf3", 00:46:15 INFO - "stage_platform": "linux64-pgo", 00:46:15 INFO - "builduid": "94bb63ddabbc448a9415b6a1eff54db0", 00:46:15 INFO - "slavebuilddir": "test" 00:46:15 INFO - } 00:46:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 00:46:15 INFO - Running post-action listener: _resource_record_post_action 00:46:15 INFO - ##### 00:46:15 INFO - ##### Running download-and-extract step. 00:46:15 INFO - ##### 00:46:15 INFO - Running pre-action listener: _resource_record_pre_action 00:46:15 INFO - Running main action method: download_and_extract 00:46:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 00:46:15 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')]} 00:46:15 INFO - https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 00:46:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/test_packages.json 00:46:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/test_packages.json 00:46:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 00:46:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 00:46:17 INFO - Downloaded 1448 bytes. 00:46:17 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 00:46:17 INFO - Using the following test package requirements: 00:46:17 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 00:46:17 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:46:17 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:46:17 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'jsshell-linux-x86_64.zip'], 00:46:17 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:46:17 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 00:46:17 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:46:17 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:46:17 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:46:17 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 00:46:17 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 00:46:17 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:46:17 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:46: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')]} 00:46:17 INFO - https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 00:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 00:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:19 INFO - Downloaded 22494105 bytes. 00:46:19 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'] 00:46:19 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 00:46:20 INFO - Return code: 0 00:46:20 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 00:46:20 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')]} 00:46:20 INFO - https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:46:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 00:46:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 00:46:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:22 INFO - Downloaded 22494105 bytes. 00:46:22 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'] 00:46:22 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 00:46:24 INFO - Return code: 0 00:46:24 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')]} 00:46:24 INFO - https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:46:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 00:46:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 00:46:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:25 INFO - Downloaded 10706572 bytes. 00:46:25 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'] 00:46:25 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 00:46:25 INFO - Return code: 0 00:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:25 INFO - https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:46:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 00:46:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 00:46:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XBM6ihp1R3-m4vQNnAgyew/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 00:46:26 INFO - Downloaded 56001711 bytes. 00:46:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 00:46:26 INFO - mkdir: /builds/slave/test-pgo/properties 00:46:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 00:46:26 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 00:46:26 INFO - Contents: 00:46:26 INFO - build_url:https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 00:46:26 INFO - Running post-action listener: _resource_record_post_action 00:46:26 INFO - Running post-action listener: set_extra_try_arguments 00:46:26 INFO - ##### 00:46:26 INFO - ##### Running populate-webroot step. 00:46:26 INFO - ##### 00:46:26 INFO - Running pre-action listener: _resource_record_pre_action 00:46:26 INFO - Running main action method: populate_webroot 00:46:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:46:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:46:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:46:26 INFO - '--tppagecycles', 00:46:26 INFO - '7'], 00:46:26 INFO - 'tests': ['tsvgm']}}, 00:46:26 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 00:46:26 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:46:26 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 00:46:26 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 00:46:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:46:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:46:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'tests': ['damp', 'tps']}, 00:46:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['damp', 'tps']}, 00:46:26 INFO - 'g3': {'tests': ['dromaeo_dom']}, 00:46:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:46:26 INFO - 'other': {'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'other-e10s': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'other_l64': {'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'other_nol64': {'tests': ['a11yr', 00:46:26 INFO - 'ts_paint', 00:46:26 INFO - 'tpaint', 00:46:26 INFO - 'sessionrestore', 00:46:26 INFO - 'sessionrestore_no_auto_restore']}, 00:46:26 INFO - 'svgr': {'tests': ['tsvgx', 00:46:26 INFO - 'tsvgr_opacity', 00:46:26 INFO - 'tart', 00:46:26 INFO - 'tscrollx', 00:46:26 INFO - 'cart']}, 00:46:26 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['tsvgx', 00:46:26 INFO - 'tsvgr_opacity', 00:46:26 INFO - 'tart', 00:46:26 INFO - 'tscrollx', 00:46:26 INFO - 'cart']}, 00:46:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'tests': ['tp5o']}, 00:46:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'talos_options': ['--e10s'], 00:46:26 INFO - 'tests': ['tp5o']}, 00:46:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'talos_options': ['--xperf_path', 00:46:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:46:26 INFO - 'tests': ['tp5n']}, 00:46:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:46:26 INFO - 'talos_options': ['--e10s', 00:46:26 INFO - '--xperf_path', 00:46:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:46:26 INFO - 'tests': ['tp5n']}}, 00:46:26 INFO - 'talos.zip': {'path': '', 00:46:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:46:26 INFO - Running post-action listener: _resource_record_post_action 00:46:26 INFO - ##### 00:46:26 INFO - ##### Running create-virtualenv step. 00:46:26 INFO - ##### 00:46:26 INFO - Running pre-action listener: _resource_record_pre_action 00:46:26 INFO - Running main action method: create_virtualenv 00:46:26 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 00:46:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 00:46:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 00:46:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:46:26 INFO - Using real prefix '/usr' 00:46:26 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 00:46:27 INFO - Installing distribute.............................................................................................................................................................................................done. 00:46:29 INFO - Installing pip.................done. 00:46:29 INFO - Return code: 0 00:46:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 00:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 00:46:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:46:29 INFO - Using env: {'DISPLAY': ':0', 00:46:29 INFO - 'HOME': '/home/cltbld', 00:46:29 INFO - 'LANG': 'en_US.UTF-8', 00:46:29 INFO - 'LANGUAGE': 'en_US:en', 00:46:29 INFO - 'LOGNAME': 'cltbld', 00:46:29 INFO - 'MAIL': '/var/mail/cltbld', 00:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:29 INFO - 'MOZ_NO_REMOTE': '1', 00:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:29 INFO - 'NO_EM_RESTART': '1', 00:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:29 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:29 INFO - 'SHELL': '/bin/bash', 00:46:29 INFO - 'SHLVL': '1', 00:46:29 INFO - 'TERM': 'linux', 00:46:29 INFO - 'TMOUT': '86400', 00:46:29 INFO - 'USER': 'cltbld', 00:46:29 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:29 INFO - '_': '/tools/buildbot/bin/python'} 00:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 00:46:29 INFO - Cleaning up... 00:46:29 INFO - Return code: 0 00:46:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 00:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 00:46:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 00:46:29 INFO - Using env: {'DISPLAY': ':0', 00:46:29 INFO - 'HOME': '/home/cltbld', 00:46:29 INFO - 'LANG': 'en_US.UTF-8', 00:46:29 INFO - 'LANGUAGE': 'en_US:en', 00:46:29 INFO - 'LOGNAME': 'cltbld', 00:46:29 INFO - 'MAIL': '/var/mail/cltbld', 00:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:29 INFO - 'MOZ_NO_REMOTE': '1', 00:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:29 INFO - 'NO_EM_RESTART': '1', 00:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:29 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:29 INFO - 'SHELL': '/bin/bash', 00:46:29 INFO - 'SHLVL': '1', 00:46:29 INFO - 'TERM': 'linux', 00:46:29 INFO - 'TMOUT': '86400', 00:46:29 INFO - 'USER': 'cltbld', 00:46:29 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:29 INFO - '_': '/tools/buildbot/bin/python'} 00:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:29 INFO - Downloading/unpacking psutil>=0.7.1 00:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 00:46:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:46:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:46:31 INFO - Installing collected packages: psutil 00:46:31 INFO - Running setup.py install for psutil 00:46:31 INFO - building 'psutil._psutil_linux' extension 00:46:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 00:46:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 00:46:32 INFO - building 'psutil._psutil_posix' extension 00:46:32 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 00:46:32 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 00:46:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:46:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:46:32 INFO - Successfully installed psutil 00:46:32 INFO - Cleaning up... 00:46:32 INFO - Return code: 0 00:46:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 00: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')]} 00:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00: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')]} 00:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 00:46:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 00:46:32 INFO - Using env: {'DISPLAY': ':0', 00:46:32 INFO - 'HOME': '/home/cltbld', 00:46:32 INFO - 'LANG': 'en_US.UTF-8', 00:46:32 INFO - 'LANGUAGE': 'en_US:en', 00:46:32 INFO - 'LOGNAME': 'cltbld', 00:46:32 INFO - 'MAIL': '/var/mail/cltbld', 00:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:32 INFO - 'MOZ_NO_REMOTE': '1', 00:46:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:32 INFO - 'NO_EM_RESTART': '1', 00:46:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:32 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:32 INFO - 'SHELL': '/bin/bash', 00:46:32 INFO - 'SHLVL': '1', 00:46:32 INFO - 'TERM': 'linux', 00:46:32 INFO - 'TMOUT': '86400', 00:46:32 INFO - 'USER': 'cltbld', 00:46:32 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:32 INFO - '_': '/tools/buildbot/bin/python'} 00:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:46:32 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 00:46:32 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 00:46:32 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 00:46:32 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 00:46:32 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 00:46:32 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 00:46:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:46:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:46:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:46:34 INFO - Installing collected packages: mozsystemmonitor 00:46:34 INFO - Running setup.py install for mozsystemmonitor 00:46:34 INFO - Successfully installed mozsystemmonitor 00:46:34 INFO - Cleaning up... 00:46:35 INFO - Return code: 0 00:46:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 00:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 00:46:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:46:35 INFO - Using env: {'DISPLAY': ':0', 00:46:35 INFO - 'HOME': '/home/cltbld', 00:46:35 INFO - 'LANG': 'en_US.UTF-8', 00:46:35 INFO - 'LANGUAGE': 'en_US:en', 00:46:35 INFO - 'LOGNAME': 'cltbld', 00:46:35 INFO - 'MAIL': '/var/mail/cltbld', 00:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:35 INFO - 'MOZ_NO_REMOTE': '1', 00:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:35 INFO - 'NO_EM_RESTART': '1', 00:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:35 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:35 INFO - 'SHELL': '/bin/bash', 00:46:35 INFO - 'SHLVL': '1', 00:46:35 INFO - 'TERM': 'linux', 00:46:35 INFO - 'TMOUT': '86400', 00:46:35 INFO - 'USER': 'cltbld', 00:46:35 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:35 INFO - '_': '/tools/buildbot/bin/python'} 00:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:35 INFO - Downloading/unpacking blobuploader==1.2.4 00:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:37 INFO - Downloading blobuploader-1.2.4.tar.gz 00:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:46:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 00:46:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 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 00:46:37 INFO - Downloading docopt-0.6.1.tar.gz 00:46:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 00:46:37 INFO - Installing collected packages: blobuploader, requests, docopt 00:46:37 INFO - Running setup.py install for blobuploader 00:46:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:46:37 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 00:46:37 INFO - Running setup.py install for requests 00:46:38 INFO - Running setup.py install for docopt 00:46:38 INFO - Successfully installed blobuploader requests docopt 00:46:38 INFO - Cleaning up... 00:46:38 INFO - Return code: 0 00:46:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 00:46:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:46:38 INFO - Using env: {'DISPLAY': ':0', 00:46:38 INFO - 'HOME': '/home/cltbld', 00:46:38 INFO - 'LANG': 'en_US.UTF-8', 00:46:38 INFO - 'LANGUAGE': 'en_US:en', 00:46:38 INFO - 'LOGNAME': 'cltbld', 00:46:38 INFO - 'MAIL': '/var/mail/cltbld', 00:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:38 INFO - 'MOZ_NO_REMOTE': '1', 00:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:38 INFO - 'NO_EM_RESTART': '1', 00:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:38 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:38 INFO - 'SHELL': '/bin/bash', 00:46:38 INFO - 'SHLVL': '1', 00:46:38 INFO - 'TERM': 'linux', 00:46:38 INFO - 'TMOUT': '86400', 00:46:38 INFO - 'USER': 'cltbld', 00:46:38 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:38 INFO - '_': '/tools/buildbot/bin/python'} 00:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:46:38 INFO - Running setup.py (path:/tmp/pip-HGW3uS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:46:38 INFO - Running setup.py (path:/tmp/pip-xBTEIQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:46:38 INFO - Running setup.py (path:/tmp/pip-jO0kfP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:46:38 INFO - Running setup.py (path:/tmp/pip-mpSGrw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:46:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 00:46:38 INFO - Running setup.py (path:/tmp/pip-oRdHPx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:46:39 INFO - Running setup.py (path:/tmp/pip-ap0b4z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:46:39 INFO - Running setup.py (path:/tmp/pip-jQwu8M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:46:39 INFO - Running setup.py (path:/tmp/pip-ekHGsB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 00:46:39 INFO - Running setup.py (path:/tmp/pip-qTZzxq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 00:46:39 INFO - Running setup.py (path:/tmp/pip-UMowD8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:46:39 INFO - Running setup.py (path:/tmp/pip-WpNwWS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:46:39 INFO - Running setup.py (path:/tmp/pip-s4P1gZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:46:39 INFO - Running setup.py (path:/tmp/pip-5vOyBw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:46:39 INFO - Running setup.py (path:/tmp/pip-tPRAxz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:46:39 INFO - Running setup.py (path:/tmp/pip-y88_kE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 00:46:39 INFO - Running setup.py (path:/tmp/pip-vGrmYa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 00:46:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 00:46:39 INFO - Running setup.py (path:/tmp/pip-F_zr5v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 00:46:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:46:39 INFO - Running setup.py install for manifestparser 00:46:39 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 00:46:39 INFO - Running setup.py install for mozcrash 00:46:40 INFO - Running setup.py install for mozdebug 00:46:40 INFO - Running setup.py install for mozdevice 00:46:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for mozfile 00:46:40 INFO - Running setup.py install for mozhttpd 00:46:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for mozinfo 00:46:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for mozInstall 00:46:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for mozleak 00:46:40 INFO - Running setup.py install for mozlog 00:46:40 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for moznetwork 00:46:40 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 00:46:40 INFO - Running setup.py install for mozprocess 00:46:41 INFO - Running setup.py install for mozprofile 00:46:41 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 00:46:41 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 00:46:41 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 00:46:41 INFO - Running setup.py install for mozrunner 00:46:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 00:46:41 INFO - Running setup.py install for mozscreenshot 00:46:41 INFO - Running setup.py install for moztest 00:46:41 INFO - Running setup.py install for mozversion 00:46:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 00:46:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:46:41 INFO - Cleaning up... 00:46:41 INFO - Return code: 0 00:46:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00: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')]} 00:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00: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')]} 00:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 00:46:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:46:41 INFO - Using env: {'DISPLAY': ':0', 00:46:41 INFO - 'HOME': '/home/cltbld', 00:46:41 INFO - 'LANG': 'en_US.UTF-8', 00:46:41 INFO - 'LANGUAGE': 'en_US:en', 00:46:41 INFO - 'LOGNAME': 'cltbld', 00:46:41 INFO - 'MAIL': '/var/mail/cltbld', 00:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:41 INFO - 'MOZ_NO_REMOTE': '1', 00:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:41 INFO - 'NO_EM_RESTART': '1', 00:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:41 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:41 INFO - 'SHELL': '/bin/bash', 00:46:41 INFO - 'SHLVL': '1', 00:46:41 INFO - 'TERM': 'linux', 00:46:41 INFO - 'TMOUT': '86400', 00:46:41 INFO - 'USER': 'cltbld', 00:46:41 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:41 INFO - '_': '/tools/buildbot/bin/python'} 00:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:46:41 INFO - Running setup.py (path:/tmp/pip-ljGiDO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 00:46:41 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)) 00:46:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:46:41 INFO - Running setup.py (path:/tmp/pip-pUz6bH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:46:42 INFO - Running setup.py (path:/tmp/pip-sFKGuM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:46:42 INFO - Running setup.py (path:/tmp/pip-jaGfvk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 00:46:42 INFO - Running setup.py (path:/tmp/pip-XeXk1r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:46:42 INFO - Running setup.py (path:/tmp/pip-s76Z2g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:46:42 INFO - Running setup.py (path:/tmp/pip-y9RKZr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:46:42 INFO - Running setup.py (path:/tmp/pip-Vh0yd6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 00:46:42 INFO - Running setup.py (path:/tmp/pip-figEwM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 00:46:42 INFO - Running setup.py (path:/tmp/pip-bE987v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:46:42 INFO - Running setup.py (path:/tmp/pip-6dz2oa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:46:42 INFO - Running setup.py (path:/tmp/pip-O0bY4u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:46:42 INFO - Running setup.py (path:/tmp/pip-mkxgUQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:46:42 INFO - Running setup.py (path:/tmp/pip-h_L8qi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:46:42 INFO - Running setup.py (path:/tmp/pip-peQtru-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 00:46:42 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)) 00:46:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 00:46:42 INFO - Running setup.py (path:/tmp/pip-gozUl_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 00:46:43 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)) 00:46:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 00:46:43 INFO - Running setup.py (path:/tmp/pip-H4K11Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 00:46:43 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)) 00:46:43 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)) 00:46:43 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)) 00:46:43 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)) 00:46:43 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)) 00:46:43 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)) 00:46:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 00:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:44 INFO - Downloading blessings-1.6.tar.gz 00:46:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 00:46:44 INFO - Installing collected packages: blessings 00:46:44 INFO - Running setup.py install for blessings 00:46:45 INFO - Successfully installed blessings 00:46:45 INFO - Cleaning up... 00:46:45 INFO - Return code: 0 00:46:45 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 00:46:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 00:46:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 00:46:45 INFO - Reading from file tmpfile_stdout 00:46:45 INFO - Current package versions: 00:46:45 INFO - argparse == 1.2.1 00:46:45 INFO - blessings == 1.6 00:46:45 INFO - blobuploader == 1.2.4 00:46:45 INFO - docopt == 0.6.1 00:46:45 INFO - manifestparser == 1.1 00:46:45 INFO - mozInstall == 1.12 00:46:45 INFO - mozcrash == 0.16 00:46:45 INFO - mozdebug == 0.1 00:46:45 INFO - mozdevice == 0.48 00:46:45 INFO - mozfile == 1.2 00:46:45 INFO - mozhttpd == 0.7 00:46:45 INFO - mozinfo == 0.9 00:46:45 INFO - mozleak == 0.1 00:46:45 INFO - mozlog == 3.1 00:46:45 INFO - moznetwork == 0.27 00:46:45 INFO - mozprocess == 0.22 00:46:45 INFO - mozprofile == 0.28 00:46:45 INFO - mozrunner == 6.11 00:46:45 INFO - mozscreenshot == 0.1 00:46:45 INFO - mozsystemmonitor == 0.0 00:46:45 INFO - moztest == 0.7 00:46:45 INFO - mozversion == 1.4 00:46:45 INFO - psutil == 3.1.1 00:46:45 INFO - requests == 1.2.3 00:46:45 INFO - wsgiref == 0.1.2 00:46:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 00:46:45 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')]} 00:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:45 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')]} 00:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 00:46:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:46:45 INFO - Using env: {'DISPLAY': ':0', 00:46:45 INFO - 'HOME': '/home/cltbld', 00:46:45 INFO - 'LANG': 'en_US.UTF-8', 00:46:45 INFO - 'LANGUAGE': 'en_US:en', 00:46:45 INFO - 'LOGNAME': 'cltbld', 00:46:45 INFO - 'MAIL': '/var/mail/cltbld', 00:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:45 INFO - 'MOZ_NO_REMOTE': '1', 00:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:45 INFO - 'NO_EM_RESTART': '1', 00:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:45 INFO - 'SHELL': '/bin/bash', 00:46:45 INFO - 'SHLVL': '1', 00:46:45 INFO - 'TERM': 'linux', 00:46:45 INFO - 'TMOUT': '86400', 00:46:45 INFO - 'USER': 'cltbld', 00:46:45 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:45 INFO - '_': '/tools/buildbot/bin/python'} 00:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 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)) 00:46:45 INFO - Cleaning up... 00:46:45 INFO - Return code: 0 00:46:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 00:46:45 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')]} 00:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:45 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')]} 00:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 00:46:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:46:45 INFO - Using env: {'DISPLAY': ':0', 00:46:45 INFO - 'HOME': '/home/cltbld', 00:46:45 INFO - 'LANG': 'en_US.UTF-8', 00:46:45 INFO - 'LANGUAGE': 'en_US:en', 00:46:45 INFO - 'LOGNAME': 'cltbld', 00:46:45 INFO - 'MAIL': '/var/mail/cltbld', 00:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:45 INFO - 'MOZ_NO_REMOTE': '1', 00:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:45 INFO - 'NO_EM_RESTART': '1', 00:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:45 INFO - 'SHELL': '/bin/bash', 00:46:45 INFO - 'SHLVL': '1', 00:46:45 INFO - 'TERM': 'linux', 00:46:45 INFO - 'TMOUT': '86400', 00:46:45 INFO - 'USER': 'cltbld', 00:46:45 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:45 INFO - '_': '/tools/buildbot/bin/python'} 00:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:45 INFO - Downloading/unpacking jsonschema 00:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:46:47 INFO - Downloading/unpacking functools32 (from jsonschema) 00:46:47 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 00:46:47 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 00:46:47 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 00:46:47 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 00:46:47 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 00:46:47 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 00:46:47 INFO - Downloading functools32-3.2.3-2.tar.gz 00:46:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 00:46:47 INFO - warning: no files found matching '*.txt' 00:46:47 INFO - no previously-included directories found matching 'build' 00:46:47 INFO - no previously-included directories found matching 'dist' 00:46:47 INFO - no previously-included directories found matching '.git*' 00:46:47 INFO - Installing collected packages: jsonschema, functools32 00:46:47 INFO - Running setup.py install for functools32 00:46:47 INFO - warning: no files found matching '*.txt' 00:46:47 INFO - no previously-included directories found matching 'build' 00:46:47 INFO - no previously-included directories found matching 'dist' 00:46:47 INFO - no previously-included directories found matching '.git*' 00:46:47 INFO - Successfully installed jsonschema functools32 00:46:47 INFO - Cleaning up... 00:46:47 INFO - Return code: 0 00:46:47 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 00:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:46:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f21af0abf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a4480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, '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': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', '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 00:46:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 00:46:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 00:46:47 INFO - Using env: {'DISPLAY': ':0', 00:46:47 INFO - 'HOME': '/home/cltbld', 00:46:47 INFO - 'LANG': 'en_US.UTF-8', 00:46:47 INFO - 'LANGUAGE': 'en_US:en', 00:46:47 INFO - 'LOGNAME': 'cltbld', 00:46:47 INFO - 'MAIL': '/var/mail/cltbld', 00:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:46:47 INFO - 'MOZ_NO_REMOTE': '1', 00:46:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:46:47 INFO - 'NO_EM_RESTART': '1', 00:46:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:46:47 INFO - 'PWD': '/builds/slave/test-pgo', 00:46:47 INFO - 'SHELL': '/bin/bash', 00:46:47 INFO - 'SHLVL': '1', 00:46:47 INFO - 'TERM': 'linux', 00:46:47 INFO - 'TMOUT': '86400', 00:46:47 INFO - 'USER': 'cltbld', 00:46:47 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:46:47 INFO - '_': '/tools/buildbot/bin/python'} 00:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:46:48 INFO - Downloading/unpacking flake8 00: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 00: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 00: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 00: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 00: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 00: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 00:46:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:46:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:46:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:46:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 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 00:46:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 00:46:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:46:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:46:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:46:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:46:50 INFO - no previously-included directories found matching 'docs/_build' 00:46:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:46:50 INFO - Running setup.py install for pep8 00:46:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:46:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:46:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:46:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:46:50 INFO - no previously-included directories found matching 'docs/_build' 00:46:50 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 00:46:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:46:50 INFO - Cleaning up... 00:46:50 INFO - Return code: 0 00:46:50 INFO - Running post-action listener: _resource_record_post_action 00:46:50 INFO - Running post-action listener: _start_resource_monitoring 00:46:50 INFO - Starting resource monitoring. 00:46:50 INFO - ##### 00:46:50 INFO - ##### Running install step. 00:46:50 INFO - ##### 00:46:50 INFO - Running pre-action listener: _resource_record_pre_action 00:46:50 INFO - Running main action method: install 00:46:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 00:46:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 00:46:51 INFO - Reading from file tmpfile_stdout 00:46:51 INFO - Detecting whether we're running mozinstall >=1.0... 00:46:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 00:46:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 00:46:51 INFO - Reading from file tmpfile_stdout 00:46:51 INFO - Output received: 00:46:51 INFO - Usage: mozinstall [options] installer 00:46:51 INFO - Options: 00:46:51 INFO - -h, --help show this help message and exit 00:46:51 INFO - -d DEST, --destination=DEST 00:46:51 INFO - Directory to install application into. [default: 00:46:51 INFO - "/builds/slave/test-pgo"] 00:46:51 INFO - --app=APP Application being installed. [default: firefox] 00:46:51 INFO - mkdir: /builds/slave/test-pgo/build/application 00:46:51 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'] 00:46:51 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 00:47:07 INFO - Reading from file tmpfile_stdout 00:47:07 INFO - Output received: 00:47:07 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 00:47:07 INFO - Running post-action listener: _resource_record_post_action 00:47:07 INFO - ##### 00:47:07 INFO - ##### Running run-tests step. 00:47:07 INFO - ##### 00:47:07 INFO - Running pre-action listener: _resource_record_pre_action 00:47:07 INFO - Running main action method: run_tests 00:47:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 00:47:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 00:47:07 INFO - Python 2.7.3 00:47:07 INFO - Return code: 0 00:47:07 INFO - grabbing minidump binary from tooltool 00:47:07 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')]} 00:47:07 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 0x2433660>, '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 0x2434560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2434a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 00:47:07 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 00:47:07 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 00:47:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:47:07 INFO - Return code: 0 00:47:07 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 00:47:07 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 00:47:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 00:47:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 00:47:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 00:47:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 00:47:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 00:47:08 INFO - Return code: 0 00:47:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 00:47:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 00:47:08 INFO - Using env: {'DISPLAY': ':0', 00:47:08 INFO - 'HOME': '/home/cltbld', 00:47:08 INFO - 'LANG': 'en_US.UTF-8', 00:47:08 INFO - 'LANGUAGE': 'en_US:en', 00:47:08 INFO - 'LOGNAME': 'cltbld', 00:47:08 INFO - 'MAIL': '/var/mail/cltbld', 00:47:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 00:47:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 00:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:08 INFO - 'MOZ_NO_REMOTE': '1', 00:47:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 00:47:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:47:08 INFO - 'NO_EM_RESTART': '1', 00:47:08 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 00:47:08 INFO - 'PWD': '/builds/slave/test-pgo', 00:47:08 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 00:47:08 INFO - 'SHELL': '/bin/bash', 00:47:08 INFO - 'SHLVL': '1', 00:47:08 INFO - 'TERM': 'linux', 00:47:08 INFO - 'TMOUT': '86400', 00:47:08 INFO - 'USER': 'cltbld', 00:47:08 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919', 00:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:08 INFO - '_': '/tools/buildbot/bin/python'} 00:47:08 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 00:47:08 INFO - mozversion application_buildid: 20160124223547 00:47:08 INFO - mozversion application_changeset: 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 00:47:08 INFO - mozversion application_display_name: Nightly 00:47:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:47:08 INFO - mozversion application_name: Firefox 00:47:08 INFO - mozversion application_remotingname: firefox 00:47:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:47:08 INFO - mozversion application_vendor: Mozilla 00:47:08 INFO - mozversion application_version: 46.0a1 00:47:08 INFO - mozversion platform_buildid: 20160124223547 00:47:08 INFO - mozversion platform_changeset: 9807e39dc5bc53e34d0885a3af68f42f04a0fdf3 00:47:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 00:47:08 INFO - mozversion platform_version: 46.0a1 00:47:08 INFO - using testdate: 1453711628 00:47:08 INFO - actual date: 1453711628 00:47:08 INFO - starting webserver on 'localhost:35224' 00:47:08 INFO - SUITE-START | Running 3 tests 00:47:08 INFO - TEST-START | dromaeo_css 00:47:08 INFO - operating with platform_type : linux_ 00:47:08 INFO - Initialising browser for dromaeo_css test... 00:47:08 INFO - TEST-INFO | started process 4442 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpESiFfs/profile http://localhost:35224/getInfo.html) 00:47:12 INFO - PROCESS | 4442 | 00:47:12 INFO - PROCESS | 4442 | (process:4526): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:47:13 INFO - PROCESS | 4442 | __metrics Screen width/height:1600/1200 00:47:13 INFO - PROCESS | 4442 | colorDepth:24 00:47:13 INFO - PROCESS | 4442 | Browser inner width/height: 1024/697 00:47:13 INFO - PROCESS | 4442 | __metrics 00:47:13 INFO - PROCESS | 4442 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 00:47:14 INFO - TEST-INFO | 4442: exit 0 00:47:14 INFO - Browser initialized. 00:47:14 INFO - Running cycle 1/1 for dromaeo_css test... 00:47:14 INFO - TEST-INFO | started process 4575 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpESiFfs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:47:15 INFO - PROCESS | 4575 | 00:47:15 INFO - PROCESS | 4575 | (process:4636): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 00:47:26 INFO - PROCESS | 4575 | RSS: Main: 150589440 00:47:26 INFO - PROCESS | 4575 | 00:48:15 INFO - PROCESS | 4575 | 1453711695690 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] 00:50:24 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35224/tests/dromaeo/cssquery-ext.html) 00:50:25 INFO - PROCESS | 4575 | RSS: Main: 160391168 00:50:25 INFO - PROCESS | 4575 | 00:53:27 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-ext.html (next: http://localhost:35224/tests/dromaeo/cssquery-jquery.html) 00:53:27 INFO - PROCESS | 4575 | RSS: Main: 157986816 00:53:27 INFO - PROCESS | 4575 | 00:56:29 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35224/tests/dromaeo/cssquery-mootools.html) 00:56:30 INFO - PROCESS | 4575 | RSS: Main: 157114368 00:56:30 INFO - PROCESS | 4575 | 00:59:31 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35224/tests/dromaeo/cssquery-prototype.html) 00:59:32 INFO - PROCESS | 4575 | RSS: Main: 153944064 00:59:32 INFO - PROCESS | 4575 | 01:02:34 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35224/tests/dromaeo/cssquery-yui.html) 01:02:35 INFO - PROCESS | 4575 | RSS: Main: 154746880 01:02:35 INFO - PROCESS | 4575 | 01:05:37 INFO - PROCESS | 4575 | Cycle 1(1): loaded http://localhost:35224/tests/dromaeo/cssquery-yui.html (next: http://localhost:35224/tests/dromaeo/cssquery-dojo.html) 01:05:38 INFO - PROCESS | 4575 | RSS: Main: 157257728 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | __start_tp_report 01:05:38 INFO - PROCESS | 4575 | _x_x_mozilla_page_load 01:05:38 INFO - PROCESS | 4575 | _x_x_mozilla_page_load_details 01:05:38 INFO - PROCESS | 4575 | |i|pagename|runs| 01:05:38 INFO - PROCESS | 4575 | |0;dojo.html;3148.6064241969752;3148.66938971408;3173.7302329302;3193.4730769423045;3195.057458049875;5094.821681241157;5115.744212789359;5145.588352931766;5179.4043684976195;5204.895902081956;5147.897042059157;5171.482851714826;5187.351581052369;5192.974035129829;5220.2430647556175;4668.883277918046;4707.741074240916;4709.811607535695;4779.665423420362;4836.322914791932;4494;4792.90414191716;4806.87982800431;4860.878478038042;4867.3915760529935;4696.29555566666;4701.882452938669;4748.264019077049;4766.809327626891;4789.904201915977;4709.7174169549935;4729.905401891943;4737.455192652855;4741.288806678991;4742.2886567014875;4603.861884143481;4711.528847115295;4733.857984260478;4735.692180008288;4769.666123371366;5031.270465782441;5035.974820125876;5041.092603331399;5042.621803364762;5077.5684066854365;5523.143912693539;5525.778968841241;5526.032944234783;5540.50135487804;5567.025770490189;5574.470425309589;5575.665460072409;5577.05190117681;5578.967890940148;5583.218349431083;5164.8192313268855;5247;5258.97370513145;5274.235235890775;5349.625526213205;5686.3744988051285;5692.260006199168;5722.113072473773;5733.655980641091;5736.627119237236;5491.28613280271;5494.752736126884;5498.12428072515;5501.862453438696;5514;5454.154606036071;5476.644018138808;5557.944420555743;5586.748596313095;5648.8870222595315;5472.98749731301;5487.670739755627;5501.312335958005;5506.146547285177;5507.339119305709;5417.620766546304;5507.421720719347;5537.556995440355;5545.972270138624;5579.302587176603;4343.9348409773875;4352.608265256142;4388.0785035142335;4394.033312671208;4428.114377124588;3899.2396482685604;3899.337112690849;3900.3759398496104;3912.295786758354;3940.940885886714;5167.354080739908;5227.843164705065;5298.6290959633225;5300.602454815904;5364.839054828361;5580.330360356702;5606.775728970877;5627.606067575312;5645.689487078168;5668.234788303609;5214.478552144831;5234.842954711365;5259.47405259471;5261.657992230493;5281.313429254173;4733.337332773414;4752.2158843790385;4759.309900064541;4779.426468823764;4850.442199147144;5787.305523337142;5792.102224155263;5807.303123625192;5808.186853840466;5826.096954971986;5522.30971128609;5534.280543529316;5534.833954981276;5559.861003474945;5579.414161512983;5578.246936663499;5669.234653321749;5676.4323567642905;5740.741666625096;5776.7400466979925;5435.809746658848;5442.047641662614;5448.809291674773;5449.455054494519;5453.563714902878;5649.887002260014;5669.291338582677;5690.9756243874845;5693.686847223443;5735.655860648374;5463.31708536433;5483.287172667528;5504.146857237135;5508.091164957737;5510.752016159202;5247.89504209906;5433.8369848904595;5484.2596249505805;5491.28613280271;5539;5318.069337865935;5331.360236771612;5334.653247538898;5336.653117547347;5380.946190538045;5371.274877891435;5455.890882182413;5458.399576046552;5458.781648734165;5464.2076898848645;5246.76389562463;5259.658122222197;5360.758765855468;5384.730763461889;5454.3454785426 01:05:38 INFO - PROCESS | 4575 | |1;ext.html;530.8168681804777;531.5216305325207;554.839096661968;560.8794109266512;562.6033646279374;34328.82835585822;34890.8255458723;35186.648133518655;35225.29549409017;35322;38276.42585361215;38307.80846095773;38584.42123368152;38618.80690596543;38636.80681596595;8280.37897157714;8461.23848853603;8512.404131710782;8521.488710677348;8564.100769419183;8129.146439623843;8298.128696486843;8376.120507346732;8582;8586.31309495239;8460.534670593115;8594.785130371729;8605.569721513899;8618.43980141292;8639.870401943945;8371.372147088992;8532.189442003;8541.786455338604;8579.871301930476;8598.484090954562;8409.159084091603;8537.18896704812;8577.52823594702;8606.612702428405;8613.827723445496;39149.41275880864;39405.40891886624;39439.80280098581;39510.20979580421;39594;3357.9496307555405;3467.6532346765375;3529.5764508258917;3643.41705327149;3817.9618203818163;28275.434491310058;29080.563791543143;29272.926342733917;29279.2680182995;29320.70679293201;3217.4530329843983;3264.9183770405693;3299.8185099819502;3391.0335554367134;3396.2698019925588;280962;282178;282607;282738;284030;51465.485345146066;52227.47772522302;53708.731456342466;55917.44082559122;56765.43234567602;52829;52895.47104528906;53020.73489632527;53245.2013219802;53422;13808.792868106986;13899.79150312746;13905.582832515041;13952.16287022782;13994.650133746738;10456.111230545337;10458;10538.156947444186;10543.947280263703;10607.204459665401;18911.905440472983;19137.330193443162;19188.13653385601;19201.711974320395;19287.807121928883;7369.4472914531625;7487.1764105948305;7487.363574096154;7571.924280757232;7578.886316705254;978.3005151316843;991.9454033250056;1020.6835880876804;1077.5905156040653;1112.0991996482874;27608.447831043268;28203.858980705376;28293.292667683472;28347.71652283492;29563;1023.4545835787865;1057.0086654767144;1075.306427354352;1096.0464395975553;1115.9163062770324;14009.229492378017;14072.366743496566;14153.726164645232;14195.574132776033;14316.141031538142;328023;331811;332010;332094;332661;49188.75405622949;49669.75165124224;49738.75130624323;49752.75123624359;49975.50024499781;1352.2359866675297;1364.4746772492663;1367.453018792471;1371.2595198592649;1372.3737371215143;26459.206223813315;27481.86259068692;27619.585706214824;27660.861695691394;27819.44361112807;43389.566104338555;44585.77707111444;44637.55362446334;44696.10607787891;44875.551244487135;299612;301417;303989;304082;305079;30953.380932381675;31144.352730018203;31664.366712666077;31674.524882126327;31808;37523;37699.43450848185;38049.61950380571;38215.61784382121;38680.80659596684;30386.392272154877;30646.387072258876;30662.386752265276;30799;32629.673703262662;31522;32130;32298.83850580732;32393.35213295768;32521.186970325933;31540.526892097096;31843.522347164348;32000.839995799874;32093.839530802197;32126.19684507906;27970.580441293805;31193.412757533173;31241.218969525944;31364.37271254608;31567.36975247784;23877.761222387555;23904.2673019609;24199.51600968006;24202.636960445958;24322.635160472255 01:05:38 INFO - PROCESS | 4575 | |2;jquery.html;368.54300667172697;370.9276691045246;371.87356298858384;374.4308650850707;384.65573311885845;15575.688486230269;16611.41860034899;16756.83243167568;17230.224639891203;17282.481525554223;9945.303828731992;10189.235807314444;10278.177745780338;10281.845772313422;10481.318714283567;4749.90500189996;4828.97585512072;4909.975450122744;4922.80308787648;5559.8888022239535;5401.135818269077;5534.142207957753;5538.196961440589;5543.584231182657;5548.056830338832;4838.516148385169;5054.797808087672;5056.292119103328;5279.732864112624;5510.952918945388;4963.826266080688;5008.599312055045;5010.473900240477;5091.694498330112;5810.070388737781;5155.819546315898;5197.818076367309;5220.399654039796;5221.112410890158;5243.239730239094;11680.532778688843;18853.151608177664;19471.707924381146;19839.60320793576;19846.503837404038;2115.9365219043298;2144.774798646151;2188.9890550547148;2212.9225477108225;2274.0562666493383;8832.8675069874;9068;9113.58988845497;9858.753531161707;13347.86652133476;1675.2042779679678;1781.15395187285;1805.7201133824278;1814.7912990006055;1866;53539.19691204635;53820.73089634566;54125;55702.442975570535;55769.44230557683;9585.424874507551;10018.949905250573;10061.295709300424;10208.234382421348;10208.948955255177;12982.285974271317;13082.672933176556;13141.868581314257;13159.67100822468;13171;6926.688299026556;7023.8244043898285;7524;7623.199564045806;7637.885431718529;7347.448941329421;7474.7757567273065;7560.924390756022;7580.204078571781;7604.9239507605325;3995.800209989489;4093.5087789465456;4418.977905110454;4434.312681534368;4451.154280686659;1754.456118603237;1772.831580999803;1794.0491539484094;1828.2138680367304;1829.6340731853682;864.9524276164872;957.5116690487902;1016.0168577675353;1040.6461802987021;1051.6266725312405;14700.485483008044;15150.54548363551;15275.541733748005;16102.597435063983;16559;907.4237858959608;927.5176908007799;937.704623043739;965.6384497858127;988.1600639456404;9396.342256042011;9519.809603807886;9546;9562.330636855493;9563.234941204686;547669;553386;565497;568003;587738;42746.572534274885;43360.783196083816;68829.65585172042;69932;73437.26562734411;1046.4715318763976;1055.994720026395;1066.9679909602723;1068.738159151013;1073.5544748929262;14004.509842155476;14529.927350363181;14806.925965370105;14850.554483365517;15108.244587770349;29241.85379073091;29259.41481170407;30190.69809301879;30669.086436788468;31456.370872582876;38340.23319533649;39169.824905252884;39475.60524394834;39523.80238098791;39558.604413955494;6615;6891.689873955584;7037.1555413350725;7154.928450715426;7180.497365184596;9949.502524873873;9955.850662239927;10161.644342447978;10419.791604167722;10708.678739637824;8789.91210087891;9233.445993240428;9263.490508022123;9359;9417.105374989365;8898.243649289883;9354.391964522285;9384.57769400365;9561.713148605553;9566.952165239407;8723.694670686495;9189.310801690033;9337.71986840396;9554.61781528759;9660.275479338938;6854.451643868579;7038.225795162426;7153.309376151147;7276.126864776261;7426.220246874216;8659.913400866162;9572;9622.807543848943;9672.516374181121;9847.950760246153 01:05:38 INFO - PROCESS | 4575 | |3;mootools.html;1472.742270102732;1486.1083349990004;1647.3657641807908;1749.9026722700812;1787.1868299923528;7483.10202775667;7487.138979017414;7556.924430755691;7674.616269186539;7685.423593230509;7407.296306850854;7443.59060251686;7505.28699773522;7512.924870751291;7515.023047003896;2315.9305220843357;2338.497223097034;2348.8355815092864;2349.34218418843;2352.541254455382;2322.5238826040622;2335.7781010803947;2379.3218932604213;2389.7849193572574;2389.9641505377435;2031.238285642884;2061.9587608247825;2332.836701430892;2340.1224540797202;2356.7289761677457;2299.839011269212;2315.2012555668266;2324.732655744586;2330.498942727305;2339.7192336919593;2247.213475283654;2290.7594702556157;2321.257197696738;2326.953460930772;2338.9240949163404;7546.962265188639;7610;7630.122535908386;7631.198724133941;7633.9618301908695;4740;4786.928196077061;4826.083044221622;4847.2729090636685;4849.442314133885;3165.5884734984306;3176.3806057818733;3259.1852036990754;3289.7697161198485;3309.1396236978317;1039.3348257115376;1046.1108058150512;1056.10758908722;1084.4957094950807;1092.7759809239087;10385.06534411899;10417.687469375931;10421.426821524814;10446.320989135682;10463.110635595984;4316.223079845658;4367.148406060787;4428.645708343325;4433.135538570013;4448.021435284232;8505.192006759347;8561.272291855137;8561.272291855263;8563.914360856312;8569.400141990001;4870.732109733928;4879.072976134523;4891.413030436371;4892.217245240744;4897.730624815598;4478.664100192498;4482.327650852399;4488.326750987378;4498.527654596286;4499.392582001388;3405.5851196597528;3432.9313413731584;3451.516787649731;3451.8274086295582;3481.860725570949;1061.7345663584103;1072.9785404292027;1079.8488211650376;1079.9676009719722;1082.242430298794;964.4791812421214;1007.8992100789862;1060.6393826099008;1072.0565902006185;1089.8855620159768;6933.653317334113;7046.718131274692;7107.82230444243;7122.109736282965;7144.142702875688;572.6077636818792;578.0808514462026;579.7333226715758;635.2028204603193;780.3484090784259;5519.641223320471;5555.000099982042;5576.302962129734;5645.576581756384;5659.349174844863;8991.145841145211;9298.442093474277;9363.063693630718;9379.953100234592;9420.199283061016;9093.454392736458;9124.635014599475;9186.494742789077;9261.07389261082;9285.907140928504;7199.568025918462;7319.194888562254;7322.1945585985495;7332.706691732377;7356.558606483628;6892.448604111559;6957.8956315654295;6966.338197871194;7013.193482749549;7014.508984371045;8133.308668762985;8251.546164960984;8342.749717508485;8346.874796878174;8514.55252607046;8516.020657624453;8604;8629.654813807629;8644.610992505226;8720.694775682821;6570.310117437601;6570.868582628416;6622.90065649006;6631.3700198481065;6650.866982660416;7113.288671133052;7257.564546127249;7292.708291668274;7293.088363954505;7346.816329591803;6993;7093.14882214117;7114.964425177841;7194.172670143001;7245.492815502864;6176.474999625082;6190.7523699051535;6729.360710732669;6784.118064651564;6909.689063992233;6755.594664320157;6760.256371799;6844.315568443116;6846.212685541226;6865.450763938973;6532.83667908287;6559.7376104957175;6568.343165683394;6572.244191917991;6572.934270657423;6520.836979075371;6566.9343306566325;6570.277269500447;6590.3739144783085;6591.703373348115 01:05:38 INFO - PROCESS | 4575 | |4;prototype.html;325.7003556727811;327.4809427058113;327.85082787331766;331.6186385656496;333.90817525180574;6230.190075290212;6279.4254355714165;6374.266959299682;6411.711472983715;6448.742050317982;6194;6204.13142160098;6209.068639704046;6218.25380954285;6244.937550624492;4784.40194975628;4793.5925446337105;4796.784144713479;4799.448063472693;4806.687565308262;4769.809207631691;4783.473817880031;4786.521347865203;4788;4788.88027799306;4647.930281045787;4737.407824021997;4763.595094416979;4768.570828625423;4779.426468823746;4737.407824021997;4739.881502962437;4743.312219728155;4763.618910487153;4765.451973023112;4109.732867363612;4120.763770868757;4130.112025914443;4134.090500089976;4140.192662430827;5971.910421343683;5989.640621562677;5998.310194327664;6010.939890601126;6013.579049466539;5428.565714742811;5499.4225606311165;5525.336959564837;5535.086710692771;5553;6650.467962562983;6669.699863506154;6676.399124078807;6678.131842860397;6694.430973367221;4559.316102584638;4575.107853968478;4584.564466375689;4587.3807036050375;4604.631629469634;6832.350926661959;6925.688344024581;6939.722411103549;6996.930030699678;7008.579485230903;4554.544545545419;4569.702969306984;4583.931241031387;4584.816607335669;4586.082783443325;4647.907041859212;4652.837150699709;4657.743824089707;4674.555917187861;4679.555442232982;5416.864578385572;5421.593380496479;5426.457354264542;5433.022056029874;5440.700761458079;6582.111414959015;6665.333466653393;6703.162104736908;6713.295104014106;6719.328067193242;1599.3282821215118;1619.8218195998431;1628.894121882074;1641.5731909703325;1642.868570514356;1065.1851333729703;1073.935563866155;1100.1078145589572;1113.3542545323692;1117.2626066795876;6171.352008039181;6173.69131543421;6195.907061394083;6207.81376558704;6296.527760417987;6672.9332706673285;6678.065070890079;6709.261981182066;6717.932820671828;6718.8320291993095;6153.384661533811;6257.5932564383165;6294.276158241769;6311.715972781236;6318.462930650946;3672.081979505124;3676.356637588411;3678.5401824771902;3686.189038411545;3689.1146124929824;7033.9296607033275;7046.6476676165985;7060.858782824314;7064.364207221272;7076.823079423056;6789.558678685869;6920.411765000032;6949.270326615733;6963.7562685305775;7012.7545535906;7058.541194822423;7073.929260707327;7079.787606371817;7082.610456424946;7087.645617719197;6708.362705542965;6746.4265537429865;6794.72821087151;6804.727810887509;6823.351781580742;6602.702878370389;6657.800265992028;6685.799426017227;6713.56361836479;6723.831904202239;7058.294170582901;7079.893801592877;7083.787486375417;7102.644867756489;7111.608861512666;4565.566271204363;4579.885502862455;4586.931196031996;4594.88512787183;4598.908021839477;6762.087118238975;6793.592384456949;6801.149856267966;6818.93181068183;6997.545159564612;6699.162604674416;6722.058911752456;6727.192736871607;6744.22441419243;6785.880329745537;6715.630640314635;6730.461563074844;6734.629595372302;6742.325767423415;6769.593824370553;6403.711832967434;6497.18785151856;6558.081868538504;6567.343265673395;6641.900371494335;6770.729170833309;6801.2178599461695;6810.82972925661;6811.2167100784045;6813.557118787263;6629.370209830058;6676.799696009128;6716.697748601422;6730.562513436611;6734.595924244561 01:05:38 INFO - PROCESS | 4575 | |5;yui.html;773.8065483629093;781.4842204145261;833.275050705886;841.6128580852808;859.0850743957685;13013.67465813354;13112.541061062864;13120.409581568829;13150.276734779583;13183.6704082398;12531.436085376157;12643.178193417421;12652.93673531631;12674.936625316883;12695.238285702864;795.6101510259974;810.3395732478011;825.2613910550054;836.3016880904452;836.4270474724833;815.8449894520052;823.3084209264217;827.2141465607667;827.7847759582492;830.6760363458257;854.598338780772;860.8192279621287;866.0127454701645;866.0386970462813;868.1578868497547;843.5824266987819;849.723839752083;852.7441767469796;854.6410507586828;854.8375808596347;635.3582881289931;635.94594459471;636.4431122767578;637.3148864970176;640.5612155673332;13530.188188708611;13544.661383465394;13571.253581053039;13593.660158496017;13622;12892.80660790089;13053.804192937114;13173.736525269536;13214.783821478439;13330.466781328736;9789.314747967645;9892.604295828158;9986.251031172618;10319.226058045677;10441.738956526071;3456.5160877477174;3626.510421093145;3633.745637805355;3634.527511423498;3679.208970071421;49891.25163122556;50124.997500049794;50254;50585.74707126478;50691.74654126743;9617.951910240545;9771.51142442875;10231.13035391985;10296.330738501973;10347.379157250438;17752.11239438023;18415;18433.90783046076;18435.53911152232;18492.167852446673;7734.7679569613;7875.488093273919;7883.645235964395;7903.762887113396;8046.798830029296;4476.782275515617;4478.955210447919;4505.549445055468;4514.836883955497;4649.628029757611;3117.4076925384106;3135.372925414926;3140.0422871024102;3147.512135618983;3154.2902846859274;564.6132399306488;571.7141429285357;573.7102763104689;580.89834279001;582.4466756581264;524.8530411484791;546.956243500519;611.2817439508559;647.4011539326104;661.4642139866723;8285.792855178548;8632.266257368194;8725.258353039935;8807.295416366675;8835.690750823818;501.8745313671582;560.4731552340787;587.4448646029547;597.8595030167907;694.3646563394539;3815.523059617548;3983.2232714620372;3992.720509564361;4088.448059511928;4103.610157035077;51988.48011519836;58874;58887.41112588905;59117.704411478524;59224;50596.49403505991;50676.239856401444;50682.493175068514;52489;52502.47497525052;43223.78388108102;43365.783171083945;45095;45571.08857822825;46899;7898.802529936797;7957.482763620346;8075.757727268191;8120.431569790058;8197.79505512367;33641.3271734559;33797;33965;34835.47746783851;35619.82190089033;41329.58670413258;41961.58038419577;45988.54011459977;46043.76978115088;46369;9803.705888823346;9858;9949.552270147717;9975.501224938578;10222.335548189343;11593.478293476644;11678.591249306233;11720.824187637021;11759.764804703687;11768.411579421167;9392.483413412328;9672.95163524178;9826;9853.211743060394;9870.161036311707;10897.59471968129;11287.604933827277;11328.376939268419;11344.092472602008;11806.114541409257;9939.552720127756;10353.792924141626;10594.682159535227;10601.840972385575;11035.172362073037;8736.213740763362;8753.91246087531;8992.59533320989;9066.365354425126;9161.633534658538;8245.175482451707;8402.495850249004;8629;8698.869516957377;8851.159139881958 01:05:38 INFO - PROCESS | 4575 | __end_tp_report 01:05:38 INFO - PROCESS | 4575 | __start_cc_report 01:05:38 INFO - PROCESS | 4575 | _x_x_mozilla_cycle_collect,306 01:05:38 INFO - PROCESS | 4575 | __end_cc_report 01:05:38 INFO - PROCESS | 4575 | __startTimestamp1453712738179__endTimestamp 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | ------- Summary: start ------- 01:05:38 INFO - PROCESS | 4575 | Number of tests: 6 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#0] dojo.html Cycles:165 Average:5176.57 Median:5364.84 stddev:556.43 (10.4%) stddev-sans-first:534.91 01:05:38 INFO - PROCESS | 4575 | Values: 3148.6 3148.7 3173.7 3193.5 3195.1 5094.8 5115.7 5145.6 5179.4 5204.9 5147.9 5171.5 5187.4 5193.0 5220.2 4668.9 4707.7 4709.8 4779.7 4836.3 4494.0 4792.9 4806.9 4860.9 4867.4 4696.3 4701.9 4748.3 4766.8 4789.9 4709.7 4729.9 4737.5 4741.3 4742.3 4603.9 4711.5 4733.9 4735.7 4769.7 5031.3 5036.0 5041.1 5042.6 5077.6 5523.1 5525.8 5526.0 5540.5 5567.0 5574.5 5575.7 5577.1 5579.0 5583.2 5164.8 5247.0 5259.0 5274.2 5349.6 5686.4 5692.3 5722.1 5733.7 5736.6 5491.3 5494.8 5498.1 5501.9 5514.0 5454.2 5476.6 5557.9 5586.7 5648.9 5473.0 5487.7 5501.3 5506.1 5507.3 5417.6 5507.4 5537.6 5546.0 5579.3 4343.9 4352.6 4388.1 4394.0 4428.1 3899.2 3899.3 3900.4 3912.3 3940.9 5167.4 5227.8 5298.6 5300.6 5364.8 5580.3 5606.8 5627.6 5645.7 5668.2 5214.5 5234.8 5259.5 5261.7 5281.3 4733.3 4752.2 4759.3 4779.4 4850.4 5787.3 5792.1 5807.3 5808.2 5826.1 5522.3 5534.3 5534.8 5559.9 5579.4 5578.2 5669.2 5676.4 5740.7 5776.7 5435.8 5442.0 5448.8 5449.5 5453.6 5649.9 5669.3 5691.0 5693.7 5735.7 5463.3 5483.3 5504.1 5508.1 5510.8 5247.9 5433.8 5484.3 5491.3 5539.0 5318.1 5331.4 5334.7 5336.7 5380.9 5371.3 5455.9 5458.4 5458.8 5464.2 5246.8 5259.7 5360.8 5384.7 5454.3 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#1] ext.html Cycles:180 Average:45934.16 Median:165661.41 stddev:80236.55 (48.4%) stddev-sans-first:80389.21 01:05:38 INFO - PROCESS | 4575 | Values: 530.8 531.5 554.8 560.9 562.6 34328.8 34890.8 35186.6 35225.3 35322.0 38276.4 38307.8 38584.4 38618.8 38636.8 8280.4 8461.2 8512.4 8521.5 8564.1 8129.1 8298.1 8376.1 8582.0 8586.3 8460.5 8594.8 8605.6 8618.4 8639.9 8371.4 8532.2 8541.8 8579.9 8598.5 8409.2 8537.2 8577.5 8606.6 8613.8 39149.4 39405.4 39439.8 39510.2 39594.0 3357.9 3467.7 3529.6 3643.4 3818.0 28275.4 29080.6 29272.9 29279.3 29320.7 3217.5 3264.9 3299.8 3391.0 3396.3 280962.0 282178.0 282607.0 282738.0 284030.0 51465.5 52227.5 53708.7 55917.4 56765.4 52829.0 52895.5 53020.7 53245.2 53422.0 13808.8 13899.8 13905.6 13952.2 13994.7 10456.1 10458.0 10538.2 10543.9 10607.2 18911.9 19137.3 19188.1 19201.7 19287.8 7369.4 7487.2 7487.4 7571.9 7578.9 978.3 991.9 1020.7 1077.6 1112.1 27608.4 28203.9 28293.3 28347.7 29563.0 1023.5 1057.0 1075.3 1096.0 1115.9 14009.2 14072.4 14153.7 14195.6 14316.1 328023.0 331811.0 332010.0 332094.0 332661.0 49188.8 49669.8 49738.8 49752.8 49975.5 1352.2 1364.5 1367.5 1371.3 1372.4 26459.2 27481.9 27619.6 27660.9 27819.4 43389.6 44585.8 44637.6 44696.1 44875.6 299612.0 301417.0 303989.0 304082.0 305079.0 30953.4 31144.4 31664.4 31674.5 31808.0 37523.0 37699.4 38049.6 38215.6 38680.8 30386.4 30646.4 30662.4 30799.0 32629.7 31522.0 32130.0 32298.8 32393.4 32521.2 31540.5 31843.5 32000.8 32093.8 32126.2 27970.6 31193.4 31241.2 31364.4 31567.4 23877.8 23904.3 24199.5 24202.6 24322.6 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#2] jquery.html Cycles:180 Average:27274.29 Median:5055.54 stddev:92100.47 (1821.8%) stddev-sans-first:92336.67 01:05:38 INFO - PROCESS | 4575 | Values: 368.5 370.9 371.9 374.4 384.7 15575.7 16611.4 16756.8 17230.2 17282.5 9945.3 10189.2 10278.2 10281.8 10481.3 4749.9 4829.0 4910.0 4922.8 5559.9 5401.1 5534.1 5538.2 5543.6 5548.1 4838.5 5054.8 5056.3 5279.7 5511.0 4963.8 5008.6 5010.5 5091.7 5810.1 5155.8 5197.8 5220.4 5221.1 5243.2 11680.5 18853.2 19471.7 19839.6 19846.5 2115.9 2144.8 2189.0 2212.9 2274.1 8832.9 9068.0 9113.6 9858.8 13347.9 1675.2 1781.2 1805.7 1814.8 1866.0 53539.2 53820.7 54125.0 55702.4 55769.4 9585.4 10018.9 10061.3 10208.2 10208.9 12982.3 13082.7 13141.9 13159.7 13171.0 6926.7 7023.8 7524.0 7623.2 7637.9 7347.4 7474.8 7560.9 7580.2 7604.9 3995.8 4093.5 4419.0 4434.3 4451.2 1754.5 1772.8 1794.0 1828.2 1829.6 865.0 957.5 1016.0 1040.6 1051.6 14700.5 15150.5 15275.5 16102.6 16559.0 907.4 927.5 937.7 965.6 988.2 9396.3 9519.8 9546.0 9562.3 9563.2 547669.0 553386.0 565497.0 568003.0 587738.0 42746.6 43360.8 68829.7 69932.0 73437.3 1046.5 1056.0 1067.0 1068.7 1073.6 14004.5 14529.9 14806.9 14850.6 15108.2 29241.9 29259.4 30190.7 30669.1 31456.4 38340.2 39169.8 39475.6 39523.8 39558.6 6615.0 6891.7 7037.2 7154.9 7180.5 9949.5 9955.9 10161.6 10419.8 10708.7 8789.9 9233.4 9263.5 9359.0 9417.1 8898.2 9354.4 9384.6 9561.7 9567.0 8723.7 9189.3 9337.7 9554.6 9660.3 6854.5 7038.2 7153.3 7276.1 7426.2 8659.9 9572.0 9622.8 9672.5 9848.0 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#3] mootools.html Cycles:180 Average:5382.31 Median:6526.84 stddev:2760.60 (42.3%) stddev-sans-first:2752.70 01:05:38 INFO - PROCESS | 4575 | Values: 1472.7 1486.1 1647.4 1749.9 1787.2 7483.1 7487.1 7556.9 7674.6 7685.4 7407.3 7443.6 7505.3 7512.9 7515.0 2315.9 2338.5 2348.8 2349.3 2352.5 2322.5 2335.8 2379.3 2389.8 2390.0 2031.2 2062.0 2332.8 2340.1 2356.7 2299.8 2315.2 2324.7 2330.5 2339.7 2247.2 2290.8 2321.3 2327.0 2338.9 7547.0 7610.0 7630.1 7631.2 7634.0 4740.0 4786.9 4826.1 4847.3 4849.4 3165.6 3176.4 3259.2 3289.8 3309.1 1039.3 1046.1 1056.1 1084.5 1092.8 10385.1 10417.7 10421.4 10446.3 10463.1 4316.2 4367.1 4428.6 4433.1 4448.0 8505.2 8561.3 8561.3 8563.9 8569.4 4870.7 4879.1 4891.4 4892.2 4897.7 4478.7 4482.3 4488.3 4498.5 4499.4 3405.6 3432.9 3451.5 3451.8 3481.9 1061.7 1073.0 1079.8 1080.0 1082.2 964.5 1007.9 1060.6 1072.1 1089.9 6933.7 7046.7 7107.8 7122.1 7144.1 572.6 578.1 579.7 635.2 780.3 5519.6 5555.0 5576.3 5645.6 5659.3 8991.1 9298.4 9363.1 9380.0 9420.2 9093.5 9124.6 9186.5 9261.1 9285.9 7199.6 7319.2 7322.2 7332.7 7356.6 6892.4 6957.9 6966.3 7013.2 7014.5 8133.3 8251.5 8342.7 8346.9 8514.6 8516.0 8604.0 8629.7 8644.6 8720.7 6570.3 6570.9 6622.9 6631.4 6650.9 7113.3 7257.6 7292.7 7293.1 7346.8 6993.0 7093.1 7115.0 7194.2 7245.5 6176.5 6190.8 6729.4 6784.1 6909.7 6755.6 6760.3 6844.3 6846.2 6865.5 6532.8 6559.7 6568.3 6572.2 6572.9 6520.8 6566.9 6570.3 6590.4 6591.7 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#4] prototype.html Cycles:180 Average:5534.32 Median:6251.27 stddev:1688.85 (27.0%) stddev-sans-first:1647.71 01:05:38 INFO - PROCESS | 4575 | Values: 325.7 327.5 327.9 331.6 333.9 6230.2 6279.4 6374.3 6411.7 6448.7 6194.0 6204.1 6209.1 6218.3 6244.9 4784.4 4793.6 4796.8 4799.4 4806.7 4769.8 4783.5 4786.5 4788.0 4788.9 4647.9 4737.4 4763.6 4768.6 4779.4 4737.4 4739.9 4743.3 4763.6 4765.5 4109.7 4120.8 4130.1 4134.1 4140.2 5971.9 5989.6 5998.3 6010.9 6013.6 5428.6 5499.4 5525.3 5535.1 5553.0 6650.5 6669.7 6676.4 6678.1 6694.4 4559.3 4575.1 4584.6 4587.4 4604.6 6832.4 6925.7 6939.7 6996.9 7008.6 4554.5 4569.7 4583.9 4584.8 4586.1 4647.9 4652.8 4657.7 4674.6 4679.6 5416.9 5421.6 5426.5 5433.0 5440.7 6582.1 6665.3 6703.2 6713.3 6719.3 1599.3 1619.8 1628.9 1641.6 1642.9 1065.2 1073.9 1100.1 1113.4 1117.3 6171.4 6173.7 6195.9 6207.8 6296.5 6672.9 6678.1 6709.3 6717.9 6718.8 6153.4 6257.6 6294.3 6311.7 6318.5 3672.1 3676.4 3678.5 3686.2 3689.1 7033.9 7046.6 7060.9 7064.4 7076.8 6789.6 6920.4 6949.3 6963.8 7012.8 7058.5 7073.9 7079.8 7082.6 7087.6 6708.4 6746.4 6794.7 6804.7 6823.4 6602.7 6657.8 6685.8 6713.6 6723.8 7058.3 7079.9 7083.8 7102.6 7111.6 4565.6 4579.9 4586.9 4594.9 4598.9 6762.1 6793.6 6801.1 6818.9 6997.5 6699.2 6722.1 6727.2 6744.2 6785.9 6715.6 6730.5 6734.6 6742.3 6769.6 6403.7 6497.2 6558.1 6567.3 6641.9 6770.7 6801.2 6810.8 6811.2 6813.6 6629.4 6676.8 6716.7 6730.6 6734.6 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - PROCESS | 4575 | [#5] yui.html Cycles:180 Average:13637.16 Median:26506.93 stddev:16018.76 (60.4%) stddev-sans-first:16034.57 01:05:38 INFO - PROCESS | 4575 | Values: 773.8 781.5 833.3 841.6 859.1 13013.7 13112.5 13120.4 13150.3 13183.7 12531.4 12643.2 12652.9 12674.9 12695.2 795.6 810.3 825.3 836.3 836.4 815.8 823.3 827.2 827.8 830.7 854.6 860.8 866.0 866.0 868.2 843.6 849.7 852.7 854.6 854.8 635.4 635.9 636.4 637.3 640.6 13530.2 13544.7 13571.3 13593.7 13622.0 12892.8 13053.8 13173.7 13214.8 13330.5 9789.3 9892.6 9986.3 10319.2 10441.7 3456.5 3626.5 3633.7 3634.5 3679.2 49891.3 50125.0 50254.0 50585.7 50691.7 9618.0 9771.5 10231.1 10296.3 10347.4 17752.1 18415.0 18433.9 18435.5 18492.2 7734.8 7875.5 7883.6 7903.8 8046.8 4476.8 4479.0 4505.5 4514.8 4649.6 3117.4 3135.4 3140.0 3147.5 3154.3 564.6 571.7 573.7 580.9 582.4 524.9 547.0 611.3 647.4 661.5 8285.8 8632.3 8725.3 8807.3 8835.7 501.9 560.5 587.4 597.9 694.4 3815.5 3983.2 3992.7 4088.4 4103.6 51988.5 58874.0 58887.4 59117.7 59224.0 50596.5 50676.2 50682.5 52489.0 52502.5 43223.8 43365.8 45095.0 45571.1 46899.0 7898.8 7957.5 8075.8 8120.4 8197.8 33641.3 33797.0 33965.0 34835.5 35619.8 41329.6 41961.6 45988.5 46043.8 46369.0 9803.7 9858.0 9949.6 9975.5 10222.3 11593.5 11678.6 11720.8 11759.8 11768.4 9392.5 9673.0 9826.0 9853.2 9870.2 10897.6 11287.6 11328.4 11344.1 11806.1 9939.6 10353.8 10594.7 10601.8 11035.2 8736.2 8753.9 8992.6 9066.4 9161.6 8245.2 8402.5 8629.0 8698.9 8851.2 01:05:38 INFO - PROCESS | 4575 | -------- Summary: end -------- 01:05:38 INFO - PROCESS | 4575 | 01:05:38 INFO - TEST-INFO | 4575: exit 0 01:05:38 INFO - TEST-OK | dromaeo_css | took 1110194ms 01:05:38 INFO - TEST-START | kraken 01:05:38 INFO - operating with platform_type : linux_ 01:05:38 INFO - Initialising browser for kraken test... 01:05:38 INFO - TEST-INFO | started process 4821 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGkrzUh/profile http://localhost:35224/getInfo.html) 01:05:42 INFO - PROCESS | 4821 | 01:05:42 INFO - PROCESS | 4821 | (process:4893): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:05:43 INFO - PROCESS | 4821 | __metrics Screen width/height:1600/1200 01:05:43 INFO - PROCESS | 4821 | colorDepth:24 01:05:43 INFO - PROCESS | 4821 | Browser inner width/height: 1024/697 01:05:43 INFO - PROCESS | 4821 | __metrics 01:05:43 INFO - PROCESS | 4821 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 01:05:44 INFO - TEST-INFO | 4821: exit 0 01:05:44 INFO - Browser initialized. 01:05:44 INFO - Running cycle 1/1 for kraken test... 01:05:44 INFO - TEST-INFO | started process 4942 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGkrzUh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:05:45 INFO - PROCESS | 4942 | 01:05:45 INFO - PROCESS | 4942 | (process:5003): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:05:55 INFO - PROCESS | 4942 | RSS: Main: 150368256 01:05:55 INFO - PROCESS | 4942 | 01:05:58 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35224/tests/kraken/driver.html?testName=audio-beat-detection) 01:05:58 INFO - PROCESS | 4942 | RSS: Main: 154914816 01:05:58 INFO - PROCESS | 4942 | 01:06:01 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35224/tests/kraken/driver.html?testName=audio-dft) 01:06:02 INFO - PROCESS | 4942 | RSS: Main: 158896128 01:06:02 INFO - PROCESS | 4942 | 01:06:05 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35224/tests/kraken/driver.html?testName=audio-fft) 01:06:05 INFO - PROCESS | 4942 | RSS: Main: 153157632 01:06:05 INFO - PROCESS | 4942 | 01:06:07 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35224/tests/kraken/driver.html?testName=audio-oscillator) 01:06:08 INFO - PROCESS | 4942 | RSS: Main: 152334336 01:06:08 INFO - PROCESS | 4942 | 01:06:10 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:06:11 INFO - PROCESS | 4942 | RSS: Main: 152600576 01:06:11 INFO - PROCESS | 4942 | 01:06:14 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35224/tests/kraken/driver.html?testName=imaging-darkroom) 01:06:14 INFO - PROCESS | 4942 | RSS: Main: 153550848 01:06:14 INFO - PROCESS | 4942 | 01:06:17 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35224/tests/kraken/driver.html?testName=imaging-desaturate) 01:06:18 INFO - PROCESS | 4942 | RSS: Main: 153956352 01:06:18 INFO - PROCESS | 4942 | 01:06:21 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35224/tests/kraken/driver.html?testName=json-parse-financial) 01:06:21 INFO - PROCESS | 4942 | RSS: Main: 154243072 01:06:22 INFO - PROCESS | 4942 | 01:06:23 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:06:24 INFO - PROCESS | 4942 | RSS: Main: 154746880 01:06:24 INFO - PROCESS | 4942 | 01:06:25 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:06:26 INFO - PROCESS | 4942 | RSS: Main: 152887296 01:06:26 INFO - PROCESS | 4942 | 01:06:28 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:06:29 INFO - PROCESS | 4942 | RSS: Main: 154431488 01:06:29 INFO - PROCESS | 4942 | 01:06:31 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:06:32 INFO - PROCESS | 4942 | RSS: Main: 154931200 01:06:32 INFO - PROCESS | 4942 | 01:06:34 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:06:35 INFO - PROCESS | 4942 | RSS: Main: 153440256 01:06:35 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | Cycle 1(1): loaded http://localhost:35224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35224/tests/kraken/driver.html?testName=ai-astar) 01:06:37 INFO - PROCESS | 4942 | RSS: Main: 154615808 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | __start_tp_report 01:06:37 INFO - PROCESS | 4942 | _x_x_mozilla_page_load 01:06:37 INFO - PROCESS | 4942 | _x_x_mozilla_page_load_details 01:06:37 INFO - PROCESS | 4942 | |i|pagename|runs| 01:06:37 INFO - PROCESS | 4942 | |0;ai-astar;112;107;112;110;113;122;112;113;113;112 01:06:37 INFO - PROCESS | 4942 | |1;audio-beat-detection;154;127;150;173;129;156;158;129;159;127 01:06:37 INFO - PROCESS | 4942 | |2;audio-dft;162;163;161;162;162;160;160;163;165;160 01:06:37 INFO - PROCESS | 4942 | |3;audio-fft;72;72;73;74;97;144;75;73;74;73 01:06:37 INFO - PROCESS | 4942 | |4;audio-oscillator;96;95;144;95;96;96;143;95;96;148 01:06:37 INFO - PROCESS | 4942 | |5;imaging-gaussian-blur;110;110;109;109;109;109;110;109;109;111 01:06:37 INFO - PROCESS | 4942 | |6;imaging-darkroom;147;147;147;146;146;148;147;145;151;147 01:06:37 INFO - PROCESS | 4942 | |7;imaging-desaturate;96;97;98;96;96;97;96;97;97;96 01:06:37 INFO - PROCESS | 4942 | |8;json-parse-financial;58;59;60;59;61;62;59;58;63;56 01:06:37 INFO - PROCESS | 4942 | |9;json-stringify-tinderbox;68;68;69;68;69;67;68;68;69;69 01:06:37 INFO - PROCESS | 4942 | |10;stanford-crypto-aes;82;82;91;89;83;83;83;82;89;83 01:06:37 INFO - PROCESS | 4942 | |11;stanford-crypto-ccm;113;121;130;122;119;123;119;119;118;119 01:06:37 INFO - PROCESS | 4942 | |12;stanford-crypto-pbkdf2;132;148;142;144;147;130;154;158;141;142 01:06:37 INFO - PROCESS | 4942 | |13;stanford-crypto-sha256-iterative;68;71;67;68;73;67;68;69;69;67 01:06:37 INFO - PROCESS | 4942 | __end_tp_report 01:06:37 INFO - PROCESS | 4942 | __start_cc_report 01:06:37 INFO - PROCESS | 4942 | _x_x_mozilla_cycle_collect,726 01:06:37 INFO - PROCESS | 4942 | __end_cc_report 01:06:37 INFO - PROCESS | 4942 | __startTimestamp1453712797345__endTimestamp 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | ------- Summary: start ------- 01:06:37 INFO - PROCESS | 4942 | Number of tests: 14 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:3.78 (3.4%) stddev-sans-first:4.00 01:06:37 INFO - PROCESS | 4942 | Values: 112.0 107.0 112.0 110.0 113.0 122.0 112.0 113.0 113.0 112.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:155.00 stddev:16.74 (10.8%) stddev-sans-first:17.51 01:06:37 INFO - PROCESS | 4942 | Values: 154.0 127.0 150.0 173.0 129.0 156.0 158.0 129.0 159.0 127.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.62 (1.0%) stddev-sans-first:1.72 01:06:37 INFO - PROCESS | 4942 | Values: 162.0 163.0 161.0 162.0 162.0 160.0 160.0 163.0 165.0 160.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#3] audio-fft Cycles:10 Average:82.70 Median:73.50 stddev:22.81 (31.0%) stddev-sans-first:23.87 01:06:37 INFO - PROCESS | 4942 | Values: 72.0 72.0 73.0 74.0 97.0 144.0 75.0 73.0 74.0 73.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#4] audio-oscillator Cycles:10 Average:110.40 Median:95.50 stddev:23.91 (25.0%) stddev-sans-first:24.79 01:06:37 INFO - PROCESS | 4942 | Values: 96.0 95.0 144.0 95.0 96.0 96.0 143.0 95.0 96.0 148.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:109.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 01:06:37 INFO - PROCESS | 4942 | Values: 110.0 110.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 111.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:1.60 (1.1%) stddev-sans-first:1.69 01:06:37 INFO - PROCESS | 4942 | Values: 147.0 147.0 147.0 146.0 146.0 148.0 147.0 145.0 151.0 147.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:97.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 01:06:37 INFO - PROCESS | 4942 | Values: 96.0 97.0 98.0 96.0 96.0 97.0 96.0 97.0 97.0 96.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:59.50 stddev:2.07 (3.5%) stddev-sans-first:2.12 01:06:37 INFO - PROCESS | 4942 | Values: 58.0 59.0 60.0 59.0 61.0 62.0 59.0 58.0 63.0 56.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 01:06:37 INFO - PROCESS | 4942 | Values: 68.0 68.0 69.0 68.0 69.0 67.0 68.0 68.0 69.0 69.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:83.00 stddev:3.50 (4.2%) stddev-sans-first:3.57 01:06:37 INFO - PROCESS | 4942 | Values: 82.0 82.0 91.0 89.0 83.0 83.0 83.0 82.0 89.0 83.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#11] stanford-crypto-ccm Cycles:10 Average:120.30 Median:120.00 stddev:4.35 (3.6%) stddev-sans-first:3.72 01:06:37 INFO - PROCESS | 4942 | Values: 113.0 121.0 130.0 122.0 119.0 123.0 119.0 119.0 118.0 119.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.80 Median:145.50 stddev:8.68 (6.0%) stddev-sans-first:8.08 01:06:37 INFO - PROCESS | 4942 | Values: 132.0 148.0 142.0 144.0 147.0 130.0 154.0 158.0 141.0 142.0 01:06:37 INFO - PROCESS | 4942 | 01:06:37 INFO - PROCESS | 4942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:1.95 (2.8%) stddev-sans-first:2.05 01:06:37 INFO - PROCESS | 4942 | Values: 68.0 71.0 67.0 68.0 73.0 67.0 68.0 69.0 69.0 67.0 01:06:37 INFO - PROCESS | 4942 | -------- Summary: end -------- 01:06:37 INFO - PROCESS | 4942 | 01:06:38 INFO - TEST-INFO | 4942: exit 0 01:06:38 INFO - TEST-OK | kraken | took 59454ms 01:06:38 INFO - TEST-START | v8_7 01:06:38 INFO - operating with platform_type : linux_ 01:06:38 INFO - Initialising browser for v8_7 test... 01:06:38 INFO - TEST-INFO | started process 5232 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4zi8Z9/profile http://localhost:35224/getInfo.html) 01:06:41 INFO - PROCESS | 5232 | 01:06:41 INFO - PROCESS | 5232 | (process:5306): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:06:42 INFO - PROCESS | 5232 | __metrics Screen width/height:1600/1200 01:06:42 INFO - PROCESS | 5232 | colorDepth:24 01:06:42 INFO - PROCESS | 5232 | Browser inner width/height: 1024/697 01:06:42 INFO - PROCESS | 5232 | __metrics 01:06:42 INFO - PROCESS | 5232 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 01:06:43 INFO - TEST-INFO | 5232: exit 0 01:06:43 INFO - Browser initialized. 01:06:43 INFO - Running cycle 1/1 for v8_7 test... 01:06:43 INFO - TEST-INFO | started process 5355 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4zi8Z9/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:06:45 INFO - PROCESS | 5355 | 01:06:45 INFO - PROCESS | 5355 | (process:5416): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 01:06:55 INFO - PROCESS | 5355 | RSS: Main: 151965696 01:06:55 INFO - PROCESS | 5355 | 01:07:17 INFO - PROCESS | 5355 | Cycle 1(1): loaded http://localhost:35224/tests/v8_7/run.html (next: http://localhost:35224/tests/v8_7/run.html) 01:07:18 INFO - PROCESS | 5355 | RSS: Main: 150937600 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | __start_tp_report 01:07:18 INFO - PROCESS | 5355 | _x_x_mozilla_page_load 01:07:18 INFO - PROCESS | 5355 | _x_x_mozilla_page_load_details 01:07:18 INFO - PROCESS | 5355 | |i|pagename|runs| 01:07:18 INFO - PROCESS | 5355 | |0;Richards;168.26518593303047 01:07:18 INFO - PROCESS | 5355 | |1;DeltaBlue;188.39487565938205 01:07:18 INFO - PROCESS | 5355 | |2;Encrypt;279.4857462269424 01:07:18 INFO - PROCESS | 5355 | |3;Decrypt;5229.166666666667 01:07:18 INFO - PROCESS | 5355 | |4;RayTrace;1067.2358591248667 01:07:18 INFO - PROCESS | 5355 | |5;Earley;736.3770250368188 01:07:18 INFO - PROCESS | 5355 | |6;Boyer;12180.722891566265 01:07:18 INFO - PROCESS | 5355 | |7;RegExp;41857.142857142855 01:07:18 INFO - PROCESS | 5355 | |8;Splay;589.2751915144372 01:07:18 INFO - PROCESS | 5355 | |9;NavierStokes;4850.241545893719 01:07:18 INFO - PROCESS | 5355 | __end_tp_report 01:07:18 INFO - PROCESS | 5355 | __startTimestamp1453712838222__endTimestamp 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | ------- Summary: start ------- 01:07:18 INFO - PROCESS | 5355 | Number of tests: 10 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#0] Richards Cycles:1 Average:168.27 Median:168.27 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 168.3 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#1] DeltaBlue Cycles:1 Average:188.39 Median:188.39 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 188.4 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 279.5 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#3] Decrypt Cycles:1 Average:5229.17 Median:5229.17 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 5229.2 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#4] RayTrace Cycles:1 Average:1067.24 Median:1067.24 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 1067.2 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#5] Earley Cycles:1 Average:736.38 Median:736.38 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 736.4 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#6] Boyer Cycles:1 Average:12180.72 Median:12180.72 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 12180.7 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#7] RegExp Cycles:1 Average:41857.14 Median:41857.14 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 41857.1 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#8] Splay Cycles:1 Average:589.28 Median:589.28 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 589.3 01:07:18 INFO - PROCESS | 5355 | 01:07:18 INFO - PROCESS | 5355 | [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 01:07:18 INFO - PROCESS | 5355 | Values: 4850.2 01:07:18 INFO - PROCESS | 5355 | -------- Summary: end -------- 01:07:18 INFO - PROCESS | 5355 | 01:07:19 INFO - TEST-INFO | 5355: exit 0 01:07:19 INFO - TEST-OK | v8_7 | took 40815ms 01:07:19 INFO - SUITE-END | took 1210s 01:07:19 INFO - Completed test suite (00:20:10) 01:07:19 INFO - Working with test: dromaeo_css 01:07:19 INFO - Generating results file: dromaeo_css 01:07:19 INFO - Working with test: kraken 01:07:19 INFO - Generating results file: kraken 01:07:19 INFO - javascript benchmark 01:07:19 INFO - Working with test: v8_7 01:07:19 INFO - Generating results file: v8_7 01:07:19 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:07:19 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 01:07:19 INFO - process_Request line: dromaeo_css 6774.93 graph.html#tests=[[72,63,43]] 01:07:19 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:07:19 INFO - process_Request line: kraken 1512.20 graph.html#tests=[[232,63,43]] 01:07:19 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:07:19 INFO - process_Request line: v8_7 20307.03 graph.html#tests=[[230,63,43]] 01:07:19 INFO - RETURN: dromaeo_css: 6774.93 01:07:19 INFO - RETURN: kraken: 1512.20 01:07:19 INFO - RETURN: v8_7: 20307.03 01:07:19 INFO - javascript benchmark 01:07:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3148.6064241969752, 3148.66938971408, 3173.7302329302, 3193.4730769423045, 3195.057458049875, 5094.821681241157, 5115.744212789359, 5145.588352931766, 5179.4043684976195, 5204.895902081956, 5147.897042059157, 5171.482851714826, 5187.351581052369, 5192.974035129829, 5220.2430647556175, 4668.883277918046, 4707.741074240916, 4709.811607535695, 4779.665423420362, 4836.322914791932, 4494.0, 4792.90414191716, 4806.87982800431, 4860.878478038042, 4867.3915760529935, 4696.29555566666, 4701.882452938669, 4748.264019077049, 4766.809327626891, 4789.904201915977, 4709.7174169549935, 4729.905401891943, 4737.455192652855, 4741.288806678991, 4742.2886567014875, 4603.861884143481, 4711.528847115295, 4733.857984260478, 4735.692180008288, 4769.666123371366, 5031.270465782441, 5035.974820125876, 5041.092603331399, 5042.621803364762, 5077.5684066854365, 5523.143912693539, 5525.778968841241, 5526.032944234783, 5540.50135487804, 5567.025770490189, 5574.470425309589, 5575.665460072409, 5577.05190117681, 5578.967890940148, 5583.218349431083, 5164.8192313268855, 5247.0, 5258.97370513145, 5274.235235890775, 5349.625526213205, 5686.3744988051285, 5692.260006199168, 5722.113072473773, 5733.655980641091, 5736.627119237236, 5491.28613280271, 5494.752736126884, 5498.12428072515, 5501.862453438696, 5514.0, 5454.154606036071, 5476.644018138808, 5557.944420555743, 5586.748596313095, 5648.8870222595315, 5472.98749731301, 5487.670739755627, 5501.312335958005, 5506.146547285177, 5507.339119305709, 5417.620766546304, 5507.421720719347, 5537.556995440355, 5545.972270138624, 5579.302587176603, 4343.9348409773875, 4352.608265256142, 4388.0785035142335, 4394.033312671208, 4428.114377124588, 3899.2396482685604, 3899.337112690849, 3900.3759398496104, 3912.295786758354, 3940.940885886714, 5167.354080739908, 5227.843164705065, 5298.6290959633225, 5300.602454815904, 5364.839054828361, 5580.330360356702, 5606.775728970877, 5627.606067575312, 5645.689487078168, 5668.234788303609, 5214.478552144831, 5234.842954711365, 5259.47405259471, 5261.657992230493, 5281.313429254173, 4733.337332773414, 4752.2158843790385, 4759.309900064541, 4779.426468823764, 4850.442199147144, 5787.305523337142, 5792.102224155263, 5807.303123625192, 5808.186853840466, 5826.096954971986, 5522.30971128609, 5534.280543529316, 5534.833954981276, 5559.861003474945, 5579.414161512983, 5578.246936663499, 5669.234653321749, 5676.4323567642905, 5740.741666625096, 5776.7400466979925, 5435.809746658848, 5442.047641662614, 5448.809291674773, 5449.455054494519, 5453.563714902878, 5649.887002260014, 5669.291338582677, 5690.9756243874845, 5693.686847223443, 5735.655860648374, 5463.31708536433, 5483.287172667528, 5504.146857237135, 5508.091164957737, 5510.752016159202, 5247.89504209906, 5433.8369848904595, 5484.2596249505805, 5491.28613280271, 5539.0, 5318.069337865935, 5331.360236771612, 5334.653247538898, 5336.653117547347, 5380.946190538045, 5371.274877891435, 5455.890882182413, 5458.399576046552, 5458.781648734165, 5464.2076898848645, 5246.76389562463, 5259.658122222197, 5360.758765855468, 5384.730763461889, 5454.3454785426], "unit": "score", "name": "dojo.html", "value": 5141.936467851469}, {"lowerIsBetter": false, "replicates": [530.8168681804777, 531.5216305325207, 554.839096661968, 560.8794109266512, 562.6033646279374, 34328.82835585822, 34890.8255458723, 35186.648133518655, 35225.29549409017, 35322.0, 38276.42585361215, 38307.80846095773, 38584.42123368152, 38618.80690596543, 38636.80681596595, 8280.37897157714, 8461.23848853603, 8512.404131710782, 8521.488710677348, 8564.100769419183, 8129.146439623843, 8298.128696486843, 8376.120507346732, 8582.0, 8586.31309495239, 8460.534670593115, 8594.785130371729, 8605.569721513899, 8618.43980141292, 8639.870401943945, 8371.372147088992, 8532.189442003, 8541.786455338604, 8579.871301930476, 8598.484090954562, 8409.159084091603, 8537.18896704812, 8577.52823594702, 8606.612702428405, 8613.827723445496, 39149.41275880864, 39405.40891886624, 39439.80280098581, 39510.20979580421, 39594.0, 3357.9496307555405, 3467.6532346765375, 3529.5764508258917, 3643.41705327149, 3817.9618203818163, 28275.434491310058, 29080.563791543143, 29272.926342733917, 29279.2680182995, 29320.70679293201, 3217.4530329843983, 3264.9183770405693, 3299.8185099819502, 3391.0335554367134, 3396.2698019925588, 280962.0, 282178.0, 282607.0, 282738.0, 284030.0, 51465.485345146066, 52227.47772522302, 53708.731456342466, 55917.44082559122, 56765.43234567602, 52829.0, 52895.47104528906, 53020.73489632527, 53245.2013219802, 53422.0, 13808.792868106986, 13899.79150312746, 13905.582832515041, 13952.16287022782, 13994.650133746738, 10456.111230545337, 10458.0, 10538.156947444186, 10543.947280263703, 10607.204459665401, 18911.905440472983, 19137.330193443162, 19188.13653385601, 19201.711974320395, 19287.807121928883, 7369.4472914531625, 7487.1764105948305, 7487.363574096154, 7571.924280757232, 7578.886316705254, 978.3005151316843, 991.9454033250056, 1020.6835880876804, 1077.5905156040653, 1112.0991996482874, 27608.447831043268, 28203.858980705376, 28293.292667683472, 28347.71652283492, 29563.0, 1023.4545835787865, 1057.0086654767144, 1075.306427354352, 1096.0464395975553, 1115.9163062770324, 14009.229492378017, 14072.366743496566, 14153.726164645232, 14195.574132776033, 14316.141031538142, 328023.0, 331811.0, 332010.0, 332094.0, 332661.0, 49188.75405622949, 49669.75165124224, 49738.75130624323, 49752.75123624359, 49975.50024499781, 1352.2359866675297, 1364.4746772492663, 1367.453018792471, 1371.2595198592649, 1372.3737371215143, 26459.206223813315, 27481.86259068692, 27619.585706214824, 27660.861695691394, 27819.44361112807, 43389.566104338555, 44585.77707111444, 44637.55362446334, 44696.10607787891, 44875.551244487135, 299612.0, 301417.0, 303989.0, 304082.0, 305079.0, 30953.380932381675, 31144.352730018203, 31664.366712666077, 31674.524882126327, 31808.0, 37523.0, 37699.43450848185, 38049.61950380571, 38215.61784382121, 38680.80659596684, 30386.392272154877, 30646.387072258876, 30662.386752265276, 30799.0, 32629.673703262662, 31522.0, 32130.0, 32298.83850580732, 32393.35213295768, 32521.186970325933, 31540.526892097096, 31843.522347164348, 32000.839995799874, 32093.839530802197, 32126.19684507906, 27970.580441293805, 31193.412757533173, 31241.218969525944, 31364.37271254608, 31567.36975247784, 23877.761222387555, 23904.2673019609, 24199.51600968006, 24202.636960445958, 24322.635160472255], "unit": "score", "name": "ext.html", "value": 17667.913646989753}, {"lowerIsBetter": false, "replicates": [368.54300667172697, 370.9276691045246, 371.87356298858384, 374.4308650850707, 384.65573311885845, 15575.688486230269, 16611.41860034899, 16756.83243167568, 17230.224639891203, 17282.481525554223, 9945.303828731992, 10189.235807314444, 10278.177745780338, 10281.845772313422, 10481.318714283567, 4749.90500189996, 4828.97585512072, 4909.975450122744, 4922.80308787648, 5559.8888022239535, 5401.135818269077, 5534.142207957753, 5538.196961440589, 5543.584231182657, 5548.056830338832, 4838.516148385169, 5054.797808087672, 5056.292119103328, 5279.732864112624, 5510.952918945388, 4963.826266080688, 5008.599312055045, 5010.473900240477, 5091.694498330112, 5810.070388737781, 5155.819546315898, 5197.818076367309, 5220.399654039796, 5221.112410890158, 5243.239730239094, 11680.532778688843, 18853.151608177664, 19471.707924381146, 19839.60320793576, 19846.503837404038, 2115.9365219043298, 2144.774798646151, 2188.9890550547148, 2212.9225477108225, 2274.0562666493383, 8832.8675069874, 9068.0, 9113.58988845497, 9858.753531161707, 13347.86652133476, 1675.2042779679678, 1781.15395187285, 1805.7201133824278, 1814.7912990006055, 1866.0, 53539.19691204635, 53820.73089634566, 54125.0, 55702.442975570535, 55769.44230557683, 9585.424874507551, 10018.949905250573, 10061.295709300424, 10208.234382421348, 10208.948955255177, 12982.285974271317, 13082.672933176556, 13141.868581314257, 13159.67100822468, 13171.0, 6926.688299026556, 7023.8244043898285, 7524.0, 7623.199564045806, 7637.885431718529, 7347.448941329421, 7474.7757567273065, 7560.924390756022, 7580.204078571781, 7604.9239507605325, 3995.800209989489, 4093.5087789465456, 4418.977905110454, 4434.312681534368, 4451.154280686659, 1754.456118603237, 1772.831580999803, 1794.0491539484094, 1828.2138680367304, 1829.6340731853682, 864.9524276164872, 957.5116690487902, 1016.0168577675353, 1040.6461802987021, 1051.6266725312405, 14700.485483008044, 15150.54548363551, 15275.541733748005, 16102.597435063983, 16559.0, 907.4237858959608, 927.5176908007799, 937.704623043739, 965.6384497858127, 988.1600639456404, 9396.342256042011, 9519.809603807886, 9546.0, 9562.330636855493, 9563.234941204686, 547669.0, 553386.0, 565497.0, 568003.0, 587738.0, 42746.572534274885, 43360.783196083816, 68829.65585172042, 69932.0, 73437.26562734411, 1046.4715318763976, 1055.994720026395, 1066.9679909602723, 1068.738159151013, 1073.5544748929262, 14004.509842155476, 14529.927350363181, 14806.925965370105, 14850.554483365517, 15108.244587770349, 29241.85379073091, 29259.41481170407, 30190.69809301879, 30669.086436788468, 31456.370872582876, 38340.23319533649, 39169.824905252884, 39475.60524394834, 39523.80238098791, 39558.604413955494, 6615.0, 6891.689873955584, 7037.1555413350725, 7154.928450715426, 7180.497365184596, 9949.502524873873, 9955.850662239927, 10161.644342447978, 10419.791604167722, 10708.678739637824, 8789.91210087891, 9233.445993240428, 9263.490508022123, 9359.0, 9417.105374989365, 8898.243649289883, 9354.391964522285, 9384.57769400365, 9561.713148605553, 9566.952165239407, 8723.694670686495, 9189.310801690033, 9337.71986840396, 9554.61781528759, 9660.275479338938, 6854.451643868579, 7038.225795162426, 7153.309376151147, 7276.126864776261, 7426.220246874216, 8659.913400866162, 9572.0, 9622.807543848943, 9672.516374181121, 9847.950760246153], "unit": "score", "name": "jquery.html", "value": 7863.500330005072}, {"lowerIsBetter": false, "replicates": [1472.742270102732, 1486.1083349990004, 1647.3657641807908, 1749.9026722700812, 1787.1868299923528, 7483.10202775667, 7487.138979017414, 7556.924430755691, 7674.616269186539, 7685.423593230509, 7407.296306850854, 7443.59060251686, 7505.28699773522, 7512.924870751291, 7515.023047003896, 2315.9305220843357, 2338.497223097034, 2348.8355815092864, 2349.34218418843, 2352.541254455382, 2322.5238826040622, 2335.7781010803947, 2379.3218932604213, 2389.7849193572574, 2389.9641505377435, 2031.238285642884, 2061.9587608247825, 2332.836701430892, 2340.1224540797202, 2356.7289761677457, 2299.839011269212, 2315.2012555668266, 2324.732655744586, 2330.498942727305, 2339.7192336919593, 2247.213475283654, 2290.7594702556157, 2321.257197696738, 2326.953460930772, 2338.9240949163404, 7546.962265188639, 7610.0, 7630.122535908386, 7631.198724133941, 7633.9618301908695, 4740.0, 4786.928196077061, 4826.083044221622, 4847.2729090636685, 4849.442314133885, 3165.5884734984306, 3176.3806057818733, 3259.1852036990754, 3289.7697161198485, 3309.1396236978317, 1039.3348257115376, 1046.1108058150512, 1056.10758908722, 1084.4957094950807, 1092.7759809239087, 10385.06534411899, 10417.687469375931, 10421.426821524814, 10446.320989135682, 10463.110635595984, 4316.223079845658, 4367.148406060787, 4428.645708343325, 4433.135538570013, 4448.021435284232, 8505.192006759347, 8561.272291855137, 8561.272291855263, 8563.914360856312, 8569.400141990001, 4870.732109733928, 4879.072976134523, 4891.413030436371, 4892.217245240744, 4897.730624815598, 4478.664100192498, 4482.327650852399, 4488.326750987378, 4498.527654596286, 4499.392582001388, 3405.5851196597528, 3432.9313413731584, 3451.516787649731, 3451.8274086295582, 3481.860725570949, 1061.7345663584103, 1072.9785404292027, 1079.8488211650376, 1079.9676009719722, 1082.242430298794, 964.4791812421214, 1007.8992100789862, 1060.6393826099008, 1072.0565902006185, 1089.8855620159768, 6933.653317334113, 7046.718131274692, 7107.82230444243, 7122.109736282965, 7144.142702875688, 572.6077636818792, 578.0808514462026, 579.7333226715758, 635.2028204603193, 780.3484090784259, 5519.641223320471, 5555.000099982042, 5576.302962129734, 5645.576581756384, 5659.349174844863, 8991.145841145211, 9298.442093474277, 9363.063693630718, 9379.953100234592, 9420.199283061016, 9093.454392736458, 9124.635014599475, 9186.494742789077, 9261.07389261082, 9285.907140928504, 7199.568025918462, 7319.194888562254, 7322.1945585985495, 7332.706691732377, 7356.558606483628, 6892.448604111559, 6957.8956315654295, 6966.338197871194, 7013.193482749549, 7014.508984371045, 8133.308668762985, 8251.546164960984, 8342.749717508485, 8346.874796878174, 8514.55252607046, 8516.020657624453, 8604.0, 8629.654813807629, 8644.610992505226, 8720.694775682821, 6570.310117437601, 6570.868582628416, 6622.90065649006, 6631.3700198481065, 6650.866982660416, 7113.288671133052, 7257.564546127249, 7292.708291668274, 7293.088363954505, 7346.816329591803, 6993.0, 7093.14882214117, 7114.964425177841, 7194.172670143001, 7245.492815502864, 6176.474999625082, 6190.7523699051535, 6729.360710732669, 6784.118064651564, 6909.689063992233, 6755.594664320157, 6760.256371799, 6844.315568443116, 6846.212685541226, 6865.450763938973, 6532.83667908287, 6559.7376104957175, 6568.343165683394, 6572.244191917991, 6572.934270657423, 6520.836979075371, 6566.9343306566325, 6570.277269500447, 6590.3739144783085, 6591.703373348115], "unit": "score", "name": "mootools.html", "value": 4386.0168252167605}, {"lowerIsBetter": false, "replicates": [325.7003556727811, 327.4809427058113, 327.85082787331766, 331.6186385656496, 333.90817525180574, 6230.190075290212, 6279.4254355714165, 6374.266959299682, 6411.711472983715, 6448.742050317982, 6194.0, 6204.13142160098, 6209.068639704046, 6218.25380954285, 6244.937550624492, 4784.40194975628, 4793.5925446337105, 4796.784144713479, 4799.448063472693, 4806.687565308262, 4769.809207631691, 4783.473817880031, 4786.521347865203, 4788.0, 4788.88027799306, 4647.930281045787, 4737.407824021997, 4763.595094416979, 4768.570828625423, 4779.426468823746, 4737.407824021997, 4739.881502962437, 4743.312219728155, 4763.618910487153, 4765.451973023112, 4109.732867363612, 4120.763770868757, 4130.112025914443, 4134.090500089976, 4140.192662430827, 5971.910421343683, 5989.640621562677, 5998.310194327664, 6010.939890601126, 6013.579049466539, 5428.565714742811, 5499.4225606311165, 5525.336959564837, 5535.086710692771, 5553.0, 6650.467962562983, 6669.699863506154, 6676.399124078807, 6678.131842860397, 6694.430973367221, 4559.316102584638, 4575.107853968478, 4584.564466375689, 4587.3807036050375, 4604.631629469634, 6832.350926661959, 6925.688344024581, 6939.722411103549, 6996.930030699678, 7008.579485230903, 4554.544545545419, 4569.702969306984, 4583.931241031387, 4584.816607335669, 4586.082783443325, 4647.907041859212, 4652.837150699709, 4657.743824089707, 4674.555917187861, 4679.555442232982, 5416.864578385572, 5421.593380496479, 5426.457354264542, 5433.022056029874, 5440.700761458079, 6582.111414959015, 6665.333466653393, 6703.162104736908, 6713.295104014106, 6719.328067193242, 1599.3282821215118, 1619.8218195998431, 1628.894121882074, 1641.5731909703325, 1642.868570514356, 1065.1851333729703, 1073.935563866155, 1100.1078145589572, 1113.3542545323692, 1117.2626066795876, 6171.352008039181, 6173.69131543421, 6195.907061394083, 6207.81376558704, 6296.527760417987, 6672.9332706673285, 6678.065070890079, 6709.261981182066, 6717.932820671828, 6718.8320291993095, 6153.384661533811, 6257.5932564383165, 6294.276158241769, 6311.715972781236, 6318.462930650946, 3672.081979505124, 3676.356637588411, 3678.5401824771902, 3686.189038411545, 3689.1146124929824, 7033.9296607033275, 7046.6476676165985, 7060.858782824314, 7064.364207221272, 7076.823079423056, 6789.558678685869, 6920.411765000032, 6949.270326615733, 6963.7562685305775, 7012.7545535906, 7058.541194822423, 7073.929260707327, 7079.787606371817, 7082.610456424946, 7087.645617719197, 6708.362705542965, 6746.4265537429865, 6794.72821087151, 6804.727810887509, 6823.351781580742, 6602.702878370389, 6657.800265992028, 6685.799426017227, 6713.56361836479, 6723.831904202239, 7058.294170582901, 7079.893801592877, 7083.787486375417, 7102.644867756489, 7111.608861512666, 4565.566271204363, 4579.885502862455, 4586.931196031996, 4594.88512787183, 4598.908021839477, 6762.087118238975, 6793.592384456949, 6801.149856267966, 6818.93181068183, 6997.545159564612, 6699.162604674416, 6722.058911752456, 6727.192736871607, 6744.22441419243, 6785.880329745537, 6715.630640314635, 6730.461563074844, 6734.629595372302, 6742.325767423415, 6769.593824370553, 6403.711832967434, 6497.18785151856, 6558.081868538504, 6567.343265673395, 6641.900371494335, 6770.729170833309, 6801.2178599461695, 6810.82972925661, 6811.2167100784045, 6813.557118787263, 6629.370209830058, 6676.799696009128, 6716.697748601422, 6730.562513436611, 6734.595924244561], "unit": "score", "name": "prototype.html", "value": 4974.086874226281}, {"lowerIsBetter": false, "replicates": [773.8065483629093, 781.4842204145261, 833.275050705886, 841.6128580852808, 859.0850743957685, 13013.67465813354, 13112.541061062864, 13120.409581568829, 13150.276734779583, 13183.6704082398, 12531.436085376157, 12643.178193417421, 12652.93673531631, 12674.936625316883, 12695.238285702864, 795.6101510259974, 810.3395732478011, 825.2613910550054, 836.3016880904452, 836.4270474724833, 815.8449894520052, 823.3084209264217, 827.2141465607667, 827.7847759582492, 830.6760363458257, 854.598338780772, 860.8192279621287, 866.0127454701645, 866.0386970462813, 868.1578868497547, 843.5824266987819, 849.723839752083, 852.7441767469796, 854.6410507586828, 854.8375808596347, 635.3582881289931, 635.94594459471, 636.4431122767578, 637.3148864970176, 640.5612155673332, 13530.188188708611, 13544.661383465394, 13571.253581053039, 13593.660158496017, 13622.0, 12892.80660790089, 13053.804192937114, 13173.736525269536, 13214.783821478439, 13330.466781328736, 9789.314747967645, 9892.604295828158, 9986.251031172618, 10319.226058045677, 10441.738956526071, 3456.5160877477174, 3626.510421093145, 3633.745637805355, 3634.527511423498, 3679.208970071421, 49891.25163122556, 50124.997500049794, 50254.0, 50585.74707126478, 50691.74654126743, 9617.951910240545, 9771.51142442875, 10231.13035391985, 10296.330738501973, 10347.379157250438, 17752.11239438023, 18415.0, 18433.90783046076, 18435.53911152232, 18492.167852446673, 7734.7679569613, 7875.488093273919, 7883.645235964395, 7903.762887113396, 8046.798830029296, 4476.782275515617, 4478.955210447919, 4505.549445055468, 4514.836883955497, 4649.628029757611, 3117.4076925384106, 3135.372925414926, 3140.0422871024102, 3147.512135618983, 3154.2902846859274, 564.6132399306488, 571.7141429285357, 573.7102763104689, 580.89834279001, 582.4466756581264, 524.8530411484791, 546.956243500519, 611.2817439508559, 647.4011539326104, 661.4642139866723, 8285.792855178548, 8632.266257368194, 8725.258353039935, 8807.295416366675, 8835.690750823818, 501.8745313671582, 560.4731552340787, 587.4448646029547, 597.8595030167907, 694.3646563394539, 3815.523059617548, 3983.2232714620372, 3992.720509564361, 4088.448059511928, 4103.610157035077, 51988.48011519836, 58874.0, 58887.41112588905, 59117.704411478524, 59224.0, 50596.49403505991, 50676.239856401444, 50682.493175068514, 52489.0, 52502.47497525052, 43223.78388108102, 43365.783171083945, 45095.0, 45571.08857822825, 46899.0, 7898.802529936797, 7957.482763620346, 8075.757727268191, 8120.431569790058, 8197.79505512367, 33641.3271734559, 33797.0, 33965.0, 34835.47746783851, 35619.82190089033, 41329.58670413258, 41961.58038419577, 45988.54011459977, 46043.76978115088, 46369.0, 9803.705888823346, 9858.0, 9949.552270147717, 9975.501224938578, 10222.335548189343, 11593.478293476644, 11678.591249306233, 11720.824187637021, 11759.764804703687, 11768.411579421167, 9392.483413412328, 9672.95163524178, 9826.0, 9853.211743060394, 9870.161036311707, 10897.59471968129, 11287.604933827277, 11328.376939268419, 11344.092472602008, 11806.114541409257, 9939.552720127756, 10353.792924141626, 10594.682159535227, 10601.840972385575, 11035.172362073037, 8736.213740763362, 8753.91246087531, 8992.59533320989, 9066.365354425126, 9161.633534658538, 8245.175482451707, 8402.495850249004, 8629.0, 8698.869516957377, 8851.159139881958], "unit": "score", "name": "yui.html", "value": 6204.105640268191}], "name": "dromaeo_css", "value": 6774.924347601309}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 107.0, 112.0, 110.0, 113.0, 122.0, 112.0, 113.0, 113.0, 112.0], "unit": "score", "name": "ai-astar", "value": 112.6}, {"lowerIsBetter": true, "replicates": [154.0, 127.0, 150.0, 173.0, 129.0, 156.0, 158.0, 129.0, 159.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 146.2}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 161.0, 162.0, 162.0, 160.0, 160.0, 163.0, 165.0, 160.0], "unit": "score", "name": "audio-dft", "value": 161.8}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 74.0, 97.0, 144.0, 75.0, 73.0, 74.0, 73.0], "unit": "score", "name": "audio-fft", "value": 82.7}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 144.0, 95.0, 96.0, 96.0, 143.0, 95.0, 96.0, 148.0], "unit": "score", "name": "audio-oscillator", "value": 110.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 146.0, 146.0, 148.0, 147.0, 145.0, 151.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 98.0, 96.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.6}, {"lowerIsBetter": true, "replicates": [58.0, 59.0, 60.0, 59.0, 61.0, 62.0, 59.0, 58.0, 63.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 69.0, 67.0, 68.0, 68.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 91.0, 89.0, 83.0, 83.0, 83.0, 82.0, 89.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.7}, {"lowerIsBetter": true, "replicates": [113.0, 121.0, 130.0, 122.0, 119.0, 123.0, 119.0, 119.0, 118.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.3}, {"lowerIsBetter": true, "replicates": [132.0, 148.0, 142.0, 144.0, 147.0, 130.0, 154.0, 158.0, 141.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.8}, {"lowerIsBetter": true, "replicates": [68.0, 71.0, 67.0, 68.0, 73.0, 67.0, 68.0, 69.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1512.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.26518593303047], "unit": "score", "name": "Richards", "value": 209.79978600000007}, {"lowerIsBetter": false, "replicates": [188.39487565938205], "unit": "score", "name": "DeltaBlue", "value": 350.95434399999994}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5229.166666666667], "unit": "score", "name": "Decrypt", "value": 50.90313944223106}, {"lowerIsBetter": false, "replicates": [1067.2358591248667], "unit": "score", "name": "RayTrace", "value": 693.3696929999998}, {"lowerIsBetter": false, "replicates": [736.3770250368188], "unit": "score", "name": "Earley", "value": 905.0567539999997}, {"lowerIsBetter": false, "replicates": [12180.722891566265], "unit": "score", "name": "Boyer", "value": 54.714568743818035}, {"lowerIsBetter": false, "replicates": [41857.142857142855], "unit": "score", "name": "RegExp", "value": 21.764146757679182}, {"lowerIsBetter": false, "replicates": [589.2751915144372], "unit": "score", "name": "Splay", "value": 138.29022699999996}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 20307.029290295464}]} 01:07:19 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "20307.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1512.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6774.93"}}} 01:07:19 INFO - Return code: 0 01:07:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 01:07:19 INFO - # TBPL SUCCESS # 01:07:19 INFO - Running post-action listener: _resource_record_post_action 01:07:19 INFO - Running post-run listener: _resource_record_post_run 01:07:20 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 24576; Write bytes: 317661184; Read time: 204; Write time: 183104 01:07:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 5156864; Read time: 0; Write time: 256 01:07:20 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 24576; Write bytes: 312504320; Read time: 204; Write time: 182848 01:07:20 INFO - Running post-run listener: _upload_blobber_files 01:07:20 INFO - Blob upload gear active. 01:07:20 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 01:07:20 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 01:07:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 01:07:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 01:07:21 INFO - (blobuploader) - INFO - Open directory for files ... 01:07:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:07:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:07:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:07:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:07:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:07:22 INFO - (blobuploader) - INFO - Done attempting. 01:07:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:07:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:07:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:07:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:07:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:07:22 INFO - (blobuploader) - INFO - Done attempting. 01:07:22 INFO - (blobuploader) - INFO - Iteration through files over. 01:07:22 INFO - Return code: 0 01:07:22 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 01:07:22 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 01:07:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1da1b9b69b4df4984acc3c87364fab5ed169d2ec1cc07e72416d5bb7bb6f03ce64a12d82713b56ed4696631ad567f9e50367a7fe815cd17d85285a67dc1a6722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ae7a633be68cad84e06743eadc6c449eb0d660e69ee8ae215894191a3877124503dfdaf397eda3b00afe5323f89be9a2b1a0536e0a9b5435af7006e15badaca1"} 01:07:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 01:07:22 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 01:07:22 INFO - Contents: 01:07:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1da1b9b69b4df4984acc3c87364fab5ed169d2ec1cc07e72416d5bb7bb6f03ce64a12d82713b56ed4696631ad567f9e50367a7fe815cd17d85285a67dc1a6722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ae7a633be68cad84e06743eadc6c449eb0d660e69ee8ae215894191a3877124503dfdaf397eda3b00afe5323f89be9a2b1a0536e0a9b5435af7006e15badaca1"} 01:07:22 INFO - Running post-run listener: copy_logs_to_upload_dir 01:07:22 INFO - Copying logs to upload dir... 01:07:22 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 01:07:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.096432 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-25 01:07:22.871141) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-25 01:07:22.874658) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1da1b9b69b4df4984acc3c87364fab5ed169d2ec1cc07e72416d5bb7bb6f03ce64a12d82713b56ed4696631ad567f9e50367a7fe815cd17d85285a67dc1a6722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ae7a633be68cad84e06743eadc6c449eb0d660e69ee8ae215894191a3877124503dfdaf397eda3b00afe5323f89be9a2b1a0536e0a9b5435af7006e15badaca1"} build_url:https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/XBM6ihp1R3-m4vQNnAgyew/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1da1b9b69b4df4984acc3c87364fab5ed169d2ec1cc07e72416d5bb7bb6f03ce64a12d82713b56ed4696631ad567f9e50367a7fe815cd17d85285a67dc1a6722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ae7a633be68cad84e06743eadc6c449eb0d660e69ee8ae215894191a3877124503dfdaf397eda3b00afe5323f89be9a2b1a0536e0a9b5435af7006e15badaca1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-25 01:07:22.899265) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 01:07:22.899590) ========= 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=6dbc10da6d83fee962c624be000001a8-1453711440.425396-218092919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-25 01:07:22.985302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-25 01:07:22.985620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-25 01:07:22.985977) ========= ========= Total master_lag: 0.23 =========