builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1467768568.34 results: success (0) buildid: 20160705171131 builduid: e1d2d9d5871b41a19cf332977e01f077 revision: 70e05c6832e831374604ac3ce7433971368dffe0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.345168) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.345581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.345856) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.378302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.378567) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-05 18:29:28-- 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% 31.1M=0s 2016-07-05 18:29:28 (31.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.532810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.533121) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.072098 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.617160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:28.617492) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70e05c6832e831374604ac3ce7433971368dffe0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 70e05c6832e831374604ac3ce7433971368dffe0 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-05 18:29:28,885 truncating revision to first 12 chars 2016-07-05 18:29:28,886 Setting DEBUG logging. 2016-07-05 18:29:28,886 attempt 1/10 2016-07-05 18:29:28,886 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70e05c6832e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 18:29:29,244 unpacking tar archive at: mozilla-central-70e05c6832e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683305 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:29.314865) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:29.315227) ========= script_repo_revision: 70e05c6832e831374604ac3ce7433971368dffe0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:29.315680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:29.315957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 18:29:29.331474) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-07-05 18:29:29.331754) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:29 INFO - MultiFileLogger online at 20160705 18:29:29 in /builds/slave/test 18:29:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:29:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:29 INFO - {'append_to_log': False, 18:29:29 INFO - 'base_work_dir': '/builds/slave/test', 18:29:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:29:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:29 INFO - 'branch': 'Firefox-Non-PGO', 18:29:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:29 INFO - 'config_files': ('talos/linux_config.py',), 18:29:29 INFO - 'default_actions': ('clobber', 18:29:29 INFO - 'read-buildbot-config', 18:29:29 INFO - 'download-and-extract', 18:29:29 INFO - 'populate-webroot', 18:29:29 INFO - 'create-virtualenv', 18:29:29 INFO - 'install', 18:29:29 INFO - 'run-tests'), 18:29:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:29 INFO - 'download_minidump_stackwalk': True, 18:29:29 INFO - 'download_symbols': 'ondemand', 18:29:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:29 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:29 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:29 INFO - 'installer_path': 'installer.exe', 18:29:29 INFO - 'log_level': 'info', 18:29:29 INFO - 'log_name': 'talos', 18:29:29 INFO - 'log_to_console': True, 18:29:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:29 INFO - 'opt_config_files': (), 18:29:29 INFO - 'pip_index': False, 18:29:29 INFO - 'sps_profile': False, 18:29:29 INFO - 'sps_profile_interval': 0, 18:29:29 INFO - 'suite': 'dromaeojs', 18:29:29 INFO - 'system_bits': '32', 18:29:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:29 INFO - 'title': 'talos-linux64-ix-012', 18:29:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:29 INFO - 'use_talos_json': True, 18:29:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:29 INFO - 'work_dir': 'build'} 18:29:29 INFO - ##### 18:29:29 INFO - ##### Running clobber step. 18:29:29 INFO - ##### 18:29:29 INFO - Running pre-action listener: _resource_record_pre_action 18:29:29 INFO - Running main action method: clobber 18:29:29 INFO - rmtree: /builds/slave/test/build 18:29:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:31 INFO - Running post-action listener: _resource_record_post_action 18:29:31 INFO - ##### 18:29:31 INFO - ##### Finished clobber step (success) 18:29:31 INFO - ##### 18:29:31 INFO - ##### 18:29:31 INFO - ##### Running read-buildbot-config step. 18:29:31 INFO - ##### 18:29:31 INFO - Running pre-action listener: _resource_record_pre_action 18:29:31 INFO - Running main action method: read_buildbot_config 18:29:31 INFO - Using buildbot properties: 18:29:31 INFO - { 18:29:31 INFO - "project": "", 18:29:31 INFO - "product": "firefox", 18:29:31 INFO - "script_repo_revision": "production", 18:29:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:29:31 INFO - "repository": "", 18:29:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:29:31 INFO - "buildid": "20160705171131", 18:29:31 INFO - "pgo_build": "False", 18:29:31 INFO - "basedir": "/builds/slave/test", 18:29:31 INFO - "buildnumber": 635, 18:29:31 INFO - "slavename": "talos-linux64-ix-012", 18:29:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:29:31 INFO - "platform": "ubuntu64_hw", 18:29:31 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:29:31 INFO - "branch": "mozilla-central", 18:29:31 INFO - "repo_path": "mozilla-central", 18:29:31 INFO - "revision": "70e05c6832e831374604ac3ce7433971368dffe0", 18:29:31 INFO - "stage_platform": "linux64", 18:29:31 INFO - "builduid": "e1d2d9d5871b41a19cf332977e01f077", 18:29:31 INFO - "slavebuilddir": "test" 18:29:31 INFO - } 18:29:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:29:31 INFO - Running post-action listener: _resource_record_post_action 18:29:31 INFO - ##### 18:29:31 INFO - ##### Finished read-buildbot-config step (success) 18:29:31 INFO - ##### 18:29:31 INFO - ##### 18:29:31 INFO - ##### Running download-and-extract step. 18:29:31 INFO - ##### 18:29:31 INFO - Running pre-action listener: _resource_record_pre_action 18:29:31 INFO - Running main action method: download_and_extract 18:29:31 INFO - mkdir: /builds/slave/test/build/tests 18:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:31 INFO - https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:29:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:29:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:29:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:29:38 INFO - Downloaded 1361 bytes. 18:29:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:29:38 INFO - Using the following test package requirements: 18:29:38 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:38 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:29:38 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:29:38 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'jsshell-linux-x86_64.zip'], 18:29:38 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:29:38 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:38 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:29:38 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:29:38 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:29:38 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:38 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:29:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:29: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')]} 18:29:38 INFO - https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:45 INFO - Downloaded 22731017 bytes. 18:29:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:29:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:29:47 INFO - Return code: 0 18:29:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:29: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')]} 18:29:47 INFO - https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:51 INFO - Downloaded 22731017 bytes. 18:29:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:29:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:29:52 INFO - Return code: 0 18:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:52 INFO - https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:29:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:29:53 INFO - Downloaded 14403333 bytes. 18:29:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:29:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:29:53 INFO - Return code: 0 18:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:53 INFO - https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:29:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:29:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:29:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:29:54 INFO - Downloaded 56504138 bytes. 18:29:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:29:54 INFO - mkdir: /builds/slave/test/properties 18:29:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:54 INFO - Contents: 18:29:54 INFO - build_url:https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:29:55 INFO - Running post-action listener: _resource_record_post_action 18:29:55 INFO - Running post-action listener: set_extra_try_arguments 18:29:55 INFO - ##### 18:29:55 INFO - ##### Finished download-and-extract step (success) 18:29:55 INFO - ##### 18:29:55 INFO - ##### 18:29:55 INFO - ##### Running populate-webroot step. 18:29:55 INFO - ##### 18:29:55 INFO - Running pre-action listener: _resource_record_pre_action 18:29:55 INFO - Running main action method: populate_webroot 18:29:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:55 INFO - '--tppagecycles', 18:29:55 INFO - '7'], 18:29:55 INFO - 'tests': ['tsvgm']}}, 18:29:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:29:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['damp', 'tps']}, 18:29:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'tests': ['damp', 'tps']}, 18:29:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['dromaeo_dom']}, 18:29:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['basic_compositor_video']}, 18:29:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:29:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['a11yr', 18:29:55 INFO - 'ts_paint', 18:29:55 INFO - 'tpaint', 18:29:55 INFO - 'sessionrestore', 18:29:55 INFO - 'sessionrestore_no_auto_restore', 18:29:55 INFO - 'tabpaint']}, 18:29:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['tsvgx', 18:29:55 INFO - 'tsvgr_opacity', 18:29:55 INFO - 'tart', 18:29:55 INFO - 'tscrollx', 18:29:55 INFO - 'cart']}, 18:29:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:55 INFO - 'tsvgr_opacity', 18:29:55 INFO - 'tart', 18:29:55 INFO - 'tscrollx', 18:29:55 INFO - 'cart']}, 18:29:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'talos_options': ['--disable-e10s'], 18:29:55 INFO - 'tests': ['tp5o']}, 18:29:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'tests': ['tp5o']}, 18:29:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'talos_options': ['--disable-e10s', 18:29:55 INFO - '--xperf_path', 18:29:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:55 INFO - 'tests': ['tp5n']}, 18:29:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:55 INFO - 'talos_options': ['--xperf_path', 18:29:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:55 INFO - 'tests': ['tp5n']}}, 18:29:55 INFO - 'talos.zip': {'path': '', 18:29:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:55 INFO - Running post-action listener: _resource_record_post_action 18:29:55 INFO - ##### 18:29:55 INFO - ##### Finished populate-webroot step (success) 18:29:55 INFO - ##### 18:29:55 INFO - ##### 18:29:55 INFO - ##### Running create-virtualenv step. 18:29:55 INFO - ##### 18:29:55 INFO - Running pre-action listener: _resource_record_pre_action 18:29:55 INFO - Running main action method: create_virtualenv 18:29:55 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:29:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:55 INFO - Using real prefix '/usr' 18:29:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:56 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:58 INFO - Installing pip.................done. 18:29:58 INFO - Return code: 0 18:29:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:58 INFO - Running command: ['/builds/slave/test/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/build 18:29:58 INFO - Copy/paste: /builds/slave/test/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 18:29:58 INFO - Using env: {'DISPLAY': ':0', 18:29:58 INFO - 'HOME': '/home/cltbld', 18:29:58 INFO - 'LANG': 'en_US.UTF-8', 18:29:58 INFO - 'LANGUAGE': 'en_US:en', 18:29:58 INFO - 'LOGNAME': 'cltbld', 18:29:58 INFO - 'MAIL': '/var/mail/cltbld', 18:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:58 INFO - 'MOZ_NO_REMOTE': '1', 18:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:58 INFO - 'NO_EM_RESTART': '1', 18:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:58 INFO - 'PWD': '/builds/slave/test', 18:29:58 INFO - 'SHELL': '/bin/bash', 18:29:58 INFO - 'SHLVL': '1', 18:29:58 INFO - 'TERM': 'linux', 18:29:58 INFO - 'TMOUT': '86400', 18:29:58 INFO - 'USER': 'cltbld', 18:29:58 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:58 INFO - '_': '/tools/buildbot/bin/python'} 18:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:29:59 INFO - Cleaning up... 18:29:59 INFO - Return code: 0 18:29:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:29:59 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 18:29:59 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 18:29:59 INFO - Using env: {'DISPLAY': ':0', 18:29:59 INFO - 'HOME': '/home/cltbld', 18:29:59 INFO - 'LANG': 'en_US.UTF-8', 18:29:59 INFO - 'LANGUAGE': 'en_US:en', 18:29:59 INFO - 'LOGNAME': 'cltbld', 18:29:59 INFO - 'MAIL': '/var/mail/cltbld', 18:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:59 INFO - 'MOZ_NO_REMOTE': '1', 18:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:59 INFO - 'NO_EM_RESTART': '1', 18:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:59 INFO - 'PWD': '/builds/slave/test', 18:29:59 INFO - 'SHELL': '/bin/bash', 18:29:59 INFO - 'SHLVL': '1', 18:29:59 INFO - 'TERM': 'linux', 18:29:59 INFO - 'TMOUT': '86400', 18:29:59 INFO - 'USER': 'cltbld', 18:29:59 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:59 INFO - '_': '/tools/buildbot/bin/python'} 18:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:59 INFO - Downloading/unpacking psutil>=3.1.1 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:01 INFO - Installing collected packages: psutil 18:30:01 INFO - Running setup.py install for psutil 18:30:01 INFO - building 'psutil._psutil_linux' extension 18:30:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:30:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:30:02 INFO - building 'psutil._psutil_posix' extension 18:30:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:30:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:30:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:02 INFO - Successfully installed psutil 18:30:02 INFO - Cleaning up... 18:30:02 INFO - Return code: 0 18:30:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:02 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 18:30:02 INFO - Copy/paste: /builds/slave/test/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.3 18:30:02 INFO - Using env: {'DISPLAY': ':0', 18:30:02 INFO - 'HOME': '/home/cltbld', 18:30:02 INFO - 'LANG': 'en_US.UTF-8', 18:30:02 INFO - 'LANGUAGE': 'en_US:en', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MAIL': '/var/mail/cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SHLVL': '1', 18:30:02 INFO - 'TERM': 'linux', 18:30:02 INFO - 'TMOUT': '86400', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - '_': '/tools/buildbot/bin/python'} 18:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:05 INFO - Installing collected packages: mozsystemmonitor 18:30:05 INFO - Running setup.py install for mozsystemmonitor 18:30:05 INFO - Successfully installed mozsystemmonitor 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:05 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 18:30:05 INFO - Copy/paste: /builds/slave/test/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==2.5.1 18:30:05 INFO - Using env: {'DISPLAY': ':0', 18:30:05 INFO - 'HOME': '/home/cltbld', 18:30:05 INFO - 'LANG': 'en_US.UTF-8', 18:30:05 INFO - 'LANGUAGE': 'en_US:en', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MAIL': '/var/mail/cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SHLVL': '1', 18:30:05 INFO - 'TERM': 'linux', 18:30:05 INFO - 'TMOUT': '86400', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - '_': '/tools/buildbot/bin/python'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:07 INFO - warning: no files found matching '*.txt' 18:30:07 INFO - no previously-included directories found matching 'build' 18:30:07 INFO - no previously-included directories found matching 'dist' 18:30:07 INFO - no previously-included directories found matching '.git*' 18:30:07 INFO - Installing collected packages: jsonschema, functools32 18:30:07 INFO - Running setup.py install for functools32 18:30:07 INFO - warning: no files found matching '*.txt' 18:30:07 INFO - no previously-included directories found matching 'build' 18:30:07 INFO - no previously-included directories found matching 'dist' 18:30:07 INFO - no previously-included directories found matching '.git*' 18:30:07 INFO - Successfully installed jsonschema functools32 18:30:07 INFO - Cleaning up... 18:30:07 INFO - Return code: 0 18:30:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:07 INFO - Running command: ['/builds/slave/test/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/build 18:30:07 INFO - Copy/paste: /builds/slave/test/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 18:30:07 INFO - Using env: {'DISPLAY': ':0', 18:30:07 INFO - 'HOME': '/home/cltbld', 18:30:07 INFO - 'LANG': 'en_US.UTF-8', 18:30:07 INFO - 'LANGUAGE': 'en_US:en', 18:30:07 INFO - 'LOGNAME': 'cltbld', 18:30:07 INFO - 'MAIL': '/var/mail/cltbld', 18:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:07 INFO - 'MOZ_NO_REMOTE': '1', 18:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:07 INFO - 'NO_EM_RESTART': '1', 18:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - Downloading docopt-0.6.1.tar.gz 18:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:10 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:10 INFO - Running setup.py install for blobuploader 18:30:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:10 INFO - Running setup.py install for requests 18:30:11 INFO - Running setup.py install for docopt 18:30:11 INFO - Successfully installed blobuploader requests docopt 18:30:11 INFO - Cleaning up... 18:30:11 INFO - Return code: 0 18:30:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 18:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:11 INFO - Using env: {'DISPLAY': ':0', 18:30:11 INFO - 'HOME': '/home/cltbld', 18:30:11 INFO - 'LANG': 'en_US.UTF-8', 18:30:11 INFO - 'LANGUAGE': 'en_US:en', 18:30:11 INFO - 'LOGNAME': 'cltbld', 18:30:11 INFO - 'MAIL': '/var/mail/cltbld', 18:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:11 INFO - 'MOZ_NO_REMOTE': '1', 18:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:11 INFO - 'NO_EM_RESTART': '1', 18:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:11 INFO - 'PWD': '/builds/slave/test', 18:30:11 INFO - 'SHELL': '/bin/bash', 18:30:11 INFO - 'SHLVL': '1', 18:30:11 INFO - 'TERM': 'linux', 18:30:11 INFO - 'TMOUT': '86400', 18:30:11 INFO - 'USER': 'cltbld', 18:30:11 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:11 INFO - '_': '/tools/buildbot/bin/python'} 18:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:11 INFO - Running setup.py (path:/tmp/pip-I4YYLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:11 INFO - Running setup.py (path:/tmp/pip-9uEzrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:11 INFO - Running setup.py (path:/tmp/pip-Xm0hFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:11 INFO - Running setup.py (path:/tmp/pip-TvBt46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:11 INFO - Running setup.py (path:/tmp/pip-ViUsWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:12 INFO - Running setup.py (path:/tmp/pip-rqhEeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:12 INFO - Running setup.py (path:/tmp/pip-TNF_bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:12 INFO - Running setup.py (path:/tmp/pip-kuVpqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:12 INFO - Running setup.py (path:/tmp/pip-d8d9Rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:12 INFO - Running setup.py (path:/tmp/pip-GJorEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:12 INFO - Running setup.py (path:/tmp/pip-vgNYQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:12 INFO - Running setup.py (path:/tmp/pip-bLQwYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:12 INFO - Running setup.py (path:/tmp/pip-IyM3lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:12 INFO - Running setup.py (path:/tmp/pip-Nu77er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:12 INFO - Running setup.py (path:/tmp/pip-USnZd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:12 INFO - Running setup.py (path:/tmp/pip-mmXTuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:12 INFO - Running setup.py (path:/tmp/pip-NO9bdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:12 INFO - Running setup.py install for manifestparser 18:30:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozcrash 18:30:13 INFO - Running setup.py install for mozdebug 18:30:13 INFO - Running setup.py install for mozdevice 18:30:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozfile 18:30:13 INFO - Running setup.py install for mozhttpd 18:30:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozinfo 18:30:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozInstall 18:30:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozleak 18:30:13 INFO - Running setup.py install for mozlog 18:30:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for moznetwork 18:30:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Running setup.py install for mozprocess 18:30:14 INFO - Running setup.py install for mozprofile 18:30:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Running setup.py install for mozrunner 18:30:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Running setup.py install for mozscreenshot 18:30:14 INFO - Running setup.py install for moztest 18:30:14 INFO - Running setup.py install for mozversion 18:30:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:14 INFO - Cleaning up... 18:30:14 INFO - Return code: 0 18:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 18:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:14 INFO - Using env: {'DISPLAY': ':0', 18:30:14 INFO - 'HOME': '/home/cltbld', 18:30:14 INFO - 'LANG': 'en_US.UTF-8', 18:30:14 INFO - 'LANGUAGE': 'en_US:en', 18:30:14 INFO - 'LOGNAME': 'cltbld', 18:30:14 INFO - 'MAIL': '/var/mail/cltbld', 18:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:14 INFO - 'MOZ_NO_REMOTE': '1', 18:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:14 INFO - 'NO_EM_RESTART': '1', 18:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:14 INFO - 'PWD': '/builds/slave/test', 18:30:14 INFO - 'SHELL': '/bin/bash', 18:30:14 INFO - 'SHLVL': '1', 18:30:14 INFO - 'TERM': 'linux', 18:30:14 INFO - 'TMOUT': '86400', 18:30:14 INFO - 'USER': 'cltbld', 18:30:14 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:14 INFO - '_': '/tools/buildbot/bin/python'} 18:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 INFO - Running setup.py (path:/tmp/pip-D78a3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:14 INFO - Running setup.py (path:/tmp/pip-cJuSEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:15 INFO - Running setup.py (path:/tmp/pip-zFbPbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:15 INFO - Running setup.py (path:/tmp/pip-JPoSZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:15 INFO - Running setup.py (path:/tmp/pip-gHfuob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:15 INFO - Running setup.py (path:/tmp/pip-WfsHjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:15 INFO - Running setup.py (path:/tmp/pip-fUbbrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:15 INFO - Running setup.py (path:/tmp/pip-IvRSiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:15 INFO - Running setup.py (path:/tmp/pip-h_Bh47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:15 INFO - Running setup.py (path:/tmp/pip-G0tQ9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 INFO - Running setup.py (path:/tmp/pip-Gmwgq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 INFO - Running setup.py (path:/tmp/pip-OjOgcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 INFO - Running setup.py (path:/tmp/pip-SavM3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 INFO - Running setup.py (path:/tmp/pip-LRwHiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:15 INFO - Running setup.py (path:/tmp/pip-QabYpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:15 INFO - Running setup.py (path:/tmp/pip-bnbxbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:16 INFO - Running setup.py (path:/tmp/pip-m1J8S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:18 INFO - Downloading blessings-1.6.tar.gz 18:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:18 INFO - Installing collected packages: blessings 18:30:18 INFO - Running setup.py install for blessings 18:30:18 INFO - Successfully installed blessings 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:18 INFO - Reading from file tmpfile_stdout 18:30:18 INFO - Current package versions: 18:30:18 INFO - argparse == 1.2.1 18:30:18 INFO - blessings == 1.6 18:30:18 INFO - blobuploader == 1.2.4 18:30:18 INFO - docopt == 0.6.1 18:30:18 INFO - functools32 == 3.2.3-2 18:30:18 INFO - jsonschema == 2.5.1 18:30:18 INFO - manifestparser == 1.1 18:30:18 INFO - mozInstall == 1.12 18:30:18 INFO - mozcrash == 0.17 18:30:18 INFO - mozdebug == 0.1 18:30:18 INFO - mozdevice == 0.48 18:30:18 INFO - mozfile == 1.2 18:30:18 INFO - mozhttpd == 0.7 18:30:18 INFO - mozinfo == 0.9 18:30:18 INFO - mozleak == 0.1 18:30:18 INFO - mozlog == 3.2 18:30:18 INFO - moznetwork == 0.27 18:30:18 INFO - mozprocess == 0.22 18:30:18 INFO - mozprofile == 0.28 18:30:18 INFO - mozrunner == 6.11 18:30:18 INFO - mozscreenshot == 0.1 18:30:18 INFO - mozsystemmonitor == 0.3 18:30:18 INFO - moztest == 0.7 18:30:18 INFO - mozversion == 1.4 18:30:18 INFO - psutil == 3.1.1 18:30:18 INFO - requests == 1.2.3 18:30:18 INFO - wsgiref == 0.1.2 18:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 18:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:18 INFO - Using env: {'DISPLAY': ':0', 18:30:18 INFO - 'HOME': '/home/cltbld', 18:30:18 INFO - 'LANG': 'en_US.UTF-8', 18:30:18 INFO - 'LANGUAGE': 'en_US:en', 18:30:18 INFO - 'LOGNAME': 'cltbld', 18:30:18 INFO - 'MAIL': '/var/mail/cltbld', 18:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:18 INFO - 'MOZ_NO_REMOTE': '1', 18:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:18 INFO - 'NO_EM_RESTART': '1', 18:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:18 INFO - 'PWD': '/builds/slave/test', 18:30:18 INFO - 'SHELL': '/bin/bash', 18:30:18 INFO - 'SHLVL': '1', 18:30:18 INFO - 'TERM': 'linux', 18:30:18 INFO - 'TMOUT': '86400', 18:30:18 INFO - 'USER': 'cltbld', 18:30:18 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:18 INFO - '_': '/tools/buildbot/bin/python'} 18:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:18 INFO - Running command: ['/builds/slave/test/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/build 18:30:18 INFO - Copy/paste: /builds/slave/test/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 18:30:18 INFO - Using env: {'DISPLAY': ':0', 18:30:18 INFO - 'HOME': '/home/cltbld', 18:30:18 INFO - 'LANG': 'en_US.UTF-8', 18:30:18 INFO - 'LANGUAGE': 'en_US:en', 18:30:18 INFO - 'LOGNAME': 'cltbld', 18:30:18 INFO - 'MAIL': '/var/mail/cltbld', 18:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:18 INFO - 'MOZ_NO_REMOTE': '1', 18:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:18 INFO - 'NO_EM_RESTART': '1', 18:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:18 INFO - 'PWD': '/builds/slave/test', 18:30:18 INFO - 'SHELL': '/bin/bash', 18:30:18 INFO - 'SHLVL': '1', 18:30:18 INFO - 'TERM': 'linux', 18:30:18 INFO - 'TMOUT': '86400', 18:30:18 INFO - 'USER': 'cltbld', 18:30:18 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:18 INFO - '_': '/tools/buildbot/bin/python'} 18:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Running post-action listener: _resource_record_post_action 18:30:18 INFO - Running post-action listener: _start_resource_monitoring 18:30:18 INFO - Starting resource monitoring. 18:30:18 INFO - ##### 18:30:18 INFO - ##### Finished create-virtualenv step (success) 18:30:18 INFO - ##### 18:30:18 INFO - ##### 18:30:18 INFO - ##### Running install step. 18:30:18 INFO - ##### 18:30:18 INFO - Running pre-action listener: _resource_record_pre_action 18:30:18 INFO - Running main action method: install 18:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:19 INFO - Reading from file tmpfile_stdout 18:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:19 INFO - Reading from file tmpfile_stdout 18:30:19 INFO - Output received: 18:30:19 INFO - Usage: mozinstall [options] installer 18:30:19 INFO - Options: 18:30:19 INFO - -h, --help show this help message and exit 18:30:19 INFO - -d DEST, --destination=DEST 18:30:19 INFO - Directory to install application into. [default: 18:30:19 INFO - "/builds/slave/test"] 18:30:19 INFO - --app=APP Application being installed. [default: firefox] 18:30:19 INFO - mkdir: /builds/slave/test/build/application 18:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:30:35 INFO - Reading from file tmpfile_stdout 18:30:35 INFO - Output received: 18:30:35 INFO - /builds/slave/test/build/application/firefox/firefox 18:30:35 INFO - Running post-action listener: _resource_record_post_action 18:30:35 INFO - ##### 18:30:35 INFO - ##### Finished install step (success) 18:30:35 INFO - ##### 18:30:35 INFO - ##### 18:30:35 INFO - ##### Running run-tests step. 18:30:35 INFO - ##### 18:30:35 INFO - Running pre-action listener: _resource_record_pre_action 18:30:35 INFO - Running main action method: run_tests 18:30:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:30:35 INFO - Python 2.7.3 18:30:35 INFO - Return code: 0 18:30:35 INFO - grabbing minidump binary from tooltool 18:30: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')]} 18:30:35 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:30:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:30:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:30:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:30:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:35 INFO - Return code: 0 18:30:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:30:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:30:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:30:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:30:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:30:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:30:35 INFO - Using env: {'DISPLAY': ':0', 18:30:35 INFO - 'HOME': '/home/cltbld', 18:30:35 INFO - 'LANG': 'en_US.UTF-8', 18:30:35 INFO - 'LANGUAGE': 'en_US:en', 18:30:35 INFO - 'LOGNAME': 'cltbld', 18:30:35 INFO - 'MAIL': '/var/mail/cltbld', 18:30:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:35 INFO - 'MOZ_NO_REMOTE': '1', 18:30:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:35 INFO - 'NO_EM_RESTART': '1', 18:30:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:35 INFO - 'PWD': '/builds/slave/test', 18:30:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:35 INFO - 'SHELL': '/bin/bash', 18:30:35 INFO - 'SHLVL': '1', 18:30:35 INFO - 'TERM': 'linux', 18:30:35 INFO - 'TMOUT': '86400', 18:30:35 INFO - 'USER': 'cltbld', 18:30:35 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460', 18:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:35 INFO - '_': '/tools/buildbot/bin/python'} 18:30:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:30:36 INFO - mozversion application_buildid: 20160705171131 18:30:36 INFO - mozversion application_changeset: 70e05c6832e831374604ac3ce7433971368dffe0 18:30:36 INFO - mozversion application_display_name: Nightly 18:30:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:36 INFO - mozversion application_name: Firefox 18:30:36 INFO - mozversion application_remotingname: firefox 18:30:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:30:36 INFO - mozversion application_vendor: Mozilla 18:30:36 INFO - mozversion application_version: 50.0a1 18:30:36 INFO - mozversion platform_buildid: 20160705171131 18:30:36 INFO - mozversion platform_changeset: 70e05c6832e831374604ac3ce7433971368dffe0 18:30:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:30:36 INFO - mozversion platform_version: 50.0a1 18:30:36 INFO - using testdate: 1467768636 18:30:36 INFO - actual date: 1467768636 18:30:36 INFO - starting webserver on 'localhost:51120' 18:30:36 INFO - SUITE-START | Running 2 tests 18:30:36 INFO - TEST-START | dromaeo_css 18:30:36 INFO - operating with platform_type : linux_ 18:30:36 INFO - Initialising browser for dromaeo_css test... 18:30:36 INFO - TEST-INFO | started process 3274 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObTEWC/profile http://localhost:51120/getInfo.html) 18:30:41 INFO - PROCESS | 3274 | __metrics Screen width/height:1600/1200 18:30:41 INFO - PROCESS | 3274 | colorDepth:24 18:30:41 INFO - PROCESS | 3274 | Browser inner width/height: 1024/697 18:30:41 INFO - PROCESS | 3274 | __metrics 18:30:42 INFO - PROCESS | 3274 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:43 INFO - TEST-INFO | 3274: exit 0 18:30:43 INFO - Browser initialized. 18:30:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:30:43 INFO - TEST-INFO | started process 3363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpObTEWC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:55 INFO - PROCESS | 3363 | RSS: Main: 158412800 18:30:55 INFO - PROCESS | 3363 | 18:31:46 INFO - PROCESS | 3363 | 1467768706757 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:33:59 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51120/tests/dromaeo/cssquery-ext.html) 18:34:00 INFO - PROCESS | 3363 | RSS: Main: 246734848 18:34:00 INFO - PROCESS | 3363 | 18:37:03 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-ext.html (next: http://localhost:51120/tests/dromaeo/cssquery-jquery.html) 18:37:04 INFO - PROCESS | 3363 | RSS: Main: 247058432 18:37:04 INFO - PROCESS | 3363 | 18:40:06 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51120/tests/dromaeo/cssquery-mootools.html) 18:40:07 INFO - PROCESS | 3363 | RSS: Main: 241491968 18:40:07 INFO - PROCESS | 3363 | 18:43:09 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51120/tests/dromaeo/cssquery-prototype.html) 18:43:10 INFO - PROCESS | 3363 | RSS: Main: 239091712 18:43:10 INFO - PROCESS | 3363 | 18:46:13 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51120/tests/dromaeo/cssquery-yui.html) 18:46:13 INFO - PROCESS | 3363 | RSS: Main: 213274624 18:46:13 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:51120/tests/dromaeo/cssquery-yui.html (next: http://localhost:51120/tests/dromaeo/cssquery-dojo.html) 18:49:16 INFO - PROCESS | 3363 | RSS: Main: 276422656 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | __start_tp_report 18:49:16 INFO - PROCESS | 3363 | _x_x_mozilla_page_load 18:49:16 INFO - PROCESS | 3363 | _x_x_mozilla_page_load_details 18:49:16 INFO - PROCESS | 3363 | |i|pagename|runs| 18:49:16 INFO - PROCESS | 3363 | |0;dojo.html;2642.088479474581;2651.920442386729;2683.020697445432;2692.959605605915;2695.0702007807313;4701.50634183411;4751.38232029836;4777.426708794941;4787.114383838988;4860.708357498553;4694.530546945304;4765.952340476594;4769.928451073237;4781.306710526971;4789.066132104233;4321.82712691492;4344.261475549149;4400.44994375703;4432.578905004019;4518.774061296938;4448.510663826976;4473.642108631302;4481.932771008437;4508.661850361236;4535.614472769818;4226.513950895655;4256.553061928484;4264.530901600821;4344.196323680129;4384.736915785031;4390.714603550759;4393.7363758174615;4398.34024896265;4403;4485.708428952108;4423.911521769579;4429.247028005247;4435.046465010038;4472.955270447286;4474.948387129022;4706;4741.075490279397;4822.252550854623;4830.830920917751;4883.682560633583;5103.770330335144;5134.614903882224;5185.766640501149;5186.5850731941355;5189.714565698885;5165.793368265265;5189.558887494568;5195.6622819516615;5225.895482090375;5234.26720259164;4728.929066064012;4751.40607424072;4790.856274311706;4839.080574690797;4855.271709243606;5280.894382112337;5290;5318.468153184728;5326.813561525328;5339.626226164131;5034.244863270539;5040.974795126074;5041.395032596112;5072.568831649277;5096.821611243588;5049.318342023853;5091.465396133427;5143.12566863634;5171.637985341065;5176.4305926348825;5066.670666406671;5090.287359769635;5092.592592592584;5101.770420331094;5181.896362072737;5114.130597798383;5118;5120.48795120485;5130.102232109366;5134.512221338967;4220.430241917302;4244.427002354704;4255.212785634668;4286.54991225923;4297.806598703066;3783.4703141560203;3807.8667246646232;3811.8094095295123;3830.0999265172663;3832.9808350958065;4337.154254920323;4376.387305777193;4379.430674012358;4388.429504164438;4407.074514351958;5021.397432308147;5136.178211486144;5143.202803565453;5146.742662866916;5205.6616319939085;4267.231898258343;4375.299952007664;4376.081022985145;4379.58393952574;4439.356293337512;4515.932261016087;4559.01981074064;4581.93127103087;4626.282926146453;4648.465426475931;4862.635302352267;4880.902381952341;4886.560209581155;4889.339939108318;4930.9506904930495;5110.335656364798;5113.105206588906;5138.07514647367;5173.32746742921;5197.22041693749;4732.550407711262;4745.738984355894;4748.691335063209;4788;4803.5916947059895;4962.556613503466;4990.775415106402;4993.950060499494;5002.9994001199175;5040.344755181803;5039.21892106724;5065.544101030925;5079.5428411443145;5080.618953578571;5129.179331306973;4906.239532872411;4960.70235785854;4979.452260251442;4986.052649996489;4991.900161996812;4939.357883475125;4971.825986090615;4976.800927962841;4978.477259887804;5026.899462010666;4771.06964141989;4856.320115183807;4899.16714158601;4910.631702622389;4964.205727083649;5029.9245511318095;5041.0422019816115;5051.116054690341;5053.7473126344275;5104.923426148536;4920.803167873388;4948.158813001726;4949.802007919787;4962.404511458648;5011.273365361929 18:49:16 INFO - PROCESS | 3363 | |1;ext.html;550.9745303341047;570.3213176320179;579.6000759475963;583.7460704593501;596.1742985964439;39757.80121099394;40142.59857401425;41069.589304106914;41237.175256494855;44265.33601995964;40452;40821.183576328454;41046.79476602613;41108.794456027754;41110;7510.887336689954;7730.18833022533;7740.458167928247;7852.528848269108;8013.158618345048;7740.922590774105;10157.339772914736;10440.530176142054;10455.111315538188;10756.515956781925;10500.422476763775;10549.15606751462;10562.78874422511;10566.524506397192;10569.947150264237;7575.204603516607;10100.797984040279;10333.121684656813;10375.584976600927;10386.013328733796;10010.54952527138;10356.119729822974;10356.896431035668;10360.533775980099;10373.222008349405;39930;40355.59644403548;40361.39457908134;40388;40408;3081.7380522655603;3168.984155079233;3198.184462961953;3373.1398493384277;3530.0998245447245;29260.707392926222;29505.409891802257;29568.260793480335;29926.95255666063;30195.396092078256;3322.734181265493;3342.398368293706;3350.7821991570713;3360.8487618057;3437.74216933731;254353;254672;255037;255242;255670.72164639243;31174.376512469622;32512.674873250966;52371.214431783555;53146;53189.468105318454;33058.83470582632;33217.66782332194;33257.83371083129;33280.334393312;33288.50067248993;12755.553555625509;12836.101472896808;12865.871341286655;12866.806997895039;12874.742505150032;9260.259179265642;10629.84055239172;10655.68032958997;10709;10890;7828.960855195687;7843.960780196063;7871.055473343121;7922.564258965697;7925.128235894047;3030.272734543694;3038.346755447589;3076.7384772294604;3084.1364417963005;3092.505199168122;832.4797001873828;902.0843843498853;943.2265542255285;958.8993155718689;1008.6167256442504;26994.32514187161;28491.857540712164;28523.286917826805;29227.707722922925;29272.85363573211;927.1470247372433;997.6209040564539;1001.399160503692;1024.636254129788;1053.8419237114495;14275.571732848031;15047.247637618075;15062;15081.170535620427;15320.310586023434;397420;399030;402146;402238;403070;51038.234426483636;51094.48905510972;51098;51133.7443312781;51543;1034.5809946971488;1098.5111625326654;1137.4028634966708;1150.1603829204728;1195.2768575012167;27134.592981105696;29905;29912.401751965273;30487.695123049372;30623.2344191397;43429.56570434255;43984.780076099414;44077.33883991807;44147;44189.77905110454;309073;310734;310890;311317;312137;24763.25710228696;25726.485470290114;25732.485350292514;25749.742502574736;25771.613425799;42862.57137428586;42926.35610465783;43477;44228.77885610551;44470.777646111565;37295.81352093222;37378.813105935384;37739.81130094442;37870;38276;37040.25919481649;37495.81252093722;37509.43735844019;40285.1942961145;42049.579504204565;36928.630713693594;37163.81418092892;37659;37693.81153094217;38120.80939595284;32525.34949301048;33114.66885331116;34399.82800085984;34478.13804654803;34483.310333793685;25677.614835777105;25854.224373268833;26065.696715164544;26072;26087.956481741276 18:49:16 INFO - PROCESS | 3363 | |2;jquery.html;349.86879920029986;371.9944200836987;376.17429741716944;379.8081968605854;393.1154901471689;13180.868191318084;16595.917020414898;16903.408380706678;18209.18058687359;18231.54421139469;16523.752143717855;16649.084300363476;17494.562635934108;17499.475015749547;17511.562210944732;5037.823676171335;5058.342415485991;5205.687658740488;5212.556932660709;5216.713080780555;3735.775853448788;5168.689878607277;5181.4300426953005;5893.764249430018;6234.314225435199;6243.094751261065;6268.185135932323;6271.310155882849;6282.937170628281;6293.339199384068;5979.402059794029;6226.750929962796;6240.063990401431;6240.375962403768;6241.500679945593;3424.845881935319;3593.766405183681;3600.3339382214376;5462.617616766828;5470.316210473691;19461.708074378897;20956.580868382545;21374.893125534272;22437.214697485673;22579.435514112116;2018.1927229108328;2107.662773956175;2310.1567927706433;2351.106579499779;2381.4403615150427;12747.235165890077;13590.456381744718;13755.656108597264;13774;13913.930430347884;1691.1121661127884;1762.9118544072744;1788.2668106076576;1819.9090045497671;1845.2987864611362;65098.67450662716;65887.3411265886;66011.66994164999;67069.66465167691;68839;9077.727668169966;9706.174975127193;9787.461689606998;9939.204863610894;10397.428141452141;12410.937945310396;13276.668083297802;13288.136271142344;13557.25435101079;13610.455581776816;5720.998825205573;6917.654117294115;6979.092717946634;6997.615131167835;7076.150861896503;6413.16628838248;6446.645434501053;6493.350664933469;6587.53887227899;6633.336666333425;4403.625691816167;4446.688731788744;4446.866594002185;4456.576625220664;4457.4205353304515;1786.2944137066047;1786.7051936430603;1836.8875792838344;1840.3098837935775;1869.8878067316004;858.2404571953866;894.879191309165;939.7509659940122;956.3496822160997;962.0998527907327;17384.217710202818;17434.12829358527;17789;18153.18310676023;18576;853.5774791478258;858.2618947704968;865.3812524045212;870.5516658920661;875.916787905148;9691.903080969241;10048.698539043695;10085.445300508396;10113.747156321004;10157.644482443078;350938.6795990311;358560;363094;363783;363835;44032;44226.55773442289;80705;84097;84865.15134848696;872.3326655108847;877.9209871111631;885.5218182181678;885.7918389178537;897.1297841094129;15791.36834526606;16038.358465661242;16254.674906501567;16317;16342.754858676892;26969;38610.227795444494;39698;40278.798606006785;41446.1710765789;49311;50110.749446252536;52084;56372;56797;7617.542947423172;7965.084015338311;8010.279074883289;8023.799405014921;8341.708040218806;11990;12243.20419172751;12253.877461225273;12695.42870570808;12788.616341509769;10912.78174436491;10923.508442119964;10927.83608245861;11132.721681958015;11141.275817071864;10562.788744225227;10724.89275107239;10756.51595678212;10760.3543787373;10961.890381096406;10489.632862849763;10616.734581635521;10653.254272201182;10727.892721073002;11060.17048721333;6817.216020157745;6881.587104773729;7887.526748395115;8009.839803203787;8011.038675358995;9856.655017074369;10724;10865.619703310345;10910.454477276264;10958.01377875963 18:49:16 INFO - PROCESS | 3363 | |3;mootools.html;1395.302348825587;1425.0879437160218;1507.2539093148894;1701.5150682055616;1720.6816738903303;7566.092068951727;7981.561014144219;8092.635831387587;8103.149169337215;8106.2299081587225;6736.932630673692;7430.256974302581;7625.8474830503355;7653.770386888388;7851.960740196305;2144.6461333879893;2169.9566008679817;2170.5116348821466;2173.0655817998254;2175.0321107107343;2213.612617791888;2229;2242.7308722953267;2245.057076028072;2245.8989345479413;1914.3586898389055;2117.4388786971463;2183.759786423492;2188.102877820094;2212.4800671842104;2192.6382146945734;2203.1627981367137;2213.690083388327;2213.8339624528226;2230.9776902231097;2144.142343062772;2162.1243396424316;2162.697222388867;2170.7395112586432;2176.7714389988982;7948.642311096015;8001.719939802138;8002.519848809032;8007.359411247086;8007.759767206993;4527.954720452786;4653.046125544255;4653.930191047137;4656.068786242765;4656.906861862778;2863.2984918694847;2892.146816689082;2986.5072263076568;3000.819950802937;3001.519756838917;888.1873086126187;895.2077411490877;904.8552231642972;907.2152588011388;914.6021480655936;10662.1470282378;10768.569257229701;10791.298565593133;10791.946040269828;10799.298045627087;3966;4108.137291168888;4248.362745588187;4337.457817772778;4344.109457561192;9067.81864362709;9103;9125.36122471434;9138.497382644018;9149.451032938045;4530.252508336153;4536.591706746409;4537.0925814837165;4545.840895568639;4546.431696037996;4037.717359784787;4072.144849581621;4078.388241763759;4086.366613174963;4199.223143718422;3652.7260455465416;3677.889663310105;3704.6110158432975;3755.774653520743;3772.6227377262603;1006.4766321512918;1163.5578480177478;1180.3213152437384;1199.4782269712703;1218.0742635596832;708.9858202835915;756.8334966307403;765.4182821055927;777.8522080804629;790.1348023913805;4683.906321873544;4688.249880019181;4814.085323788469;4896.681715688469;4948.38145231846;844.3371953016853;859.8538248497771;871.4030888841164;873.2359185712502;880.4409200157818;3172.1435212492497;3307.570015897966;3328.4674452088034;3343.36476069546;3419.1110311319235;9510.476923769122;9840.311178217456;9981.600735970625;9989.200863930868;9999.600015999424;9815.803683926282;9824.95087524572;9828.459434731016;9833.213342932548;9877;6386.521010924199;7145.857082858313;7371.520851144658;7410.592417417094;7462.291082347168;5378.515933565841;5391.299131113087;5448.155535891943;5466.316710411199;5608.915866262091;9048.095190480899;9187.44875307457;9218.677346292847;9232.076792320715;9250.4912229828;8638.611262493077;8928.955355223183;8949.463032218087;9138.68014619485;9149.359544831797;6838.350356716104;6970.477214208853;6992.79021629352;6993.510454268152;6994.720211191496;7366.410687145123;7418.814529636802;7444.73943411978;7512.586807725409;7526.924730752622;5964.97017514927;6113.2969708482315;6677.29888361715;6803.489738269551;6965.7213711453005;6731.259561448139;6806.217285012287;6808.353206445379;6888.931110688829;6905.689243983932;6028.246469191351;6087.786927457518;6094.634321940698;6098.146259523759;6125.9693701531205;4129.525104612888;4181.853635122756;4249.830006799817;4289.592488713567;4303.440552728125;4329.6969212154845;4357.281048626941;4373.190959672471;4486.461624605068;4510.210713125124 18:49:16 INFO - PROCESS | 3363 | |4;prototype.html;289.47460359447604;292.9223755704739;293.77232644700354;296.9881204751809;298.96561895382035;4829.8792530186765;4863.878403039926;4868.269759536071;4874.317595536628;4888;4799.560131960416;4800.399950006249;4811.711297322163;4812.181929072058;4814.325994360793;3589.820508974554;3689.317476266886;3695.334839728848;3781.468505255376;3786.6970642348683;3723.3484140275477;3763.56718977317;3770.660640542351;3777.376732839078;3779.263043706478;3579.266250418658;3742.6257374262627;3747.5877653458097;3753.3806921858;3769.4157405602036;3621.1852333224947;3743.47591337213;3751.3060083884297;3755.0236938396215;3763.2097259575658;3295.555100061509;3296.258341873059;3301.224212310105;3304.884329048472;3309.1065412338776;4717;4717.245240761462;4722.929156062662;4724.71651700899;4730.432348118248;4134.627883490501;4228.365745138223;4248.723832950848;4256.34026725858;4283.186194623012;4979.676321039121;5008.12357837377;5011.398632164127;5017.197248440269;5020;3563.572371315433;3573.285342931398;3579.997600671816;3581.4627805829073;3591.9403775886176;4999.4250661174065;5203.7398130093725;5210.270562121306;5234.377343023675;5234.476552344735;3656.3784156693428;3672.5776535698737;3673.1368128489785;3681.4661874028116;3682.9447558286647;3705.962940370616;3712.5730540987593;3719.683826874692;3720.1071742781537;3728.9440658390145;4221.324588065895;4224.683148763855;4226.830926762957;4233.216854881856;4234.449521562177;4822.432709369469;5017.774200161001;5022.910909790453;5023.773930173151;5045.974770126126;1281.769281529315;1287.9806802897965;1296.2287438973794;1298.1821452484874;1300.3563236198142;835.0188528479013;836.4186890111436;863.792689754467;902.2105657549644;904.8733177355175;4350.891227719332;4367.563243675607;4383.364412160283;4411.073674528385;4427.756473393994;5132.384113906281;5141.717205553656;5156.458571849977;5173.63784535079;5176.197689358156;4243.681723870722;4363.890902727458;4370.278903980871;4384.4519435070615;4388.692791504628;2714.348556346463;2730.1298610451904;2736.3979924416594;2737.438825040862;2746.780257579389;4855.708657480563;4856.538628830255;4860.441049279378;4862.465128835825;4874.2201247800185;4994.850154495371;5209.218617207449;5248.396434410091;5249.42256351801;5249.527542521191;4831.613470922318;4837.419509658793;4853.417589889165;4858.46556878743;4874.268859670936;5085.288059671723;5112.233165025276;5113.233015047773;5114.872128196825;5115.335006449138;4880.829170979141;5046.949530504795;5063.898722025612;5157.200633901752;5159.251908473176;4932.087563800708;4947.777350019186;4950.9257361140335;4975.278584605139;4978.153713868579;3695.5934847166254;3696.8890933272046;3718.330700473941;3727.453106960581;3737.1965027519213;5075.771590278372;5203.869903252449;5209;5219.765110569958;5230.045024561111;5021.422536408214;5026.396832380138;5058.367704036996;5069.264956581349;5070.2141168119;5023.924641130459;5042.596592272683;5047.444781074006;5067;5067.442581316126;4830.275458681226;4938.432080310667;4939.827106051271;4949.7030178189425;4988.700677959189;4813.229883218808;4895.314655948241;5048.974755126201;5050.898982020412;5059;4933.679310844784;4943;4957.4794646561595;4965.106280869478;4977.382350735953 18:49:16 INFO - PROCESS | 3363 | |5;yui.html;612.5803824380303;635.5773410681894;645.3191882563895;699.8740226759185;702.5222848463045;11903.107266954981;12787;12825.102242843004;12827;12839.679008024805;11698.649040528777;11908.82136767947;12112.212706174092;12141.392930353491;12149;629.9118123462696;648.2609824799708;767.6315368623065;774.4772278711861;795.411395567279;785.6071964017991;787.5943888897194;789.763071078677;790.7785819970388;792.6036981509245;838.3125836813815;838.5094719589017;838.7944953486405;839.2237180607913;842.8609279468881;642.4250295985067;807.0476837331946;825.6779855856221;830.8753686946945;833.071125694852;606.0787602843697;607.6748939317444;608.3581821178655;609.439315829438;609.8383928259015;12074.094442916728;12119.091068169835;12122.030237580972;12124.393780311037;12129.818052729217;12178.695532611668;12532.51194392719;12563.371831408393;12768.170068945581;12977.35113244334;11558.19092663514;11610.709732256677;11670.416479176007;11737.237079589884;11905.285682859056;3856.652901238902;3903.843846246175;3909.3745000799736;3950.348192548226;4064.512258528966;59616;59721.402785972015;59836;59843.70078149625;60089.699551501966;9227.630894764134;9599.136077753035;9606.567704453317;9804.607815687436;10093.444860532594;17890.105494725212;17893;17931.551711207063;17933.461996140137;18386.08069596515;6792.558483698544;6835.179778426521;6857.691403886837;6886.657101865088;6898.103246578014;3686.4101743720876;3710.220853620716;3734.7572407793405;3792.943105853414;3818.4081467372603;2692.892284308645;2760.5307097793425;2778.4998700233746;2786.679531853863;2787.205646390769;472.29156265601597;480.0950208856261;488.66038103518423;490.23278569039337;491.5575981616502;534.236042459287;546.4535068232349;564.2439131563642;564.5060571999501;565.966042037479;7252.5648461092505;7419.332260096618;7491.325780679765;7548.811279718051;7868.859015442728;432.88744926319384;494.6092586856415;502.70591703852926;525.1151809201508;575.1602660116193;3494.7029502492574;3576.445650924111;3610.4403817408343;3714.312852122366;3720.2745464634136;93270;97620.51189744005;98791;99906.5004674972;100497;59065.70467147637;69504.65247673729;70505.29494704987;83270.58364708138;83299;46814.531854681016;79809;80441;81955.5902220497;83875;8970.86543701832;8972.685955991508;9069.455832650063;9124.77188070277;9134.314926380415;47854;48303;48622;48773.268400973306;49489.50510494849;63247.68376158274;66486.66756666185;71936.64031680017;72136;72429;11889.108316876096;11961.940190298992;12081.214721043105;12335.568255111028;12498.12513124109;12618.810717839424;13493.662658433617;13567.932160339134;13900.235487048309;13941.860581394461;7977.680892764457;12072.698182545506;12569.560065397667;12723.300218488072;12756.48974041065;11888.227265227733;12233.3271670059;12484;12698.174618649758;13081.149725267827;12091.47890229319;12566.434510446868;12653;12687.873121269038;12804.295763733084;9796.559154838193;9904.50477476109;10491.632792852213;10694.89305106939;10737.302075365076;9776.113815050543;10042.49787510636;10607.94696026515;10631.627893024017;10665.41340226295 18:49:16 INFO - PROCESS | 3363 | __end_tp_report 18:49:16 INFO - PROCESS | 3363 | __start_cc_report 18:49:16 INFO - PROCESS | 3363 | _x_x_mozilla_cycle_collect,873 18:49:16 INFO - PROCESS | 3363 | __end_cc_report 18:49:16 INFO - PROCESS | 3363 | __startTimestamp1467769756423__endTimestamp 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | ------- Summary: start ------- 18:49:16 INFO - PROCESS | 3363 | Number of tests: 6 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#0] dojo.html Cycles:165 Average:4732.96 Median:4880.90 stddev:503.69 (10.3%) stddev-sans-first:477.79 18:49:16 INFO - PROCESS | 3363 | Values: 2642.1 2651.9 2683.0 2693.0 2695.1 4701.5 4751.4 4777.4 4787.1 4860.7 4694.5 4766.0 4769.9 4781.3 4789.1 4321.8 4344.3 4400.4 4432.6 4518.8 4448.5 4473.6 4481.9 4508.7 4535.6 4226.5 4256.6 4264.5 4344.2 4384.7 4390.7 4393.7 4398.3 4403.0 4485.7 4423.9 4429.2 4435.0 4473.0 4474.9 4706.0 4741.1 4822.3 4830.8 4883.7 5103.8 5134.6 5185.8 5186.6 5189.7 5165.8 5189.6 5195.7 5225.9 5234.3 4728.9 4751.4 4790.9 4839.1 4855.3 5280.9 5290.0 5318.5 5326.8 5339.6 5034.2 5041.0 5041.4 5072.6 5096.8 5049.3 5091.5 5143.1 5171.6 5176.4 5066.7 5090.3 5092.6 5101.8 5181.9 5114.1 5118.0 5120.5 5130.1 5134.5 4220.4 4244.4 4255.2 4286.5 4297.8 3783.5 3807.9 3811.8 3830.1 3833.0 4337.2 4376.4 4379.4 4388.4 4407.1 5021.4 5136.2 5143.2 5146.7 5205.7 4267.2 4375.3 4376.1 4379.6 4439.4 4515.9 4559.0 4581.9 4626.3 4648.5 4862.6 4880.9 4886.6 4889.3 4931.0 5110.3 5113.1 5138.1 5173.3 5197.2 4732.6 4745.7 4748.7 4788.0 4803.6 4962.6 4990.8 4994.0 5003.0 5040.3 5039.2 5065.5 5079.5 5080.6 5129.2 4906.2 4960.7 4979.5 4986.1 4991.9 4939.4 4971.8 4976.8 4978.5 5026.9 4771.1 4856.3 4899.2 4910.6 4964.2 5029.9 5041.0 5051.1 5053.7 5104.9 4920.8 4948.2 4949.8 4962.4 5011.3 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#1] ext.html Cycles:180 Average:47234.74 Median:18290.28 stddev:86368.56 (472.2%) stddev-sans-first:86539.73 18:49:16 INFO - PROCESS | 3363 | Values: 551.0 570.3 579.6 583.7 596.2 39757.8 40142.6 41069.6 41237.2 44265.3 40452.0 40821.2 41046.8 41108.8 41110.0 7510.9 7730.2 7740.5 7852.5 8013.2 7740.9 10157.3 10440.5 10455.1 10756.5 10500.4 10549.2 10562.8 10566.5 10569.9 7575.2 10100.8 10333.1 10375.6 10386.0 10010.5 10356.1 10356.9 10360.5 10373.2 39930.0 40355.6 40361.4 40388.0 40408.0 3081.7 3169.0 3198.2 3373.1 3530.1 29260.7 29505.4 29568.3 29927.0 30195.4 3322.7 3342.4 3350.8 3360.8 3437.7 254353.0 254672.0 255037.0 255242.0 255670.7 31174.4 32512.7 52371.2 53146.0 53189.5 33058.8 33217.7 33257.8 33280.3 33288.5 12755.6 12836.1 12865.9 12866.8 12874.7 9260.3 10629.8 10655.7 10709.0 10890.0 7829.0 7844.0 7871.1 7922.6 7925.1 3030.3 3038.3 3076.7 3084.1 3092.5 832.5 902.1 943.2 958.9 1008.6 26994.3 28491.9 28523.3 29227.7 29272.9 927.1 997.6 1001.4 1024.6 1053.8 14275.6 15047.2 15062.0 15081.2 15320.3 397420.0 399030.0 402146.0 402238.0 403070.0 51038.2 51094.5 51098.0 51133.7 51543.0 1034.6 1098.5 1137.4 1150.2 1195.3 27134.6 29905.0 29912.4 30487.7 30623.2 43429.6 43984.8 44077.3 44147.0 44189.8 309073.0 310734.0 310890.0 311317.0 312137.0 24763.3 25726.5 25732.5 25749.7 25771.6 42862.6 42926.4 43477.0 44228.8 44470.8 37295.8 37378.8 37739.8 37870.0 38276.0 37040.3 37495.8 37509.4 40285.2 42049.6 36928.6 37163.8 37659.0 37693.8 38120.8 32525.3 33114.7 34399.8 34478.1 34483.3 25677.6 25854.2 26065.7 26072.0 26088.0 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#2] jquery.html Cycles:180 Average:23473.47 Median:2055.98 stddev:59469.06 (2892.5%) stddev-sans-first:59610.54 18:49:16 INFO - PROCESS | 3363 | Values: 349.9 372.0 376.2 379.8 393.1 13180.9 16595.9 16903.4 18209.2 18231.5 16523.8 16649.1 17494.6 17499.5 17511.6 5037.8 5058.3 5205.7 5212.6 5216.7 3735.8 5168.7 5181.4 5893.8 6234.3 6243.1 6268.2 6271.3 6282.9 6293.3 5979.4 6226.8 6240.1 6240.4 6241.5 3424.8 3593.8 3600.3 5462.6 5470.3 19461.7 20956.6 21374.9 22437.2 22579.4 2018.2 2107.7 2310.2 2351.1 2381.4 12747.2 13590.5 13755.7 13774.0 13913.9 1691.1 1762.9 1788.3 1819.9 1845.3 65098.7 65887.3 66011.7 67069.7 68839.0 9077.7 9706.2 9787.5 9939.2 10397.4 12410.9 13276.7 13288.1 13557.3 13610.5 5721.0 6917.7 6979.1 6997.6 7076.2 6413.2 6446.6 6493.4 6587.5 6633.3 4403.6 4446.7 4446.9 4456.6 4457.4 1786.3 1786.7 1836.9 1840.3 1869.9 858.2 894.9 939.8 956.3 962.1 17384.2 17434.1 17789.0 18153.2 18576.0 853.6 858.3 865.4 870.6 875.9 9691.9 10048.7 10085.4 10113.7 10157.6 350938.7 358560.0 363094.0 363783.0 363835.0 44032.0 44226.6 80705.0 84097.0 84865.2 872.3 877.9 885.5 885.8 897.1 15791.4 16038.4 16254.7 16317.0 16342.8 26969.0 38610.2 39698.0 40278.8 41446.2 49311.0 50110.7 52084.0 56372.0 56797.0 7617.5 7965.1 8010.3 8023.8 8341.7 11990.0 12243.2 12253.9 12695.4 12788.6 10912.8 10923.5 10927.8 11132.7 11141.3 10562.8 10724.9 10756.5 10760.4 10961.9 10489.6 10616.7 10653.3 10727.9 11060.2 6817.2 6881.6 7887.5 8009.8 8011.0 9856.7 10724.0 10865.6 10910.5 10958.0 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#3] mootools.html Cycles:180 Average:5128.23 Median:5384.91 stddev:2922.14 (54.3%) stddev-sans-first:2916.87 18:49:16 INFO - PROCESS | 3363 | Values: 1395.3 1425.1 1507.3 1701.5 1720.7 7566.1 7981.6 8092.6 8103.1 8106.2 6736.9 7430.3 7625.8 7653.8 7852.0 2144.6 2170.0 2170.5 2173.1 2175.0 2213.6 2229.0 2242.7 2245.1 2245.9 1914.4 2117.4 2183.8 2188.1 2212.5 2192.6 2203.2 2213.7 2213.8 2231.0 2144.1 2162.1 2162.7 2170.7 2176.8 7948.6 8001.7 8002.5 8007.4 8007.8 4528.0 4653.0 4653.9 4656.1 4656.9 2863.3 2892.1 2986.5 3000.8 3001.5 888.2 895.2 904.9 907.2 914.6 10662.1 10768.6 10791.3 10791.9 10799.3 3966.0 4108.1 4248.4 4337.5 4344.1 9067.8 9103.0 9125.4 9138.5 9149.5 4530.3 4536.6 4537.1 4545.8 4546.4 4037.7 4072.1 4078.4 4086.4 4199.2 3652.7 3677.9 3704.6 3755.8 3772.6 1006.5 1163.6 1180.3 1199.5 1218.1 709.0 756.8 765.4 777.9 790.1 4683.9 4688.2 4814.1 4896.7 4948.4 844.3 859.9 871.4 873.2 880.4 3172.1 3307.6 3328.5 3343.4 3419.1 9510.5 9840.3 9981.6 9989.2 9999.6 9815.8 9825.0 9828.5 9833.2 9877.0 6386.5 7145.9 7371.5 7410.6 7462.3 5378.5 5391.3 5448.2 5466.3 5608.9 9048.1 9187.4 9218.7 9232.1 9250.5 8638.6 8929.0 8949.5 9138.7 9149.4 6838.4 6970.5 6992.8 6993.5 6994.7 7366.4 7418.8 7444.7 7512.6 7526.9 5965.0 6113.3 6677.3 6803.5 6965.7 6731.3 6806.2 6808.4 6888.9 6905.7 6028.2 6087.8 6094.6 6098.1 6126.0 4129.5 4181.9 4249.8 4289.6 4303.4 4329.7 4357.3 4373.2 4486.5 4510.2 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#4] prototype.html Cycles:180 Average:4171.76 Median:4818.38 stddev:1207.68 (25.1%) stddev-sans-first:1175.39 18:49:16 INFO - PROCESS | 3363 | Values: 289.5 292.9 293.8 297.0 299.0 4829.9 4863.9 4868.3 4874.3 4888.0 4799.6 4800.4 4811.7 4812.2 4814.3 3589.8 3689.3 3695.3 3781.5 3786.7 3723.3 3763.6 3770.7 3777.4 3779.3 3579.3 3742.6 3747.6 3753.4 3769.4 3621.2 3743.5 3751.3 3755.0 3763.2 3295.6 3296.3 3301.2 3304.9 3309.1 4717.0 4717.2 4722.9 4724.7 4730.4 4134.6 4228.4 4248.7 4256.3 4283.2 4979.7 5008.1 5011.4 5017.2 5020.0 3563.6 3573.3 3580.0 3581.5 3591.9 4999.4 5203.7 5210.3 5234.4 5234.5 3656.4 3672.6 3673.1 3681.5 3682.9 3706.0 3712.6 3719.7 3720.1 3728.9 4221.3 4224.7 4226.8 4233.2 4234.4 4822.4 5017.8 5022.9 5023.8 5046.0 1281.8 1288.0 1296.2 1298.2 1300.4 835.0 836.4 863.8 902.2 904.9 4350.9 4367.6 4383.4 4411.1 4427.8 5132.4 5141.7 5156.5 5173.6 5176.2 4243.7 4363.9 4370.3 4384.5 4388.7 2714.3 2730.1 2736.4 2737.4 2746.8 4855.7 4856.5 4860.4 4862.5 4874.2 4994.9 5209.2 5248.4 5249.4 5249.5 4831.6 4837.4 4853.4 4858.5 4874.3 5085.3 5112.2 5113.2 5114.9 5115.3 4880.8 5046.9 5063.9 5157.2 5159.3 4932.1 4947.8 4950.9 4975.3 4978.2 3695.6 3696.9 3718.3 3727.5 3737.2 5075.8 5203.9 5209.0 5219.8 5230.0 5021.4 5026.4 5058.4 5069.3 5070.2 5023.9 5042.6 5047.4 5067.0 5067.4 4830.3 4938.4 4939.8 4949.7 4988.7 4813.2 4895.3 5049.0 5050.9 5059.0 4933.7 4943.0 4957.5 4965.1 4977.4 18:49:16 INFO - PROCESS | 3363 | 18:49:16 INFO - PROCESS | 3363 | [#5] yui.html Cycles:180 Average:17737.78 Median:24630.96 stddev:25140.85 (102.1%) stddev-sans-first:25178.49 18:49:16 INFO - PROCESS | 3363 | Values: 612.6 635.6 645.3 699.9 702.5 11903.1 12787.0 12825.1 12827.0 12839.7 11698.6 11908.8 12112.2 12141.4 12149.0 629.9 648.3 767.6 774.5 795.4 785.6 787.6 789.8 790.8 792.6 838.3 838.5 838.8 839.2 842.9 642.4 807.0 825.7 830.9 833.1 606.1 607.7 608.4 609.4 609.8 12074.1 12119.1 12122.0 12124.4 12129.8 12178.7 12532.5 12563.4 12768.2 12977.4 11558.2 11610.7 11670.4 11737.2 11905.3 3856.7 3903.8 3909.4 3950.3 4064.5 59616.0 59721.4 59836.0 59843.7 60089.7 9227.6 9599.1 9606.6 9804.6 10093.4 17890.1 17893.0 17931.6 17933.5 18386.1 6792.6 6835.2 6857.7 6886.7 6898.1 3686.4 3710.2 3734.8 3792.9 3818.4 2692.9 2760.5 2778.5 2786.7 2787.2 472.3 480.1 488.7 490.2 491.6 534.2 546.5 564.2 564.5 566.0 7252.6 7419.3 7491.3 7548.8 7868.9 432.9 494.6 502.7 525.1 575.2 3494.7 3576.4 3610.4 3714.3 3720.3 93270.0 97620.5 98791.0 99906.5 100497.0 59065.7 69504.7 70505.3 83270.6 83299.0 46814.5 79809.0 80441.0 81955.6 83875.0 8970.9 8972.7 9069.5 9124.8 9134.3 47854.0 48303.0 48622.0 48773.3 49489.5 63247.7 66486.7 71936.6 72136.0 72429.0 11889.1 11961.9 12081.2 12335.6 12498.1 12618.8 13493.7 13567.9 13900.2 13941.9 7977.7 12072.7 12569.6 12723.3 12756.5 11888.2 12233.3 12484.0 12698.2 13081.1 12091.5 12566.4 12653.0 12687.9 12804.3 9796.6 9904.5 10491.6 10694.9 10737.3 9776.1 10042.5 10607.9 10631.6 10665.4 18:49:16 INFO - PROCESS | 3363 | -------- Summary: end -------- 18:49:16 INFO - PROCESS | 3363 | 18:49:17 INFO - TEST-INFO | 3363: exit 0 18:49:17 INFO - TEST-OK | dromaeo_css | took 1120805ms 18:49:17 INFO - TEST-START | kraken 18:49:17 INFO - operating with platform_type : linux_ 18:49:17 INFO - Initialising browser for kraken test... 18:49:17 INFO - TEST-INFO | started process 3551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfRGE4R/profile http://localhost:51120/getInfo.html) 18:49:21 INFO - PROCESS | 3551 | __metrics Screen width/height:1600/1200 18:49:21 INFO - PROCESS | 3551 | colorDepth:24 18:49:21 INFO - PROCESS | 3551 | Browser inner width/height: 1024/697 18:49:21 INFO - PROCESS | 3551 | __metrics 18:49:22 INFO - PROCESS | 3551 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:22 INFO - TEST-INFO | 3551: exit 0 18:49:22 INFO - Browser initialized. 18:49:22 INFO - Running cycle 1/1 for kraken test... 18:49:22 INFO - TEST-INFO | started process 3633 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfRGE4R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:34 INFO - PROCESS | 3633 | RSS: Main: 158900224 18:49:34 INFO - PROCESS | 3633 | 18:49:37 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51120/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:38 INFO - PROCESS | 3633 | RSS: Main: 216993792 18:49:38 INFO - PROCESS | 3633 | 18:49:41 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51120/tests/kraken/driver.html?testName=audio-dft) 18:49:41 INFO - PROCESS | 3633 | RSS: Main: 205393920 18:49:41 INFO - PROCESS | 3633 | 18:49:45 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51120/tests/kraken/driver.html?testName=audio-fft) 18:49:45 INFO - PROCESS | 3633 | RSS: Main: 271175680 18:49:45 INFO - PROCESS | 3633 | 18:49:47 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51120/tests/kraken/driver.html?testName=audio-oscillator) 18:49:48 INFO - PROCESS | 3633 | RSS: Main: 181510144 18:49:48 INFO - PROCESS | 3633 | 18:49:50 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51120/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:51 INFO - PROCESS | 3633 | RSS: Main: 186048512 18:49:51 INFO - PROCESS | 3633 | 18:49:54 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51120/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:55 INFO - PROCESS | 3633 | RSS: Main: 222097408 18:49:55 INFO - PROCESS | 3633 | 18:49:58 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51120/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:59 INFO - PROCESS | 3633 | RSS: Main: 218071040 18:49:59 INFO - PROCESS | 3633 | 18:50:02 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51120/tests/kraken/driver.html?testName=json-parse-financial) 18:50:02 INFO - PROCESS | 3633 | RSS: Main: 220254208 18:50:02 INFO - PROCESS | 3633 | 18:50:04 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51120/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:04 INFO - PROCESS | 3633 | RSS: Main: 206565376 18:50:04 INFO - PROCESS | 3633 | 18:50:06 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:07 INFO - PROCESS | 3633 | RSS: Main: 212094976 18:50:07 INFO - PROCESS | 3633 | 18:50:09 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:10 INFO - PROCESS | 3633 | RSS: Main: 209559552 18:50:10 INFO - PROCESS | 3633 | 18:50:13 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:13 INFO - PROCESS | 3633 | RSS: Main: 224116736 18:50:13 INFO - PROCESS | 3633 | 18:50:16 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:17 INFO - PROCESS | 3633 | RSS: Main: 223793152 18:50:17 INFO - PROCESS | 3633 | 18:50:18 INFO - PROCESS | 3633 | Cycle 1(1): loaded http://localhost:51120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51120/tests/kraken/driver.html?testName=ai-astar) 18:50:19 INFO - PROCESS | 3633 | RSS: Main: 265990144 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | __start_tp_report 18:50:19 INFO - PROCESS | 3633 | _x_x_mozilla_page_load 18:50:19 INFO - PROCESS | 3633 | _x_x_mozilla_page_load_details 18:50:19 INFO - PROCESS | 3633 | |i|pagename|runs| 18:50:19 INFO - PROCESS | 3633 | |0;ai-astar;103;114;113;112;113;113;111;113;112;113 18:50:19 INFO - PROCESS | 3633 | |1;audio-beat-detection;154;122;128;186;127;122;151;120;164;121 18:50:19 INFO - PROCESS | 3633 | |2;audio-dft;161;160;159;161;162;160;161;161;161;161 18:50:19 INFO - PROCESS | 3633 | |3;audio-fft;75;77;76;120;77;76;75;75;106;75 18:50:19 INFO - PROCESS | 3633 | |4;audio-oscillator;86;85;128;85;86;87;128;85;85;128 18:50:19 INFO - PROCESS | 3633 | |5;imaging-gaussian-blur;117;117;117;116;117;118;118;117;117;117 18:50:19 INFO - PROCESS | 3633 | |6;imaging-darkroom;146;147;148;145;146;146;147;146;146;146 18:50:19 INFO - PROCESS | 3633 | |7;imaging-desaturate;95;95;95;95;95;96;95;94;94;94 18:50:19 INFO - PROCESS | 3633 | |8;json-parse-financial;62;61;63;63;62;64;63;63;62;63 18:50:19 INFO - PROCESS | 3633 | |9;json-stringify-tinderbox;66;65;64;65;64;64;64;66;64;73 18:50:19 INFO - PROCESS | 3633 | |10;stanford-crypto-aes;84;79;79;79;85;78;78;78;78;81 18:50:19 INFO - PROCESS | 3633 | |11;stanford-crypto-ccm;131;131;136;129;130;129;128;128;168;133 18:50:19 INFO - PROCESS | 3633 | |12;stanford-crypto-pbkdf2;166;164;165;175;166;166;165;166;163;160 18:50:19 INFO - PROCESS | 3633 | |13;stanford-crypto-sha256-iterative;67;67;70;66;67;67;69;65;66;67 18:50:19 INFO - PROCESS | 3633 | __end_tp_report 18:50:19 INFO - PROCESS | 3633 | __start_cc_report 18:50:19 INFO - PROCESS | 3633 | _x_x_mozilla_cycle_collect,1875 18:50:19 INFO - PROCESS | 3633 | __end_cc_report 18:50:19 INFO - PROCESS | 3633 | __startTimestamp1467769819131__endTimestamp 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | ------- Summary: start ------- 18:50:19 INFO - PROCESS | 3633 | Number of tests: 14 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.16 (2.8%) stddev-sans-first:0.87 18:50:19 INFO - PROCESS | 3633 | Values: 103.0 114.0 113.0 112.0 113.0 113.0 111.0 113.0 112.0 113.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:139.50 stddev:22.92 (16.4%) stddev-sans-first:23.70 18:50:19 INFO - PROCESS | 3633 | Values: 154.0 122.0 128.0 186.0 127.0 122.0 151.0 120.0 164.0 121.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 18:50:19 INFO - PROCESS | 3633 | Values: 161.0 160.0 159.0 161.0 162.0 160.0 161.0 161.0 161.0 161.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.50 stddev:16.07 (21.3%) stddev-sans-first:16.77 18:50:19 INFO - PROCESS | 3633 | Values: 75.0 77.0 76.0 120.0 77.0 76.0 75.0 75.0 106.0 75.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:85.00 stddev:20.51 (24.1%) stddev-sans-first:21.26 18:50:19 INFO - PROCESS | 3633 | Values: 86.0 85.0 128.0 85.0 86.0 87.0 128.0 85.0 85.0 128.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:50:19 INFO - PROCESS | 3633 | Values: 117.0 117.0 117.0 116.0 117.0 118.0 118.0 117.0 117.0 117.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 18:50:19 INFO - PROCESS | 3633 | Values: 146.0 147.0 148.0 145.0 146.0 146.0 147.0 146.0 146.0 146.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 18:50:19 INFO - PROCESS | 3633 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 94.0 94.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 18:50:19 INFO - PROCESS | 3633 | Values: 62.0 61.0 63.0 63.0 62.0 64.0 63.0 63.0 62.0 63.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.00 stddev:2.76 (4.2%) stddev-sans-first:2.92 18:50:19 INFO - PROCESS | 3633 | Values: 66.0 65.0 64.0 65.0 64.0 64.0 64.0 66.0 64.0 73.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.00 stddev:2.60 (3.3%) stddev-sans-first:2.30 18:50:19 INFO - PROCESS | 3633 | Values: 84.0 79.0 79.0 79.0 85.0 78.0 78.0 78.0 78.0 81.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:131.00 stddev:12.09 (9.2%) stddev-sans-first:12.77 18:50:19 INFO - PROCESS | 3633 | Values: 131.0 131.0 136.0 129.0 130.0 129.0 128.0 128.0 168.0 133.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.00 stddev:3.81 (2.3%) stddev-sans-first:4.03 18:50:19 INFO - PROCESS | 3633 | Values: 166.0 164.0 165.0 175.0 166.0 166.0 165.0 166.0 163.0 160.0 18:50:19 INFO - PROCESS | 3633 | 18:50:19 INFO - PROCESS | 3633 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.45 (2.2%) stddev-sans-first:1.54 18:50:19 INFO - PROCESS | 3633 | Values: 67.0 67.0 70.0 66.0 67.0 67.0 69.0 65.0 66.0 67.0 18:50:19 INFO - PROCESS | 3633 | -------- Summary: end -------- 18:50:19 INFO - PROCESS | 3633 | 18:50:20 INFO - TEST-INFO | 3633: exit 0 18:50:20 INFO - TEST-OK | kraken | took 63020ms 18:50:20 INFO - SUITE-END | took 1183s 18:50:20 INFO - Completed test suite (00:19:43) 18:50:20 INFO - javascript benchmark 18:50:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2642.088479474581, 2651.920442386729, 2683.020697445432, 2692.959605605915, 2695.0702007807313, 4701.50634183411, 4751.38232029836, 4777.426708794941, 4787.114383838988, 4860.708357498553, 4694.530546945304, 4765.952340476594, 4769.928451073237, 4781.306710526971, 4789.066132104233, 4321.82712691492, 4344.261475549149, 4400.44994375703, 4432.578905004019, 4518.774061296938, 4448.510663826976, 4473.642108631302, 4481.932771008437, 4508.661850361236, 4535.614472769818, 4226.513950895655, 4256.553061928484, 4264.530901600821, 4344.196323680129, 4384.736915785031, 4390.714603550759, 4393.7363758174615, 4398.34024896265, 4403.0, 4485.708428952108, 4423.911521769579, 4429.247028005247, 4435.046465010038, 4472.955270447286, 4474.948387129022, 4706.0, 4741.075490279397, 4822.252550854623, 4830.830920917751, 4883.682560633583, 5103.770330335144, 5134.614903882224, 5185.766640501149, 5186.5850731941355, 5189.714565698885, 5165.793368265265, 5189.558887494568, 5195.6622819516615, 5225.895482090375, 5234.26720259164, 4728.929066064012, 4751.40607424072, 4790.856274311706, 4839.080574690797, 4855.271709243606, 5280.894382112337, 5290.0, 5318.468153184728, 5326.813561525328, 5339.626226164131, 5034.244863270539, 5040.974795126074, 5041.395032596112, 5072.568831649277, 5096.821611243588, 5049.318342023853, 5091.465396133427, 5143.12566863634, 5171.637985341065, 5176.4305926348825, 5066.670666406671, 5090.287359769635, 5092.592592592584, 5101.770420331094, 5181.896362072737, 5114.130597798383, 5118.0, 5120.48795120485, 5130.102232109366, 5134.512221338967, 4220.430241917302, 4244.427002354704, 4255.212785634668, 4286.54991225923, 4297.806598703066, 3783.4703141560203, 3807.8667246646232, 3811.8094095295123, 3830.0999265172663, 3832.9808350958065, 4337.154254920323, 4376.387305777193, 4379.430674012358, 4388.429504164438, 4407.074514351958, 5021.397432308147, 5136.178211486144, 5143.202803565453, 5146.742662866916, 5205.6616319939085, 4267.231898258343, 4375.299952007664, 4376.081022985145, 4379.58393952574, 4439.356293337512, 4515.932261016087, 4559.01981074064, 4581.93127103087, 4626.282926146453, 4648.465426475931, 4862.635302352267, 4880.902381952341, 4886.560209581155, 4889.339939108318, 4930.9506904930495, 5110.335656364798, 5113.105206588906, 5138.07514647367, 5173.32746742921, 5197.22041693749, 4732.550407711262, 4745.738984355894, 4748.691335063209, 4788.0, 4803.5916947059895, 4962.556613503466, 4990.775415106402, 4993.950060499494, 5002.9994001199175, 5040.344755181803, 5039.21892106724, 5065.544101030925, 5079.5428411443145, 5080.618953578571, 5129.179331306973, 4906.239532872411, 4960.70235785854, 4979.452260251442, 4986.052649996489, 4991.900161996812, 4939.357883475125, 4971.825986090615, 4976.800927962841, 4978.477259887804, 5026.899462010666, 4771.06964141989, 4856.320115183807, 4899.16714158601, 4910.631702622389, 4964.205727083649, 5029.9245511318095, 5041.0422019816115, 5051.116054690341, 5053.7473126344275, 5104.923426148536, 4920.803167873388, 4948.158813001726, 4949.802007919787, 4962.404511458648, 5011.273365361929], "value": 4700.432854242678, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.9745303341047, 570.3213176320179, 579.6000759475963, 583.7460704593501, 596.1742985964439, 39757.80121099394, 40142.59857401425, 41069.589304106914, 41237.175256494855, 44265.33601995964, 40452.0, 40821.183576328454, 41046.79476602613, 41108.794456027754, 41110.0, 7510.887336689954, 7730.18833022533, 7740.458167928247, 7852.528848269108, 8013.158618345048, 7740.922590774105, 10157.339772914736, 10440.530176142054, 10455.111315538188, 10756.515956781925, 10500.422476763775, 10549.15606751462, 10562.78874422511, 10566.524506397192, 10569.947150264237, 7575.204603516607, 10100.797984040279, 10333.121684656813, 10375.584976600927, 10386.013328733796, 10010.54952527138, 10356.119729822974, 10356.896431035668, 10360.533775980099, 10373.222008349405, 39930.0, 40355.59644403548, 40361.39457908134, 40388.0, 40408.0, 3081.7380522655603, 3168.984155079233, 3198.184462961953, 3373.1398493384277, 3530.0998245447245, 29260.707392926222, 29505.409891802257, 29568.260793480335, 29926.95255666063, 30195.396092078256, 3322.734181265493, 3342.398368293706, 3350.7821991570713, 3360.8487618057, 3437.74216933731, 254353.0, 254672.0, 255037.0, 255242.0, 255670.72164639243, 31174.376512469622, 32512.674873250966, 52371.214431783555, 53146.0, 53189.468105318454, 33058.83470582632, 33217.66782332194, 33257.83371083129, 33280.334393312, 33288.50067248993, 12755.553555625509, 12836.101472896808, 12865.871341286655, 12866.806997895039, 12874.742505150032, 9260.259179265642, 10629.84055239172, 10655.68032958997, 10709.0, 10890.0, 7828.960855195687, 7843.960780196063, 7871.055473343121, 7922.564258965697, 7925.128235894047, 3030.272734543694, 3038.346755447589, 3076.7384772294604, 3084.1364417963005, 3092.505199168122, 832.4797001873828, 902.0843843498853, 943.2265542255285, 958.8993155718689, 1008.6167256442504, 26994.32514187161, 28491.857540712164, 28523.286917826805, 29227.707722922925, 29272.85363573211, 927.1470247372433, 997.6209040564539, 1001.399160503692, 1024.636254129788, 1053.8419237114495, 14275.571732848031, 15047.247637618075, 15062.0, 15081.170535620427, 15320.310586023434, 397420.0, 399030.0, 402146.0, 402238.0, 403070.0, 51038.234426483636, 51094.48905510972, 51098.0, 51133.7443312781, 51543.0, 1034.5809946971488, 1098.5111625326654, 1137.4028634966708, 1150.1603829204728, 1195.2768575012167, 27134.592981105696, 29905.0, 29912.401751965273, 30487.695123049372, 30623.2344191397, 43429.56570434255, 43984.780076099414, 44077.33883991807, 44147.0, 44189.77905110454, 309073.0, 310734.0, 310890.0, 311317.0, 312137.0, 24763.25710228696, 25726.485470290114, 25732.485350292514, 25749.742502574736, 25771.613425799, 42862.57137428586, 42926.35610465783, 43477.0, 44228.77885610551, 44470.777646111565, 37295.81352093222, 37378.813105935384, 37739.81130094442, 37870.0, 38276.0, 37040.25919481649, 37495.81252093722, 37509.43735844019, 40285.1942961145, 42049.579504204565, 36928.630713693594, 37163.81418092892, 37659.0, 37693.81153094217, 38120.80939595284, 32525.34949301048, 33114.66885331116, 34399.82800085984, 34478.13804654803, 34483.310333793685, 25677.614835777105, 25854.224373268833, 26065.696715164544, 26072.0, 26087.956481741276], "value": 17118.241763219085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.86879920029986, 371.9944200836987, 376.17429741716944, 379.8081968605854, 393.1154901471689, 13180.868191318084, 16595.917020414898, 16903.408380706678, 18209.18058687359, 18231.54421139469, 16523.752143717855, 16649.084300363476, 17494.562635934108, 17499.475015749547, 17511.562210944732, 5037.823676171335, 5058.342415485991, 5205.687658740488, 5212.556932660709, 5216.713080780555, 3735.775853448788, 5168.689878607277, 5181.4300426953005, 5893.764249430018, 6234.314225435199, 6243.094751261065, 6268.185135932323, 6271.310155882849, 6282.937170628281, 6293.339199384068, 5979.402059794029, 6226.750929962796, 6240.063990401431, 6240.375962403768, 6241.500679945593, 3424.845881935319, 3593.766405183681, 3600.3339382214376, 5462.617616766828, 5470.316210473691, 19461.708074378897, 20956.580868382545, 21374.893125534272, 22437.214697485673, 22579.435514112116, 2018.1927229108328, 2107.662773956175, 2310.1567927706433, 2351.106579499779, 2381.4403615150427, 12747.235165890077, 13590.456381744718, 13755.656108597264, 13774.0, 13913.930430347884, 1691.1121661127884, 1762.9118544072744, 1788.2668106076576, 1819.9090045497671, 1845.2987864611362, 65098.67450662716, 65887.3411265886, 66011.66994164999, 67069.66465167691, 68839.0, 9077.727668169966, 9706.174975127193, 9787.461689606998, 9939.204863610894, 10397.428141452141, 12410.937945310396, 13276.668083297802, 13288.136271142344, 13557.25435101079, 13610.455581776816, 5720.998825205573, 6917.654117294115, 6979.092717946634, 6997.615131167835, 7076.150861896503, 6413.16628838248, 6446.645434501053, 6493.350664933469, 6587.53887227899, 6633.336666333425, 4403.625691816167, 4446.688731788744, 4446.866594002185, 4456.576625220664, 4457.4205353304515, 1786.2944137066047, 1786.7051936430603, 1836.8875792838344, 1840.3098837935775, 1869.8878067316004, 858.2404571953866, 894.879191309165, 939.7509659940122, 956.3496822160997, 962.0998527907327, 17384.217710202818, 17434.12829358527, 17789.0, 18153.18310676023, 18576.0, 853.5774791478258, 858.2618947704968, 865.3812524045212, 870.5516658920661, 875.916787905148, 9691.903080969241, 10048.698539043695, 10085.445300508396, 10113.747156321004, 10157.644482443078, 350938.6795990311, 358560.0, 363094.0, 363783.0, 363835.0, 44032.0, 44226.55773442289, 80705.0, 84097.0, 84865.15134848696, 872.3326655108847, 877.9209871111631, 885.5218182181678, 885.7918389178537, 897.1297841094129, 15791.36834526606, 16038.358465661242, 16254.674906501567, 16317.0, 16342.754858676892, 26969.0, 38610.227795444494, 39698.0, 40278.798606006785, 41446.1710765789, 49311.0, 50110.749446252536, 52084.0, 56372.0, 56797.0, 7617.542947423172, 7965.084015338311, 8010.279074883289, 8023.799405014921, 8341.708040218806, 11990.0, 12243.20419172751, 12253.877461225273, 12695.42870570808, 12788.616341509769, 10912.78174436491, 10923.508442119964, 10927.83608245861, 11132.721681958015, 11141.275817071864, 10562.788744225227, 10724.89275107239, 10756.51595678212, 10760.3543787373, 10961.890381096406, 10489.632862849763, 10616.734581635521, 10653.254272201182, 10727.892721073002, 11060.17048721333, 6817.216020157745, 6881.587104773729, 7887.526748395115, 8009.839803203787, 8011.038675358995, 9856.655017074369, 10724.0, 10865.619703310345, 10910.454477276264, 10958.01377875963], "value": 8375.12863251311, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.302348825587, 1425.0879437160218, 1507.2539093148894, 1701.5150682055616, 1720.6816738903303, 7566.092068951727, 7981.561014144219, 8092.635831387587, 8103.149169337215, 8106.2299081587225, 6736.932630673692, 7430.256974302581, 7625.8474830503355, 7653.770386888388, 7851.960740196305, 2144.6461333879893, 2169.9566008679817, 2170.5116348821466, 2173.0655817998254, 2175.0321107107343, 2213.612617791888, 2229.0, 2242.7308722953267, 2245.057076028072, 2245.8989345479413, 1914.3586898389055, 2117.4388786971463, 2183.759786423492, 2188.102877820094, 2212.4800671842104, 2192.6382146945734, 2203.1627981367137, 2213.690083388327, 2213.8339624528226, 2230.9776902231097, 2144.142343062772, 2162.1243396424316, 2162.697222388867, 2170.7395112586432, 2176.7714389988982, 7948.642311096015, 8001.719939802138, 8002.519848809032, 8007.359411247086, 8007.759767206993, 4527.954720452786, 4653.046125544255, 4653.930191047137, 4656.068786242765, 4656.906861862778, 2863.2984918694847, 2892.146816689082, 2986.5072263076568, 3000.819950802937, 3001.519756838917, 888.1873086126187, 895.2077411490877, 904.8552231642972, 907.2152588011388, 914.6021480655936, 10662.1470282378, 10768.569257229701, 10791.298565593133, 10791.946040269828, 10799.298045627087, 3966.0, 4108.137291168888, 4248.362745588187, 4337.457817772778, 4344.109457561192, 9067.81864362709, 9103.0, 9125.36122471434, 9138.497382644018, 9149.451032938045, 4530.252508336153, 4536.591706746409, 4537.0925814837165, 4545.840895568639, 4546.431696037996, 4037.717359784787, 4072.144849581621, 4078.388241763759, 4086.366613174963, 4199.223143718422, 3652.7260455465416, 3677.889663310105, 3704.6110158432975, 3755.774653520743, 3772.6227377262603, 1006.4766321512918, 1163.5578480177478, 1180.3213152437384, 1199.4782269712703, 1218.0742635596832, 708.9858202835915, 756.8334966307403, 765.4182821055927, 777.8522080804629, 790.1348023913805, 4683.906321873544, 4688.249880019181, 4814.085323788469, 4896.681715688469, 4948.38145231846, 844.3371953016853, 859.8538248497771, 871.4030888841164, 873.2359185712502, 880.4409200157818, 3172.1435212492497, 3307.570015897966, 3328.4674452088034, 3343.36476069546, 3419.1110311319235, 9510.476923769122, 9840.311178217456, 9981.600735970625, 9989.200863930868, 9999.600015999424, 9815.803683926282, 9824.95087524572, 9828.459434731016, 9833.213342932548, 9877.0, 6386.521010924199, 7145.857082858313, 7371.520851144658, 7410.592417417094, 7462.291082347168, 5378.515933565841, 5391.299131113087, 5448.155535891943, 5466.316710411199, 5608.915866262091, 9048.095190480899, 9187.44875307457, 9218.677346292847, 9232.076792320715, 9250.4912229828, 8638.611262493077, 8928.955355223183, 8949.463032218087, 9138.68014619485, 9149.359544831797, 6838.350356716104, 6970.477214208853, 6992.79021629352, 6993.510454268152, 6994.720211191496, 7366.410687145123, 7418.814529636802, 7444.73943411978, 7512.586807725409, 7526.924730752622, 5964.97017514927, 6113.2969708482315, 6677.29888361715, 6803.489738269551, 6965.7213711453005, 6731.259561448139, 6806.217285012287, 6808.353206445379, 6888.931110688829, 6905.689243983932, 6028.246469191351, 6087.786927457518, 6094.634321940698, 6098.146259523759, 6125.9693701531205, 4129.525104612888, 4181.853635122756, 4249.830006799817, 4289.592488713567, 4303.440552728125, 4329.6969212154845, 4357.281048626941, 4373.190959672471, 4486.461624605068, 4510.210713125124], "value": 4110.229858046744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.47460359447604, 292.9223755704739, 293.77232644700354, 296.9881204751809, 298.96561895382035, 4829.8792530186765, 4863.878403039926, 4868.269759536071, 4874.317595536628, 4888.0, 4799.560131960416, 4800.399950006249, 4811.711297322163, 4812.181929072058, 4814.325994360793, 3589.820508974554, 3689.317476266886, 3695.334839728848, 3781.468505255376, 3786.6970642348683, 3723.3484140275477, 3763.56718977317, 3770.660640542351, 3777.376732839078, 3779.263043706478, 3579.266250418658, 3742.6257374262627, 3747.5877653458097, 3753.3806921858, 3769.4157405602036, 3621.1852333224947, 3743.47591337213, 3751.3060083884297, 3755.0236938396215, 3763.2097259575658, 3295.555100061509, 3296.258341873059, 3301.224212310105, 3304.884329048472, 3309.1065412338776, 4717.0, 4717.245240761462, 4722.929156062662, 4724.71651700899, 4730.432348118248, 4134.627883490501, 4228.365745138223, 4248.723832950848, 4256.34026725858, 4283.186194623012, 4979.676321039121, 5008.12357837377, 5011.398632164127, 5017.197248440269, 5020.0, 3563.572371315433, 3573.285342931398, 3579.997600671816, 3581.4627805829073, 3591.9403775886176, 4999.4250661174065, 5203.7398130093725, 5210.270562121306, 5234.377343023675, 5234.476552344735, 3656.3784156693428, 3672.5776535698737, 3673.1368128489785, 3681.4661874028116, 3682.9447558286647, 3705.962940370616, 3712.5730540987593, 3719.683826874692, 3720.1071742781537, 3728.9440658390145, 4221.324588065895, 4224.683148763855, 4226.830926762957, 4233.216854881856, 4234.449521562177, 4822.432709369469, 5017.774200161001, 5022.910909790453, 5023.773930173151, 5045.974770126126, 1281.769281529315, 1287.9806802897965, 1296.2287438973794, 1298.1821452484874, 1300.3563236198142, 835.0188528479013, 836.4186890111436, 863.792689754467, 902.2105657549644, 904.8733177355175, 4350.891227719332, 4367.563243675607, 4383.364412160283, 4411.073674528385, 4427.756473393994, 5132.384113906281, 5141.717205553656, 5156.458571849977, 5173.63784535079, 5176.197689358156, 4243.681723870722, 4363.890902727458, 4370.278903980871, 4384.4519435070615, 4388.692791504628, 2714.348556346463, 2730.1298610451904, 2736.3979924416594, 2737.438825040862, 2746.780257579389, 4855.708657480563, 4856.538628830255, 4860.441049279378, 4862.465128835825, 4874.2201247800185, 4994.850154495371, 5209.218617207449, 5248.396434410091, 5249.42256351801, 5249.527542521191, 4831.613470922318, 4837.419509658793, 4853.417589889165, 4858.46556878743, 4874.268859670936, 5085.288059671723, 5112.233165025276, 5113.233015047773, 5114.872128196825, 5115.335006449138, 4880.829170979141, 5046.949530504795, 5063.898722025612, 5157.200633901752, 5159.251908473176, 4932.087563800708, 4947.777350019186, 4950.9257361140335, 4975.278584605139, 4978.153713868579, 3695.5934847166254, 3696.8890933272046, 3718.330700473941, 3727.453106960581, 3737.1965027519213, 5075.771590278372, 5203.869903252449, 5209.0, 5219.765110569958, 5230.045024561111, 5021.422536408214, 5026.396832380138, 5058.367704036996, 5069.264956581349, 5070.2141168119, 5023.924641130459, 5042.596592272683, 5047.444781074006, 5067.0, 5067.442581316126, 4830.275458681226, 4938.432080310667, 4939.827106051271, 4949.7030178189425, 4988.700677959189, 4813.229883218808, 4895.314655948241, 5048.974755126201, 5050.898982020412, 5059.0, 4933.679310844784, 4943.0, 4957.4794646561595, 4965.106280869478, 4977.382350735953], "value": 3788.116819386225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.5803824380303, 635.5773410681894, 645.3191882563895, 699.8740226759185, 702.5222848463045, 11903.107266954981, 12787.0, 12825.102242843004, 12827.0, 12839.679008024805, 11698.649040528777, 11908.82136767947, 12112.212706174092, 12141.392930353491, 12149.0, 629.9118123462696, 648.2609824799708, 767.6315368623065, 774.4772278711861, 795.411395567279, 785.6071964017991, 787.5943888897194, 789.763071078677, 790.7785819970388, 792.6036981509245, 838.3125836813815, 838.5094719589017, 838.7944953486405, 839.2237180607913, 842.8609279468881, 642.4250295985067, 807.0476837331946, 825.6779855856221, 830.8753686946945, 833.071125694852, 606.0787602843697, 607.6748939317444, 608.3581821178655, 609.439315829438, 609.8383928259015, 12074.094442916728, 12119.091068169835, 12122.030237580972, 12124.393780311037, 12129.818052729217, 12178.695532611668, 12532.51194392719, 12563.371831408393, 12768.170068945581, 12977.35113244334, 11558.19092663514, 11610.709732256677, 11670.416479176007, 11737.237079589884, 11905.285682859056, 3856.652901238902, 3903.843846246175, 3909.3745000799736, 3950.348192548226, 4064.512258528966, 59616.0, 59721.402785972015, 59836.0, 59843.70078149625, 60089.699551501966, 9227.630894764134, 9599.136077753035, 9606.567704453317, 9804.607815687436, 10093.444860532594, 17890.105494725212, 17893.0, 17931.551711207063, 17933.461996140137, 18386.08069596515, 6792.558483698544, 6835.179778426521, 6857.691403886837, 6886.657101865088, 6898.103246578014, 3686.4101743720876, 3710.220853620716, 3734.7572407793405, 3792.943105853414, 3818.4081467372603, 2692.892284308645, 2760.5307097793425, 2778.4998700233746, 2786.679531853863, 2787.205646390769, 472.29156265601597, 480.0950208856261, 488.66038103518423, 490.23278569039337, 491.5575981616502, 534.236042459287, 546.4535068232349, 564.2439131563642, 564.5060571999501, 565.966042037479, 7252.5648461092505, 7419.332260096618, 7491.325780679765, 7548.811279718051, 7868.859015442728, 432.88744926319384, 494.6092586856415, 502.70591703852926, 525.1151809201508, 575.1602660116193, 3494.7029502492574, 3576.445650924111, 3610.4403817408343, 3714.312852122366, 3720.2745464634136, 93270.0, 97620.51189744005, 98791.0, 99906.5004674972, 100497.0, 59065.70467147637, 69504.65247673729, 70505.29494704987, 83270.58364708138, 83299.0, 46814.531854681016, 79809.0, 80441.0, 81955.5902220497, 83875.0, 8970.86543701832, 8972.685955991508, 9069.455832650063, 9124.77188070277, 9134.314926380415, 47854.0, 48303.0, 48622.0, 48773.268400973306, 49489.50510494849, 63247.68376158274, 66486.66756666185, 71936.64031680017, 72136.0, 72429.0, 11889.108316876096, 11961.940190298992, 12081.214721043105, 12335.568255111028, 12498.12513124109, 12618.810717839424, 13493.662658433617, 13567.932160339134, 13900.235487048309, 13941.860581394461, 7977.680892764457, 12072.698182545506, 12569.560065397667, 12723.300218488072, 12756.48974041065, 11888.227265227733, 12233.3271670059, 12484.0, 12698.174618649758, 13081.149725267827, 12091.47890229319, 12566.434510446868, 12653.0, 12687.873121269038, 12804.295763733084, 9796.559154838193, 9904.50477476109, 10491.632792852213, 10694.89305106939, 10737.302075365076, 9776.113815050543, 10042.49787510636, 10607.94696026515, 10631.627893024017, 10665.41340226295], "value": 6542.352883111438, "unit": "score"}], "name": "dromaeo_css", "value": 6398.971395241726, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 112.0, 113.0, 113.0, 111.0, 113.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 128.0, 186.0, 127.0, 122.0, 151.0, 120.0, 164.0, 121.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 161.0, 162.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 120.0, 77.0, 76.0, 75.0, 75.0, 106.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 128.0, 85.0, 86.0, 87.0, 128.0, 85.0, 85.0, 128.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 63.0, 62.0, 64.0, 63.0, 63.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 66.0, 64.0, 73.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 79.0, 79.0, 85.0, 78.0, 78.0, 78.0, 78.0, 81.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 136.0, 129.0, 130.0, 129.0, 128.0, 128.0, 168.0, 133.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 165.0, 175.0, 166.0, 166.0, 165.0, 166.0, 163.0, 160.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 66.0, 67.0, 67.0, 69.0, 65.0, 66.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1526.5999999999997, "alertThreshold": 2.0}]} 18:50:20 INFO - Return code: 0 18:50:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:20 INFO - # TBPL SUCCESS # 18:50:20 INFO - Running post-action listener: _resource_record_post_action 18:50:20 INFO - ##### 18:50:20 INFO - ##### Finished run-tests step (success) 18:50:20 INFO - ##### 18:50:20 INFO - Running post-run listener: _resource_record_post_run 18:50:20 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8134656; Write bytes: 433635328; Read time: 2052; Write time: 308960 18:50:20 INFO - TinderboxPrint: CPU usage
13.0% 18:50:20 INFO - TinderboxPrint: I/O read bytes / time
8,134,656 / 2,052 18:50:20 INFO - TinderboxPrint: I/O write bytes / time
433,635,328 / 308,960 18:50:20 INFO - TinderboxPrint: CPU idle
8,342.7 (87.0%) 18:50:20 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 18:50:20 INFO - TinderboxPrint: Swap in / out
0 / 0 18:50:20 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13348864; Read time: 0; Write time: 1624 18:50:20 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 5947392; Write bytes: 413315072; Read time: 1628; Write time: 303844 18:50:21 INFO - Running post-run listener: _upload_blobber_files 18:50:21 INFO - Blob upload gear active. 18:50:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:21 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:50:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:50:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:22 INFO - (blobuploader) - INFO - Done attempting. 18:50:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:26 INFO - (blobuploader) - INFO - Done attempting. 18:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:27 INFO - (blobuploader) - INFO - Done attempting. 18:50:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:27 INFO - Return code: 0 18:50:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9a003abc6dbcffd4cc8cb4a086775c3a1fa0ec677a3d806b1f45c3e2464423378817b54fcd44bda0c8ffe7df34759ae0865ef18c7313bb1bee7048d94f72e79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c575afe71d39800bb08065ffeeb3d2992b5c182677effbce1f46d83c3e2aa8208f52a4a6beae2ef3c73e7095e0cc8d424ff22df45c165db8e2ec8ca60fafa53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11b89fc13cb852c8ceae849b2aa28373d160fb221ffbbc1a3ea5a8d35bc34868e8470231e938516bc0101da4817c64ee047d7de496916e30c67b2dcad5634d8"} 18:50:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:27 INFO - Contents: 18:50:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9a003abc6dbcffd4cc8cb4a086775c3a1fa0ec677a3d806b1f45c3e2464423378817b54fcd44bda0c8ffe7df34759ae0865ef18c7313bb1bee7048d94f72e79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c575afe71d39800bb08065ffeeb3d2992b5c182677effbce1f46d83c3e2aa8208f52a4a6beae2ef3c73e7095e0cc8d424ff22df45c165db8e2ec8ca60fafa53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11b89fc13cb852c8ceae849b2aa28373d160fb221ffbbc1a3ea5a8d35bc34868e8470231e938516bc0101da4817c64ee047d7de496916e30c67b2dcad5634d8"} 18:50:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:27 INFO - Copying logs to upload dir... 18:50:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.602900 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-07-05 18:50:27.231734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 18:50:27.235556) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9a003abc6dbcffd4cc8cb4a086775c3a1fa0ec677a3d806b1f45c3e2464423378817b54fcd44bda0c8ffe7df34759ae0865ef18c7313bb1bee7048d94f72e79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c575afe71d39800bb08065ffeeb3d2992b5c182677effbce1f46d83c3e2aa8208f52a4a6beae2ef3c73e7095e0cc8d424ff22df45c165db8e2ec8ca60fafa53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11b89fc13cb852c8ceae849b2aa28373d160fb221ffbbc1a3ea5a8d35bc34868e8470231e938516bc0101da4817c64ee047d7de496916e30c67b2dcad5634d8"} build_url:https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/EDT63JoTSB2CuJGAXF91Kw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9a003abc6dbcffd4cc8cb4a086775c3a1fa0ec677a3d806b1f45c3e2464423378817b54fcd44bda0c8ffe7df34759ae0865ef18c7313bb1bee7048d94f72e79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c575afe71d39800bb08065ffeeb3d2992b5c182677effbce1f46d83c3e2aa8208f52a4a6beae2ef3c73e7095e0cc8d424ff22df45c165db8e2ec8ca60fafa53e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11b89fc13cb852c8ceae849b2aa28373d160fb221ffbbc1a3ea5a8d35bc34868e8470231e938516bc0101da4817c64ee047d7de496916e30c67b2dcad5634d8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 18:50:27.260337) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:50:27.260653) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1467768567.216243-789501460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004401 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 18:50:27.345777) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 18:50:27.346092) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 18:50:27.346376) ========= ========= Total master_lag: 0.43 =========