builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1464224478.85 results: success (0) buildid: 20160525152143 builduid: 8654ccbbea454f27b451ee4808dca30e revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.845410) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.846177) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.846455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.876544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.876928) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 18:01:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2016-05-25 18:01:19 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082691 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.973396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:18.976768) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020147 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.026013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.026328) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 --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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 18:01:19,301 truncating revision to first 12 chars 2016-05-25 18:01:19,302 Setting DEBUG logging. 2016-05-25 18:01:19,302 attempt 1/10 2016-05-25 18:01:19,302 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4f7c3a2567f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 18:01:19,646 unpacking tar archive at: mozilla-aurora-4f7c3a2567f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.756422) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.759765) ========= script_repo_revision: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.760176) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.760452) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 18:01:19.769722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-25 18:01:19.770028) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:01:20 INFO - MultiFileLogger online at 20160525 18:01:20 in /builds/slave/test 18:01:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:01:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:20 INFO - {'append_to_log': False, 18:01:20 INFO - 'base_work_dir': '/builds/slave/test', 18:01:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:01:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:20 INFO - 'branch': 'Mozilla-Aurora', 18:01:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:20 INFO - 'config_files': ('talos/linux_config.py',), 18:01:20 INFO - 'default_actions': ('clobber', 18:01:20 INFO - 'read-buildbot-config', 18:01:20 INFO - 'download-and-extract', 18:01:20 INFO - 'populate-webroot', 18:01:20 INFO - 'create-virtualenv', 18:01:20 INFO - 'install', 18:01:20 INFO - 'run-tests'), 18:01:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:20 INFO - 'download_minidump_stackwalk': True, 18:01:20 INFO - 'download_symbols': 'ondemand', 18:01:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:20 INFO - 'installer_path': 'installer.exe', 18:01:20 INFO - 'log_level': 'info', 18:01:20 INFO - 'log_name': 'talos', 18:01:20 INFO - 'log_to_console': True, 18:01:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:01:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:01:20 INFO - 'opt_config_files': (), 18:01:20 INFO - 'pip_index': False, 18:01:20 INFO - 'sps_profile': False, 18:01:20 INFO - 'sps_profile_interval': 0, 18:01:20 INFO - 'suite': 'dromaeojs', 18:01:20 INFO - 'system_bits': '32', 18:01:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:20 INFO - 'title': 'talos-linux64-ix-022', 18:01:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:20 INFO - 'use_talos_json': True, 18:01:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:20 INFO - 'work_dir': 'build'} 18:01:20 INFO - ##### 18:01:20 INFO - ##### Running clobber step. 18:01:20 INFO - ##### 18:01:20 INFO - Running pre-action listener: _resource_record_pre_action 18:01:20 INFO - Running main action method: clobber 18:01:20 INFO - rmtree: /builds/slave/test/build 18:01:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:21 INFO - Running post-action listener: _resource_record_post_action 18:01:21 INFO - ##### 18:01:21 INFO - ##### Running read-buildbot-config step. 18:01:21 INFO - ##### 18:01:21 INFO - Running pre-action listener: _resource_record_pre_action 18:01:21 INFO - Running main action method: read_buildbot_config 18:01:21 INFO - Using buildbot properties: 18:01:21 INFO - { 18:01:21 INFO - "project": "", 18:01:21 INFO - "product": "firefox", 18:01:21 INFO - "script_repo_revision": "production", 18:01:21 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:01:21 INFO - "repository": "", 18:01:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:01:21 INFO - "buildid": "20160525152143", 18:01:21 INFO - "pgo_build": "True", 18:01:21 INFO - "basedir": "/builds/slave/test", 18:01:21 INFO - "buildnumber": 257, 18:01:21 INFO - "slavename": "talos-linux64-ix-022", 18:01:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:01:21 INFO - "platform": "ubuntu64_hw", 18:01:21 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:01:21 INFO - "branch": "mozilla-aurora", 18:01:21 INFO - "repo_path": "releases/mozilla-aurora", 18:01:21 INFO - "revision": "4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3", 18:01:21 INFO - "stage_platform": "linux64-pgo", 18:01:21 INFO - "builduid": "8654ccbbea454f27b451ee4808dca30e", 18:01:21 INFO - "slavebuilddir": "test" 18:01:21 INFO - } 18:01:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 18:01:21 INFO - Running post-action listener: _resource_record_post_action 18:01:21 INFO - ##### 18:01:21 INFO - ##### Running download-and-extract step. 18:01:21 INFO - ##### 18:01:21 INFO - Running pre-action listener: _resource_record_pre_action 18:01:21 INFO - Running main action method: download_and_extract 18:01:21 INFO - mkdir: /builds/slave/test/build/tests 18:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:21 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:01:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:01:31 INFO - Downloaded 1361 bytes. 18:01:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:01:31 INFO - Using the following test package requirements: 18:01:31 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:01:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:01:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:01:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'jsshell-linux-x86_64.zip'], 18:01:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:01:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:01:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:01:31 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:01:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:01:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:01:31 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:01:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:01: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:01:31 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:01:41 INFO - Downloaded 24135950 bytes. 18:01:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:01:43 INFO - Return code: 0 18:01:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:43 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:01:44 INFO - Downloaded 24135950 bytes. 18:01:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:01:45 INFO - Return code: 0 18:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:45 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:01:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:01:46 INFO - Downloaded 10840145 bytes. 18:01:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:01:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:01:46 INFO - Return code: 0 18:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:46 INFO - https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:01:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:01:48 INFO - Downloaded 62768089 bytes. 18:01:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:48 INFO - mkdir: /builds/slave/test/properties 18:01:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:48 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:48 INFO - Contents: 18:01:48 INFO - build_url:https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:01:49 INFO - Running post-action listener: _resource_record_post_action 18:01:49 INFO - Running post-action listener: set_extra_try_arguments 18:01:49 INFO - ##### 18:01:49 INFO - ##### Running populate-webroot step. 18:01:49 INFO - ##### 18:01:49 INFO - Running pre-action listener: _resource_record_pre_action 18:01:49 INFO - Running main action method: populate_webroot 18:01:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:49 INFO - '--tppagecycles', 18:01:49 INFO - '7'], 18:01:49 INFO - 'tests': ['tsvgm']}}, 18:01:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['damp', 'tps']}, 18:01:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'tests': ['damp', 'tps']}, 18:01:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['dromaeo_dom']}, 18:01:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['a11yr', 18:01:49 INFO - 'ts_paint', 18:01:49 INFO - 'tpaint', 18:01:49 INFO - 'sessionrestore', 18:01:49 INFO - 'sessionrestore_no_auto_restore', 18:01:49 INFO - 'tabpaint']}, 18:01:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['tsvgx', 18:01:49 INFO - 'tsvgr_opacity', 18:01:49 INFO - 'tart', 18:01:49 INFO - 'tscrollx', 18:01:49 INFO - 'cart']}, 18:01:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:49 INFO - 'tsvgr_opacity', 18:01:49 INFO - 'tart', 18:01:49 INFO - 'tscrollx', 18:01:49 INFO - 'cart']}, 18:01:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'talos_options': ['--disable-e10s'], 18:01:49 INFO - 'tests': ['tp5o']}, 18:01:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'tests': ['tp5o']}, 18:01:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'talos_options': ['--disable-e10s', 18:01:49 INFO - '--xperf_path', 18:01:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:49 INFO - 'tests': ['tp5n']}, 18:01:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:01:49 INFO - 'talos_options': ['--xperf_path', 18:01:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:49 INFO - 'tests': ['tp5n']}}, 18:01:49 INFO - 'talos.zip': {'path': '', 18:01:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:49 INFO - Running post-action listener: _resource_record_post_action 18:01:49 INFO - ##### 18:01:49 INFO - ##### Running create-virtualenv step. 18:01:49 INFO - ##### 18:01:49 INFO - Running pre-action listener: _resource_record_pre_action 18:01:49 INFO - Running main action method: create_virtualenv 18:01:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:49 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:01:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:49 INFO - Using real prefix '/usr' 18:01:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:51 INFO - Installing pip.................done. 18:01:51 INFO - Return code: 0 18:01:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:51 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:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:01:51 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:01:51 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:01:51 INFO - Using env: {'DISPLAY': ':0', 18:01:51 INFO - 'HOME': '/home/cltbld', 18:01:51 INFO - 'LANG': 'en_US.UTF-8', 18:01:51 INFO - 'LANGUAGE': 'en_US:en', 18:01:51 INFO - 'LOGNAME': 'cltbld', 18:01:51 INFO - 'MAIL': '/var/mail/cltbld', 18:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:51 INFO - 'MOZ_NO_REMOTE': '1', 18:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:51 INFO - 'NO_EM_RESTART': '1', 18:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:51 INFO - 'PWD': '/builds/slave/test', 18:01:51 INFO - 'SHELL': '/bin/bash', 18:01:51 INFO - 'SHLVL': '1', 18:01:51 INFO - 'TERM': 'linux', 18:01:51 INFO - 'TMOUT': '86400', 18:01:51 INFO - 'USER': 'cltbld', 18:01:51 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:51 INFO - '_': '/tools/buildbot/bin/python'} 18:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:51 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:01:51 INFO - Cleaning up... 18:01:51 INFO - Return code: 0 18:01:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:01:51 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:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:51 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:01:51 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>=0.7.1'] in /builds/slave/test/build 18:01:51 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>=0.7.1 18:01:51 INFO - Using env: {'DISPLAY': ':0', 18:01:51 INFO - 'HOME': '/home/cltbld', 18:01:51 INFO - 'LANG': 'en_US.UTF-8', 18:01:51 INFO - 'LANGUAGE': 'en_US:en', 18:01:51 INFO - 'LOGNAME': 'cltbld', 18:01:51 INFO - 'MAIL': '/var/mail/cltbld', 18:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:51 INFO - 'MOZ_NO_REMOTE': '1', 18:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:51 INFO - 'NO_EM_RESTART': '1', 18:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:51 INFO - 'PWD': '/builds/slave/test', 18:01:51 INFO - 'SHELL': '/bin/bash', 18:01:51 INFO - 'SHLVL': '1', 18:01:51 INFO - 'TERM': 'linux', 18:01:51 INFO - 'TMOUT': '86400', 18:01:51 INFO - 'USER': 'cltbld', 18:01:51 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:51 INFO - '_': '/tools/buildbot/bin/python'} 18:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:52 INFO - Downloading/unpacking psutil>=0.7.1 18:01:52 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:01:52 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:01:52 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:01:52 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:01:52 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:01:52 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:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:54 INFO - Installing collected packages: psutil 18:01:54 INFO - Running setup.py install for psutil 18:01:54 INFO - building 'psutil._psutil_linux' extension 18:01:54 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:01:54 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:01:54 INFO - building 'psutil._psutil_posix' extension 18:01:54 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:01:54 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:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:54 INFO - Successfully installed psutil 18:01:54 INFO - Cleaning up... 18:01:54 INFO - Return code: 0 18:01:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:01:54 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:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:54 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:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:54 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:01:54 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.0.0'] in /builds/slave/test/build 18:01:54 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.0.0 18:01:54 INFO - Using env: {'DISPLAY': ':0', 18:01:54 INFO - 'HOME': '/home/cltbld', 18:01:54 INFO - 'LANG': 'en_US.UTF-8', 18:01:54 INFO - 'LANGUAGE': 'en_US:en', 18:01:54 INFO - 'LOGNAME': 'cltbld', 18:01:54 INFO - 'MAIL': '/var/mail/cltbld', 18:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:54 INFO - 'MOZ_NO_REMOTE': '1', 18:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:54 INFO - 'NO_EM_RESTART': '1', 18:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:54 INFO - 'PWD': '/builds/slave/test', 18:01:54 INFO - 'SHELL': '/bin/bash', 18:01:54 INFO - 'SHLVL': '1', 18:01:54 INFO - 'TERM': 'linux', 18:01:54 INFO - 'TMOUT': '86400', 18:01:54 INFO - 'USER': 'cltbld', 18:01:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:54 INFO - '_': '/tools/buildbot/bin/python'} 18:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:01:54 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:01:54 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:01:54 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:01:54 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:01:54 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:01:54 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:01:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:01:56 INFO - Installing collected packages: mozsystemmonitor 18:01:56 INFO - Running setup.py install for mozsystemmonitor 18:01:56 INFO - Successfully installed mozsystemmonitor 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:01:56 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:01:56 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:01:56 INFO - Using env: {'DISPLAY': ':0', 18:01:56 INFO - 'HOME': '/home/cltbld', 18:01:56 INFO - 'LANG': 'en_US.UTF-8', 18:01:56 INFO - 'LANGUAGE': 'en_US:en', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MAIL': '/var/mail/cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SHLVL': '1', 18:01:56 INFO - 'TERM': 'linux', 18:01:56 INFO - 'TMOUT': '86400', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - '_': '/tools/buildbot/bin/python'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:57 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:01:57 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:01:57 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:01:57 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:01:57 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:01:57 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:01:58 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01: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:01: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:01: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:01: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:01: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:01: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:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:01:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:01: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:01: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:01: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:01: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:01: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:01: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:01:59 INFO - Downloading docopt-0.6.1.tar.gz 18:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:01:59 INFO - Installing collected packages: blobuploader, requests, docopt 18:01:59 INFO - Running setup.py install for blobuploader 18:01:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:01:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:01:59 INFO - Running setup.py install for requests 18:02:00 INFO - Running setup.py install for docopt 18:02:00 INFO - Successfully installed blobuploader requests docopt 18:02:00 INFO - Cleaning up... 18:02:00 INFO - Return code: 0 18:02:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:00 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:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:02:00 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:02:00 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:02:00 INFO - Using env: {'DISPLAY': ':0', 18:02:00 INFO - 'HOME': '/home/cltbld', 18:02:00 INFO - 'LANG': 'en_US.UTF-8', 18:02:00 INFO - 'LANGUAGE': 'en_US:en', 18:02:00 INFO - 'LOGNAME': 'cltbld', 18:02:00 INFO - 'MAIL': '/var/mail/cltbld', 18:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:00 INFO - 'MOZ_NO_REMOTE': '1', 18:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:00 INFO - 'NO_EM_RESTART': '1', 18:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:00 INFO - 'PWD': '/builds/slave/test', 18:02:00 INFO - 'SHELL': '/bin/bash', 18:02:00 INFO - 'SHLVL': '1', 18:02:00 INFO - 'TERM': 'linux', 18:02:00 INFO - 'TMOUT': '86400', 18:02:00 INFO - 'USER': 'cltbld', 18:02:00 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:00 INFO - '_': '/tools/buildbot/bin/python'} 18:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:00 INFO - Running setup.py (path:/tmp/pip-t11xHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:00 INFO - Running setup.py (path:/tmp/pip-ZeCKIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:00 INFO - Running setup.py (path:/tmp/pip-dclfnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:00 INFO - Running setup.py (path:/tmp/pip-hRzpSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:00 INFO - Running setup.py (path:/tmp/pip-1PIEx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:00 INFO - Running setup.py (path:/tmp/pip-IWm4hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:00 INFO - Running setup.py (path:/tmp/pip-33j7z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:01 INFO - Running setup.py (path:/tmp/pip-mBb0KW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:01 INFO - Running setup.py (path:/tmp/pip-rJ0w1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:01 INFO - Running setup.py (path:/tmp/pip-8UczOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:01 INFO - Running setup.py (path:/tmp/pip-3BENiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:01 INFO - Running setup.py (path:/tmp/pip-Kj7OOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:01 INFO - Running setup.py (path:/tmp/pip-sZ6uAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:01 INFO - Running setup.py (path:/tmp/pip-jrwnef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:01 INFO - Running setup.py (path:/tmp/pip-bc3_UY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:01 INFO - Running setup.py (path:/tmp/pip-iaWuHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:01 INFO - Running setup.py (path:/tmp/pip-ZP8gtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:01 INFO - Running setup.py install for manifestparser 18:02:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:01 INFO - Running setup.py install for mozcrash 18:02:01 INFO - Running setup.py install for mozdebug 18:02:02 INFO - Running setup.py install for mozdevice 18:02:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for mozfile 18:02:02 INFO - Running setup.py install for mozhttpd 18:02:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for mozinfo 18:02:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for mozInstall 18:02:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for mozleak 18:02:02 INFO - Running setup.py install for mozlog 18:02:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for moznetwork 18:02:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:02 INFO - Running setup.py install for mozprocess 18:02:03 INFO - Running setup.py install for mozprofile 18:02:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Running setup.py install for mozrunner 18:02:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Running setup.py install for mozscreenshot 18:02:03 INFO - Running setup.py install for moztest 18:02:03 INFO - Running setup.py install for mozversion 18:02:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:03 INFO - Cleaning up... 18:02:03 INFO - Return code: 0 18:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:03 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 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:02:03 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:02:03 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:02:03 INFO - Using env: {'DISPLAY': ':0', 18:02:03 INFO - 'HOME': '/home/cltbld', 18:02:03 INFO - 'LANG': 'en_US.UTF-8', 18:02:03 INFO - 'LANGUAGE': 'en_US:en', 18:02:03 INFO - 'LOGNAME': 'cltbld', 18:02:03 INFO - 'MAIL': '/var/mail/cltbld', 18:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:03 INFO - 'MOZ_NO_REMOTE': '1', 18:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:03 INFO - 'NO_EM_RESTART': '1', 18:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:03 INFO - 'PWD': '/builds/slave/test', 18:02:03 INFO - 'SHELL': '/bin/bash', 18:02:03 INFO - 'SHLVL': '1', 18:02:03 INFO - 'TERM': 'linux', 18:02:03 INFO - 'TMOUT': '86400', 18:02:03 INFO - 'USER': 'cltbld', 18:02:03 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:03 INFO - '_': '/tools/buildbot/bin/python'} 18:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:03 INFO - Running setup.py (path:/tmp/pip-gcLaot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:03 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:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:03 INFO - Running setup.py (path:/tmp/pip-W_V97C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:03 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:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:03 INFO - Running setup.py (path:/tmp/pip-_I1ni9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:04 INFO - Running setup.py (path:/tmp/pip-g8pZyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:04 INFO - Running setup.py (path:/tmp/pip-sHEX_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:04 INFO - Running setup.py (path:/tmp/pip-Jq1eeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:04 INFO - Running setup.py (path:/tmp/pip-TzrTNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:04 INFO - Running setup.py (path:/tmp/pip-390A1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:04 INFO - Running setup.py (path:/tmp/pip-egPecv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:04 INFO - Running setup.py (path:/tmp/pip-i8KeYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:04 INFO - Running setup.py (path:/tmp/pip-bFKyzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:04 INFO - Running setup.py (path:/tmp/pip-pDXnFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:04 INFO - Running setup.py (path:/tmp/pip-sAQfEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:04 INFO - Running setup.py (path:/tmp/pip-2bW6Q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:04 INFO - Running setup.py (path:/tmp/pip-TyobJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:04 INFO - Running setup.py (path:/tmp/pip-m25aqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:04 INFO - Running setup.py (path:/tmp/pip-pdGVYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:05 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:02:05 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:02:05 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:02:05 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:02:05 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:02:05 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:02:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02: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:02: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:02: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:02: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:02: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:02: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:02:06 INFO - Downloading blessings-1.6.tar.gz 18:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:07 INFO - Installing collected packages: blessings 18:02:07 INFO - Running setup.py install for blessings 18:02:07 INFO - Successfully installed blessings 18:02:07 INFO - Cleaning up... 18:02:07 INFO - Return code: 0 18:02:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:07 INFO - Reading from file tmpfile_stdout 18:02:07 INFO - Current package versions: 18:02:07 INFO - argparse == 1.2.1 18:02:07 INFO - blessings == 1.6 18:02:07 INFO - blobuploader == 1.2.4 18:02:07 INFO - docopt == 0.6.1 18:02:07 INFO - manifestparser == 1.1 18:02:07 INFO - mozInstall == 1.12 18:02:07 INFO - mozcrash == 0.17 18:02:07 INFO - mozdebug == 0.1 18:02:07 INFO - mozdevice == 0.48 18:02:07 INFO - mozfile == 1.2 18:02:07 INFO - mozhttpd == 0.7 18:02:07 INFO - mozinfo == 0.9 18:02:07 INFO - mozleak == 0.1 18:02:07 INFO - mozlog == 3.1 18:02:07 INFO - moznetwork == 0.27 18:02:07 INFO - mozprocess == 0.22 18:02:07 INFO - mozprofile == 0.28 18:02:07 INFO - mozrunner == 6.11 18:02:07 INFO - mozscreenshot == 0.1 18:02:07 INFO - mozsystemmonitor == 0.0 18:02:07 INFO - moztest == 0.7 18:02:07 INFO - mozversion == 1.4 18:02:07 INFO - psutil == 3.1.1 18:02:07 INFO - requests == 1.2.3 18:02:07 INFO - wsgiref == 0.1.2 18:02:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02: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:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:07 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 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:02:07 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:02:07 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:02:07 INFO - Using env: {'DISPLAY': ':0', 18:02:07 INFO - 'HOME': '/home/cltbld', 18:02:07 INFO - 'LANG': 'en_US.UTF-8', 18:02:07 INFO - 'LANGUAGE': 'en_US:en', 18:02:07 INFO - 'LOGNAME': 'cltbld', 18:02:07 INFO - 'MAIL': '/var/mail/cltbld', 18:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:07 INFO - 'MOZ_NO_REMOTE': '1', 18:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:07 INFO - 'NO_EM_RESTART': '1', 18:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:07 INFO - 'PWD': '/builds/slave/test', 18:02:07 INFO - 'SHELL': '/bin/bash', 18:02:07 INFO - 'SHLVL': '1', 18:02:07 INFO - 'TERM': 'linux', 18:02:07 INFO - 'TMOUT': '86400', 18:02:07 INFO - 'USER': 'cltbld', 18:02:07 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:07 INFO - '_': '/tools/buildbot/bin/python'} 18:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 INFO - Cleaning up... 18:02:07 INFO - Return code: 0 18:02:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02: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:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:02: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', 'jsonschema'] in /builds/slave/test/build 18:02: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 jsonschema 18:02:07 INFO - Using env: {'DISPLAY': ':0', 18:02:07 INFO - 'HOME': '/home/cltbld', 18:02:07 INFO - 'LANG': 'en_US.UTF-8', 18:02:07 INFO - 'LANGUAGE': 'en_US:en', 18:02:07 INFO - 'LOGNAME': 'cltbld', 18:02:07 INFO - 'MAIL': '/var/mail/cltbld', 18:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:07 INFO - 'MOZ_NO_REMOTE': '1', 18:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:07 INFO - 'NO_EM_RESTART': '1', 18:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:07 INFO - 'PWD': '/builds/slave/test', 18:02:07 INFO - 'SHELL': '/bin/bash', 18:02:07 INFO - 'SHLVL': '1', 18:02:07 INFO - 'TERM': 'linux', 18:02:07 INFO - 'TMOUT': '86400', 18:02:07 INFO - 'USER': 'cltbld', 18:02:07 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:07 INFO - '_': '/tools/buildbot/bin/python'} 18:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:07 INFO - Downloading/unpacking jsonschema 18:02: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:02: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:02: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:02: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:02: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:02: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:02:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:09 INFO - Downloading/unpacking functools32 (from jsonschema) 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:09 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:09 INFO - warning: no files found matching '*.txt' 18:02:09 INFO - no previously-included directories found matching 'build' 18:02:09 INFO - no previously-included directories found matching 'dist' 18:02:09 INFO - no previously-included directories found matching '.git*' 18:02:09 INFO - Installing collected packages: jsonschema, functools32 18:02:10 INFO - Running setup.py install for functools32 18:02:10 INFO - warning: no files found matching '*.txt' 18:02:10 INFO - no previously-included directories found matching 'build' 18:02:10 INFO - no previously-included directories found matching 'dist' 18:02:10 INFO - no previously-included directories found matching '.git*' 18:02:10 INFO - Successfully installed jsonschema functools32 18:02:10 INFO - Cleaning up... 18:02:10 INFO - Return code: 0 18:02:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:10 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f78e72fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', '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:02:10 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', 'flake8'] in /builds/slave/test/build 18:02:10 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 flake8 18:02:10 INFO - Using env: {'DISPLAY': ':0', 18:02:10 INFO - 'HOME': '/home/cltbld', 18:02:10 INFO - 'LANG': 'en_US.UTF-8', 18:02:10 INFO - 'LANGUAGE': 'en_US:en', 18:02:10 INFO - 'LOGNAME': 'cltbld', 18:02:10 INFO - 'MAIL': '/var/mail/cltbld', 18:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:10 INFO - 'MOZ_NO_REMOTE': '1', 18:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:10 INFO - 'NO_EM_RESTART': '1', 18:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:10 INFO - 'PWD': '/builds/slave/test', 18:02:10 INFO - 'SHELL': '/bin/bash', 18:02:10 INFO - 'SHLVL': '1', 18:02:10 INFO - 'TERM': 'linux', 18:02:10 INFO - 'TMOUT': '86400', 18:02:10 INFO - 'USER': 'cltbld', 18:02:10 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:10 INFO - '_': '/tools/buildbot/bin/python'} 18:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:10 INFO - Downloading/unpacking flake8 18:02: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:02: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:02: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:02: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:02: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:02: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:02:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:02:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:02:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:12 INFO - no previously-included directories found matching 'docs/_build' 18:02:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:02:13 INFO - Running setup.py install for pep8 18:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:02:13 INFO - no previously-included directories found matching 'docs/_build' 18:02:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:02:13 INFO - Cleaning up... 18:02:13 INFO - Return code: 0 18:02:13 INFO - Running post-action listener: _resource_record_post_action 18:02:13 INFO - Running post-action listener: _start_resource_monitoring 18:02:13 INFO - Starting resource monitoring. 18:02:13 INFO - ##### 18:02:13 INFO - ##### Running install step. 18:02:13 INFO - ##### 18:02:13 INFO - Running pre-action listener: _resource_record_pre_action 18:02:13 INFO - Running main action method: install 18:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:13 INFO - Reading from file tmpfile_stdout 18:02:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:13 INFO - Reading from file tmpfile_stdout 18:02:13 INFO - Output received: 18:02:13 INFO - Usage: mozinstall [options] installer 18:02:13 INFO - Options: 18:02:13 INFO - -h, --help show this help message and exit 18:02:13 INFO - -d DEST, --destination=DEST 18:02:13 INFO - Directory to install application into. [default: 18:02:13 INFO - "/builds/slave/test"] 18:02:13 INFO - --app=APP Application being installed. [default: firefox] 18:02:13 INFO - mkdir: /builds/slave/test/build/application 18:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:02:29 INFO - Reading from file tmpfile_stdout 18:02:29 INFO - Output received: 18:02:29 INFO - /builds/slave/test/build/application/firefox/firefox 18:02:29 INFO - Running post-action listener: _resource_record_post_action 18:02:29 INFO - ##### 18:02:29 INFO - ##### Running run-tests step. 18:02:29 INFO - ##### 18:02:29 INFO - Running pre-action listener: _resource_record_pre_action 18:02:29 INFO - Running main action method: run_tests 18:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:29 INFO - Python 2.7.3 18:02:29 INFO - Return code: 0 18:02:29 INFO - grabbing minidump binary from tooltool 18:02:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:02:29 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f41410>, '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 0x1f42310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f427a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:29 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:02:29 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:02:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:29 INFO - Return code: 0 18:02:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:02:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:02:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:02:30 INFO - Return code: 0 18:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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:02:30 INFO - Using env: {'DISPLAY': ':0', 18:02:30 INFO - 'HOME': '/home/cltbld', 18:02:30 INFO - 'LANG': 'en_US.UTF-8', 18:02:30 INFO - 'LANGUAGE': 'en_US:en', 18:02:30 INFO - 'LOGNAME': 'cltbld', 18:02:30 INFO - 'MAIL': '/var/mail/cltbld', 18:02:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:30 INFO - 'MOZ_NO_REMOTE': '1', 18:02:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:30 INFO - 'NO_EM_RESTART': '1', 18:02:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:30 INFO - 'PWD': '/builds/slave/test', 18:02:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:30 INFO - 'SHELL': '/bin/bash', 18:02:30 INFO - 'SHLVL': '1', 18:02:30 INFO - 'TERM': 'linux', 18:02:30 INFO - 'TMOUT': '86400', 18:02:30 INFO - 'USER': 'cltbld', 18:02:30 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040', 18:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:30 INFO - '_': '/tools/buildbot/bin/python'} 18:02:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:02:30 INFO - mozversion application_buildid: 20160525152143 18:02:30 INFO - mozversion application_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 18:02:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:02:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:30 INFO - mozversion application_name: Firefox 18:02:30 INFO - mozversion application_remotingname: firefox-dev 18:02:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:30 INFO - mozversion application_vendor: Mozilla 18:02:30 INFO - mozversion application_version: 48.0a2 18:02:30 INFO - mozversion platform_buildid: 20160525152143 18:02:30 INFO - mozversion platform_changeset: 4f7c3a2567f88307b10e4b6fc9b0d4b180fe58a3 18:02:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:30 INFO - mozversion platform_version: 48.0a2 18:02:30 INFO - using testdate: 1464224550 18:02:30 INFO - actual date: 1464224550 18:02:30 INFO - starting webserver on 'localhost:48089' 18:02:30 INFO - SUITE-START | Running 2 tests 18:02:30 INFO - TEST-START | dromaeo_css 18:02:30 INFO - operating with platform_type : linux_ 18:02:30 INFO - Initialising browser for dromaeo_css test... 18:02:30 INFO - TEST-INFO | started process 7307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2iApnD/profile http://localhost:48089/getInfo.html) 18:02:34 INFO - PROCESS | 7307 | __metrics Screen width/height:1600/1200 18:02:34 INFO - PROCESS | 7307 | colorDepth:24 18:02:34 INFO - PROCESS | 7307 | Browser inner width/height: 1024/697 18:02:34 INFO - PROCESS | 7307 | __metrics 18:02:35 ERROR - PROCESS | 7307 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:02:36 INFO - TEST-INFO | 7307: exit 0 18:02:36 INFO - Browser initialized. 18:02:36 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:36 INFO - TEST-INFO | started process 7398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2iApnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:48 INFO - PROCESS | 7398 | RSS: Main: 141922304 18:02:48 INFO - PROCESS | 7398 | 18:03:39 INFO - PROCESS | 7398 | 1464224619484 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:05:46 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48089/tests/dromaeo/cssquery-ext.html) 18:05:47 INFO - PROCESS | 7398 | RSS: Main: 251256832 18:05:47 INFO - PROCESS | 7398 | 18:08:50 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-ext.html (next: http://localhost:48089/tests/dromaeo/cssquery-jquery.html) 18:08:50 INFO - PROCESS | 7398 | RSS: Main: 227991552 18:08:50 INFO - PROCESS | 7398 | 18:11:52 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48089/tests/dromaeo/cssquery-mootools.html) 18:11:53 INFO - PROCESS | 7398 | RSS: Main: 225615872 18:11:53 INFO - PROCESS | 7398 | 18:14:55 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48089/tests/dromaeo/cssquery-prototype.html) 18:14:55 INFO - PROCESS | 7398 | RSS: Main: 213422080 18:14:55 INFO - PROCESS | 7398 | 18:17:58 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48089/tests/dromaeo/cssquery-yui.html) 18:17:58 INFO - PROCESS | 7398 | RSS: Main: 212037632 18:17:58 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | Cycle 1(1): loaded http://localhost:48089/tests/dromaeo/cssquery-yui.html (next: http://localhost:48089/tests/dromaeo/cssquery-dojo.html) 18:21:01 INFO - PROCESS | 7398 | RSS: Main: 254885888 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | __start_tp_report 18:21:01 INFO - PROCESS | 7398 | _x_x_mozilla_page_load 18:21:01 INFO - PROCESS | 7398 | _x_x_mozilla_page_load_details 18:21:01 INFO - PROCESS | 7398 | |i|pagename|runs| 18:21:01 INFO - PROCESS | 7398 | |0;dojo.html;3303.4219011672953;3351.447011243145;3366.1584603849037;3373.89878303651;3396.1000334911255;5478.369987451444;5491.65454463657;5506.091494903346;5513.338399392073;5513.9448605513935;5510.366307874596;5519.089350257203;5530.6128571000045;5535.529479994195;5555.50000499955;4950.9504904950845;5007.549320561149;5049.4950504949575;5100.617453690969;5152.51051150142;4965.056639238551;5029.094762942669;5030.798768049273;5040.1179793535985;5044.848654540369;4971.030649023442;5029.220470827009;5039.84880453587;5041.0422019816115;5047.873803154932;5098.388193416795;5118.718470484122;5126.948730512684;5148.639595228336;5173.379194496647;4916.139675556763;4926.187179115442;5006.774695138727;5030.647854650176;5038.244263360525;5421.647592906488;5442.292501974757;5472.151816468454;5474.644148130359;5477.3427188737205;5936.881262374728;5953.58324917256;5966.463018328328;5973.283206015262;5982.162497250389;5868.4718375346;5952.9107063394085;5999.070144127667;6009.969950150221;6021.36775638556;5537.335519737617;5573.247611572427;5573.888522229573;5591.077472217079;5597.692126932976;5689.630174038674;6072.635641861502;6099.451049405575;6108.938910610926;6113.32753397126;5684.573656975661;5751.223584815996;5782.10377391505;5784.334801497797;5786.594938354359;6000.33996260411;6004.639721616717;6008.819735407945;6009.308929473079;6009.429104235091;5806.21616081832;5914.053751399755;5975.163477113208;5977.820665380045;5979.222701048923;5781.566382521328;5877.23595932526;5877.353491115974;5877.9118313225335;5904.822855314347;4453.332000199931;4479.574440428154;4479.574440428154;4481.193385190697;4507.323901414748;3988.720789544762;4003.9990002499376;4014.8996275092773;4026.456428382131;4059.939100913489;5592.608517403824;5596.916046259309;5621.7189140542805;5628.746706398222;5652.434756524314;5899.911501327483;5905.793297234576;5912.290525137011;5921.851953701192;5934.703264836741;5524.088525393346;5630.211770352154;5676.290463692038;5805.593608447368;5814.098814683731;5086.3133476980875;5087.109755792721;5087.923681144785;5088.389393272831;5096;6161.4454699076405;6170;6171.907421388682;6173.567850250529;6178.567500274938;5848.5321174305955;6011.188489553854;6064.151018857276;6093.451589356979;6101.938980610137;6108.725107370268;6118.47992920589;6119.44924956756;6122.112293717475;6128.203333566607;5693.089105743061;5768.798092066758;5770.13447982806;5773.134029895549;5775.306963164448;5908.763649453974;5998.640081595118;6006.309274433322;6007.189029480965;6044.395560444096;5801.042827933344;5824.621399609012;5825.271841019872;5837.766489340379;5840.503557197516;5650.717464126859;5736.569757268305;5745.540356771532;5790.536757059509;5800.216970708901;5670.914936276042;5688.260526131577;5695.886082278248;5720.856978575569;5731.541476681772;5772.336181339199;5784.248047753765;5792.565557583114;5803.709814509173;5824.970875145597;5673.74468148926;5696.971515142398;5714.314282286152;5762.222100016612;5830.970845145747 18:21:01 INFO - PROCESS | 7398 | |1;ext.html;593.6111846740384;646.9874646178733;647.7829926974463;662.9602223866567;670.152257394396;35256.2948741025;35809.283814323695;35820.104497387576;35857.46213806792;36048.27903441933;37622.62377376226;38265;38351.808240958824;38442.231155376874;38444.23111537775;8591.656333746643;8770.342224333168;8770.386072974863;8775.824483510325;8780.648774049063;8736.781580460476;8876.6005529751;8880.644774209057;8881.822363552725;8882;8861.689840855572;8929.464232146092;8933.419327743708;8934.687285944994;8936.55317234139;8884.244839188645;8938.64245430182;8947.50788706621;8948.418352807046;8952;8612.440191387603;8701.869471957925;8712.912870871336;8715.433496822687;8715.564221788885;37779.4333085004;37861.43207851885;38000.619993799985;38013.80993095045;38039.809800951094;3369.578802649669;3499.142710036032;3645.7447978641508;3904.160605469809;3954.367301231789;28133.29666758327;28808.279793005135;28976.71023289761;29150.562741558893;29430.26424339387;4288.549702281247;4362.978185109054;4392.38506609075;4393.846215382447;4408;269197;271981.999999998;272139;272355;272753;33927.83036084853;50920.74539627278;51223.23165152526;51252.231216531785;51521.454356369366;32982.835085824896;33004.17489562732;33148.66851331504;33167;33203.50194747032;13755.518556850462;13805.723885522435;13807.51673691416;13842.86157138436;13869.029167958146;11535.250208736574;11564.248323858923;11571.479283432098;11571.710707232218;11587.88412115868;8414.11651776567;8491.490510569385;8514.276286515715;8563.871541926876;8574.657013719381;3159.036493869346;3170.9841450792596;3182.872685092617;3226.161198088514;3234.5471633971265;944.447498213539;1027.0345874877592;1071.6624263357019;1093.830456279278;1109.7336639206533;29605.555916661266;29796.404071918874;30061.398772024437;30129.397412051636;30575.541366879515;1046.7383154211448;1074.3392813419846;1093.2347356850237;1111.6387174168267;1136.323887287063;15021.774673379909;15627.14050727221;15741.291641876143;15783.21083945821;16014.519564413085;490714;493497;496266;496710;498484;50390.496095039314;51806.22290665643;51875.22187167196;51964;52101.47898521042;1312.8293321868096;1332.000999250562;1345.0248569786827;1384.8545902680276;1405.0375492787268;27701.58447623247;28542.42915141727;28580.285492862844;32537.511937321433;33193.668063319055;49946.50053499564;50324;50646.73383165451;50681.49318506768;50701.74649126879;426282;426734;429696;431302;431425;35723;39548;40655.59344406518;40908.1818363637;41013;38754.80622596869;40292;40909.38635920523;42508.14983700371;43634.781826090664;36367.090822729646;36463.45304820483;36756;36857;37194.8140259297;35392.823035884656;35855.64144358523;35966.460503092996;35984.28031439409;36088;36011;36924.81537592402;37451.625483744814;37455.25089498249;37459;33969.66030339665;36664;36671;37095;37121.25757484889;25260.873695631406;25765.484690306464;25784.61323080193;25925.70484888222;25938.221853343675 18:21:01 INFO - PROCESS | 7398 | |2;jquery.html;407.8919086442093;410.1714536635993;412.6678024190525;416.26529176004345;420.5878239325461;14115.57653270401;15589.922050389732;17534.91232543837;18242.635147297064;18303.17635706393;13459.259740714257;15047.24763761813;15178.31697573609;15554.53336399907;15766.684666306666;5632.324121105473;5767.279090113736;5769.365369809318;5774.451427114438;5801.390853960315;4293.205756934962;5642.266505354298;6065.363136870631;6341.746330146789;6348.460380867632;6221.066839973995;6279.340669229734;6293.4335909768115;6312.053192021188;6315.368463153671;6140.877182456348;6513.348665133496;6518.869622607545;6519.837004074912;6523;5679.261695979496;5733.369329373765;5747.885042299173;5789.305283365981;5802.7388767505145;21605.243816466347;21857.344279671477;22181.667274990887;22472;22483.21308754186;2525.5075260324243;2588.5211235921415;2611.9608205876925;2854.200823769348;2918.460084884282;14065.507707230197;14280.785788213081;14328.570142895729;14504.129752214902;14665.853341466556;1963.2735887721492;2059.794020597943;2094.277474271374;2117.026167962739;2183.235867446397;73332.6333368335;77417;79443.6027819863;79521;79786.60106699429;10407.21945854064;10634.883337249632;10836.458177091114;10938.289011214245;10981.450927453756;15145.924270378799;15188.696226075417;15238.161901095324;15271.770923436157;15274.236288185546;7228.457865660096;7312.488125831247;7319.687866171311;7388.261173882676;7435.1821299656995;6753.189617245864;6799.7960061197255;6840;6872.41584465326;6947.826304342432;6437.130987316652;6542.084108224853;6567;6579.210494740566;6596.175478065242;1796.4071856287394;1848.9630207395776;1871.7192421136665;1885.1422602716007;1890.5651700108776;939.5208443693629;1031.6440827914357;1036.5180191211123;1037.984430233547;1052.4264275969463;17157;18230.36193733213;18302.745661137527;18555.35056273024;18728;979.9902000979899;989.2728844299434;990.5096976996289;1001.4191768774093;1004.091297375876;6638.800835974928;10448.164146868232;10522.842157367646;10690.465476726287;10717.732056698489;344715;498708;502036;554483;555404;93974;95328.99999999862;96634;98073.50963245139;99223.50388248013;502.2290783647084;505.94181669107786;507.0872429626657;510.47676131965034;510.60683273879647;11080.778384432428;11403.657890263305;11614.825777613512;11922.344271065174;12013.159078864395;40106.59893401029;40680.98297542585;40786.18427631372;41555.37666934938;41960.79019604882;58072.12891806711;59072.40927590841;59207.40792592019;59658;59921;9212.815743685223;9327.673531426368;9331.953340233254;9408.905910941077;9438.339316247797;10874.510647020745;13714.65713357174;13838.37727302294;14289.214093224973;14404.135751854923;11144.2199046066;12225.877741222474;12421.441035153259;12483.375831208221;12587.74824503523;11454.599089031844;11605.535778568763;11662.475188616363;11900.226485278428;11956.641300760992;11343.546258149581;11879.287242765462;12255;12555.560555380518;12662.556810511956;9153.17621414076;9429.952850235704;9440.811183776148;9538.666146684833;9608.95195524018;12257.509699611915;12339.75320493603;12559.686007849512;12999.41502632365;13015 18:21:01 INFO - PROCESS | 7398 | |3;mootools.html;1344.159900062461;1379.8896088312936;1389.2775756606566;1457.7959085728;1573.5830005048656;7961.88057179142;8137.593120343989;8163.91836081639;8166.611676015077;8181.631826567804;7779.377649788017;7936.365090792737;7947.245011723891;7948.92051079489;7964.323032542241;2081.1779347157863;2321.3500219938446;2375.5130198309307;2384.153625462962;2403.615421532555;2376.192094687806;2382.118616112041;2384.4873352229265;2395.1736650855514;2396.4847557775074;2307.261676263596;2350.74141844397;2356.9882150589224;2360.24472168906;2360.551495215912;2050.5591297870956;2100.4328831215485;2104.8316134709185;2305.43516838374;2346.9530609387803;2271.4548508357875;2299.3906614747107;2300.2409204962323;2301.4361481437154;2305.319930620454;8245.464044837066;8302.70940517085;8317.625706843206;8321.500709957361;8324.500529968162;5040.949590504085;5106.106431374495;5108.5402313791565;5144.794208231704;5154.871128221787;3349.062262566485;3437.4671925851535;3455.1189446691183;3467.514547963287;3474.843632036539;1152.3143729480946;1174.2484809721784;2022.8685135466148;2118.66101423773;2135.882526461044;11649.941750291278;11792.41037948099;11818.881811181864;11820.349880756556;11824.05407567384;4690.929636055462;4743.715377077387;4752.215884379108;4827.468978412372;4875.902921842557;9302.674406395743;9359.766005849908;9433.151016408556;9472.384295020802;9487.810243795224;5139.203423469368;5157.4584668610005;5161.329027226511;5162.328897243409;5177.792888284502;4176.415301857743;4489.079738653568;4564.132814765184;4607.308903664477;4611.492735799059;3871.3225185592974;3889.5527014393706;3930.1353702185475;3961.286968345668;4015.8393664253686;1210.449245593253;1212.5331747277362;1233.9444724987397;1252.4176258040054;1259.2822091407809;803.2489622203259;882.8366752150872;893.271983333581;909.0954500272271;909.5315912305168;5543.695096769717;5580.525655319262;5685.403032681509;5691.544676425876;5803.071508558694;916.6516723645105;917.3074328881652;950.5247376311844;962.3359881681617;970.126885802769;3704.7962362069807;3721.553413590332;3757.4739536464917;3757.492738480325;3792.393217085253;10201.693949181536;10576.15390768752;10630.362178269328;10649.361038337725;10650.360978341325;10234.846477302846;10336.224783141295;10348.430836304076;10444.373337599769;10474.528646210938;7856.685732570633;7865.803354916173;7924.366050715929;7945.205479452008;8019.39854510914;5328.227407026037;5400.756965936621;5495.258140151029;5501.092319767354;5534.86162845916;9339.159475647224;9353.532323383939;9539.284553658365;9541.188998934891;9558.522073896416;9065.274778017874;9348.906510934803;9374.671886484213;9378.67174648884;9653.855192172263;6600.141981542369;6740.696668650021;7288.453365997678;7699.30706236419;7762.184970578008;8050.919490805251;8079.83840323202;8149.266566009088;8154.959225204073;8175.223353781381;7130.251323610946;7348.154962179417;7388;7455.142658594331;7561.09266887955;7467.103947526114;7497.437692173;7531.246875312425;7649;7656.80857978555;7003;7076.752313668997;7082.150141982995;7099.254578269208;7100;4816.54242846943;4921.138800709933;4975.10448119342;5017.523335283142;5037.5214354636255;4876.634252431011;4941.703497790144;5035.0685123252315;5042.899142017066;5046.293518907282 18:21:01 INFO - PROCESS | 7398 | |4;prototype.html;342.79432340595645;366.54365315182594;368.09815950920245;368.6608320345282;372.03085961071423;6044.879102417949;6111.297200821907;6114.724837382318;6142.754289828402;6215.160953271318;5881.882362352761;6113.2664080310315;6114.541409394291;6129.203203583539;6129.938700612993;4707.882302942419;4806.543378379066;4818.325434439182;4829.734364609945;4836.322914791932;4802.735849528274;4814.7833347499445;4820.301056346828;4829;4831.613470922335;4551.2717965125585;4634.652401069917;4783.043391321732;4783.378160839104;4800.351952486422;4721.787519561628;4724.763761811896;4754.263089221176;4778.1638213312535;4782.760861956888;4239.91520169598;4241.491021077491;4244.3209086546;4249.043965107857;4250.362445633149;6003;6018.488428483584;6018.6689732064715;6018.759249630009;6033.48715359195;5567.805126820542;5583.776648934037;5590.440955904418;5591.189277554772;5591.8881622367735;6438.388353106494;6486.210929300399;6543.9345606544275;6559.016147577854;6565.409113179837;4677.859664210045;4702;4706.7646617669325;4708.1289961356915;4714.528547145293;6746.359095885883;6824.590524568592;6837.145356830396;6839.589624622538;6849.863002739918;4823.1800593898415;4843.176659967814;4844;4844.975775121102;4849.078675051729;4887.413510378778;4892.951070489321;4894.828680996148;4896.314515967767;4897.681650692765;5544.528715059265;5574.275344205308;5586.776528938797;5589.245451864028;5589.524890384363;6363.936360636427;6564.343565643398;6616.702248398834;6630.502712296597;6648.634325112069;1701.9404320848957;1733;1734.7224444088886;1736.2794440307384;1736.947891563255;1094.7810437912449;1159.0785325666116;1160.1704781081567;1175.1538891997748;1186.3890096600257;6319.842003949938;6334.366563343721;6338.683065846689;6340.682965851781;6343.841403964938;6734.191896972395;6738.9891516272955;6744.089547911068;6745.224299205556;6756.898646520306;6305.4009869062365;6307.148534947723;6307.905381419282;6320.810375688737;6333.429991300805;3593.5148754918273;3625.4018087015347;3626.274745050948;3626.9093272667865;3632.0919770057485;6867.107276054081;7147.89278160828;7156.642167891585;7159.749408770668;7165.283471652897;6994.93005069953;7035.261297563682;7038.260982596754;7102.715891364288;7110.7511237106455;7118.679659415338;7120.394766444807;7134.464915131386;7134.607596582134;7142.5714457132735;6809.489288303298;6858.485613578901;6871.5189936703955;6872.62200578973;6879.931200687929;6510;6748.966255168692;6760.560563563353;6764.4588432924265;6765.323467653392;7055.6472176392;7102.786916392516;7107.289271073058;7108.751193708195;7111.893321600077;4626.7223966562115;4645.163870503342;4651.511591282867;4655.2784318430695;4658.254679251304;6798.184217893884;6803.421709154777;6805.149356330458;6806.489513286622;6808.93191068083;6487.708053137526;6625.801225963229;6649.235337936199;6652.334766523309;6656.966715166393;6566.0479230512265;6576.506761992774;6589.901151482827;6593.703283352356;6600.438962688226;6476.579022363531;6487.675616219264;6502.772402966063;6507.219133703797;6508.381703738138;6616.966915165393;6660.935110115685;6662.100616416722;6671.366220209073;6676.833079172865;6337.4613157882095;6467.579607325509;6474.773382931575;6485.15692959931;6489.513286503626 18:21:01 INFO - PROCESS | 7398 | |5;yui.html;725.1877896755634;748.8053106192392;751.6429695894451;755.6826133024131;761.0486891385766;14388.208648524327;14546.927265363685;14555.417783288656;14588.489402870902;14693.706125877476;12621.432035558399;12662.62012139635;12665.62003139905;12666.050046246522;12720.300383478905;536.4662161149662;589.1692713274263;595.2262059322885;597.9820605381824;610.9083637454384;582.4408567774941;586.9677167755793;587.8589138606746;588.4997751910885;590.10598942602;645.7094307561592;655.4134050025225;655.4199533412916;665.6804733727789;665.7337065173921;582.6999095465839;834.6327615849037;857.8498762716499;859.0593300336122;860.1011942520081;636.9299377068519;637.092143695237;637.4390536328048;637.6397335505425;638.1002786071663;13931;13954.441822327095;13956.790648140286;13981.860181398157;14026.579202623938;11745.647630571097;12176.086793490524;12324.767523247694;12455.813162802566;12575.308358040304;11144.164187685956;11271.492782824793;11317.094632429385;11434.371109588968;11715.7071073223;4609.239475486541;4611.239145540982;4611.677182597219;4619.122366750306;4624.213883639755;58012.419875801126;58847.41152588505;59180;59279;59344.81310373812;10143.036411540892;10228.846567301496;10285.43430111336;10293.94853025745;10444.373337599769;15302.923485382724;16944.068076255677;16956.915215423844;16987.74518382225;16991.91504042472;8141.389395795339;8235.703630034808;8310.626021829032;8344.66621335152;8348.33213342931;4848.975755121272;4885.877853053631;4994.725290109079;5034.84895453137;5056.86220600362;3345.4981752737285;3400.9659903400648;3409.454487282023;3414.7951122932704;3421;526.6973413620129;568.6502800777492;594.5392320951211;595.238095238096;597.0387675841891;656.9310222426672;679.0900193740412;691.6265216782881;727.4653129949482;751.7218629107156;8905.866412003825;9496.810063798684;9653.131218190396;9672.129508344282;9702.417854928726;709.3223082862133;747.9513831600929;749.9325060744559;775.1976704111217;775.9301662850371;4512.300593408027;4521.977390113094;4623.514530974267;4636.374089497875;4644.790984405709;57255.42744572584;59609;59769;59775.40224597785;65472.67263663651;54336.45663543314;55280.72359638176;59234;60160.69919650374;60445.09332360018;51154.232686509735;52167.73916130395;53389.1991620126;54101.91796164133;54471.72764136154;10022.498875056364;10205.234607404325;10224.079832815083;10252.743681408025;10270.383776973405;38955.805220973714;38964.41553376645;39470;40093.79953100216;41021.58978410178;47488;50720.49279507158;51252;51457;52181.73909130582;11679.416029198675;11915.106367022334;11919.3444360561;11978.006681514365;12071;13541.1198272112;13547.729045418839;13646.795298070736;13838.377273022537;13848.169109853441;12646.620601381974;12651.493940242288;12665.303408312262;12792.808107878576;13284.601461956157;13516.324183790968;13804.17174969505;14031.228282444563;14092.084014539023;14179.14925104497;12632.368381581067;13120;13408;13431.462741490623;13436.865631343562;11011.394373309546;11293.378864162221;11428.771424571296;11448.885511144781;11737.354445505238;10902.454877255946;11496.88503114958;11632.534698612299;11658.717541070646;11697.654769701252 18:21:01 INFO - PROCESS | 7398 | __end_tp_report 18:21:01 INFO - PROCESS | 7398 | __start_cc_report 18:21:01 INFO - PROCESS | 7398 | _x_x_mozilla_cycle_collect,870 18:21:01 INFO - PROCESS | 7398 | __end_cc_report 18:21:01 INFO - PROCESS | 7398 | __startTimestamp1464225661427__endTimestamp 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | ------- Summary: start ------- 18:21:01 INFO - PROCESS | 7398 | Number of tests: 6 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#0] dojo.html Cycles:165 Average:5509.84 Median:5714.31 stddev:616.46 (10.8%) stddev-sans-first:593.55 18:21:01 INFO - PROCESS | 7398 | Values: 3303.4 3351.4 3366.2 3373.9 3396.1 5478.4 5491.7 5506.1 5513.3 5513.9 5510.4 5519.1 5530.6 5535.5 5555.5 4951.0 5007.5 5049.5 5100.6 5152.5 4965.1 5029.1 5030.8 5040.1 5044.8 4971.0 5029.2 5039.8 5041.0 5047.9 5098.4 5118.7 5126.9 5148.6 5173.4 4916.1 4926.2 5006.8 5030.6 5038.2 5421.6 5442.3 5472.2 5474.6 5477.3 5936.9 5953.6 5966.5 5973.3 5982.2 5868.5 5952.9 5999.1 6010.0 6021.4 5537.3 5573.2 5573.9 5591.1 5597.7 5689.6 6072.6 6099.5 6108.9 6113.3 5684.6 5751.2 5782.1 5784.3 5786.6 6000.3 6004.6 6008.8 6009.3 6009.4 5806.2 5914.1 5975.2 5977.8 5979.2 5781.6 5877.2 5877.4 5877.9 5904.8 4453.3 4479.6 4479.6 4481.2 4507.3 3988.7 4004.0 4014.9 4026.5 4059.9 5592.6 5596.9 5621.7 5628.7 5652.4 5899.9 5905.8 5912.3 5921.9 5934.7 5524.1 5630.2 5676.3 5805.6 5814.1 5086.3 5087.1 5087.9 5088.4 5096.0 6161.4 6170.0 6171.9 6173.6 6178.6 5848.5 6011.2 6064.2 6093.5 6101.9 6108.7 6118.5 6119.4 6122.1 6128.2 5693.1 5768.8 5770.1 5773.1 5775.3 5908.8 5998.6 6006.3 6007.2 6044.4 5801.0 5824.6 5825.3 5837.8 5840.5 5650.7 5736.6 5745.5 5790.5 5800.2 5670.9 5688.3 5695.9 5720.9 5731.5 5772.3 5784.2 5792.6 5803.7 5825.0 5673.7 5697.0 5714.3 5762.2 5831.0 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#1] ext.html Cycles:180 Average:53926.46 Median:37323.22 stddev:108856.15 (291.7%) stddev-sans-first:109087.87 18:21:01 INFO - PROCESS | 7398 | Values: 593.6 647.0 647.8 663.0 670.2 35256.3 35809.3 35820.1 35857.5 36048.3 37622.6 38265.0 38351.8 38442.2 38444.2 8591.7 8770.3 8770.4 8775.8 8780.6 8736.8 8876.6 8880.6 8881.8 8882.0 8861.7 8929.5 8933.4 8934.7 8936.6 8884.2 8938.6 8947.5 8948.4 8952.0 8612.4 8701.9 8712.9 8715.4 8715.6 37779.4 37861.4 38000.6 38013.8 38039.8 3369.6 3499.1 3645.7 3904.2 3954.4 28133.3 28808.3 28976.7 29150.6 29430.3 4288.5 4363.0 4392.4 4393.8 4408.0 269197.0 271982.0 272139.0 272355.0 272753.0 33927.8 50920.7 51223.2 51252.2 51521.5 32982.8 33004.2 33148.7 33167.0 33203.5 13755.5 13805.7 13807.5 13842.9 13869.0 11535.3 11564.2 11571.5 11571.7 11587.9 8414.1 8491.5 8514.3 8563.9 8574.7 3159.0 3171.0 3182.9 3226.2 3234.5 944.4 1027.0 1071.7 1093.8 1109.7 29605.6 29796.4 30061.4 30129.4 30575.5 1046.7 1074.3 1093.2 1111.6 1136.3 15021.8 15627.1 15741.3 15783.2 16014.5 490714.0 493497.0 496266.0 496710.0 498484.0 50390.5 51806.2 51875.2 51964.0 52101.5 1312.8 1332.0 1345.0 1384.9 1405.0 27701.6 28542.4 28580.3 32537.5 33193.7 49946.5 50324.0 50646.7 50681.5 50701.7 426282.0 426734.0 429696.0 431302.0 431425.0 35723.0 39548.0 40655.6 40908.2 41013.0 38754.8 40292.0 40909.4 42508.1 43634.8 36367.1 36463.5 36756.0 36857.0 37194.8 35392.8 35855.6 35966.5 35984.3 36088.0 36011.0 36924.8 37451.6 37455.3 37459.0 33969.7 36664.0 36671.0 37095.0 37121.3 25260.9 25765.5 25784.6 25925.7 25938.2 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#2] jquery.html Cycles:180 Average:28910.69 Median:192410.80 stddev:82052.27 (42.6%) stddev-sans-first:82254.54 18:21:01 INFO - PROCESS | 7398 | Values: 407.9 410.2 412.7 416.3 420.6 14115.6 15589.9 17534.9 18242.6 18303.2 13459.3 15047.2 15178.3 15554.5 15766.7 5632.3 5767.3 5769.4 5774.5 5801.4 4293.2 5642.3 6065.4 6341.7 6348.5 6221.1 6279.3 6293.4 6312.1 6315.4 6140.9 6513.3 6518.9 6519.8 6523.0 5679.3 5733.4 5747.9 5789.3 5802.7 21605.2 21857.3 22181.7 22472.0 22483.2 2525.5 2588.5 2612.0 2854.2 2918.5 14065.5 14280.8 14328.6 14504.1 14665.9 1963.3 2059.8 2094.3 2117.0 2183.2 73332.6 77417.0 79443.6 79521.0 79786.6 10407.2 10634.9 10836.5 10938.3 10981.5 15145.9 15188.7 15238.2 15271.8 15274.2 7228.5 7312.5 7319.7 7388.3 7435.2 6753.2 6799.8 6840.0 6872.4 6947.8 6437.1 6542.1 6567.0 6579.2 6596.2 1796.4 1849.0 1871.7 1885.1 1890.6 939.5 1031.6 1036.5 1038.0 1052.4 17157.0 18230.4 18302.7 18555.4 18728.0 980.0 989.3 990.5 1001.4 1004.1 6638.8 10448.2 10522.8 10690.5 10717.7 344715.0 498708.0 502036.0 554483.0 555404.0 93974.0 95329.0 96634.0 98073.5 99223.5 502.2 505.9 507.1 510.5 510.6 11080.8 11403.7 11614.8 11922.3 12013.2 40106.6 40681.0 40786.2 41555.4 41960.8 58072.1 59072.4 59207.4 59658.0 59921.0 9212.8 9327.7 9332.0 9408.9 9438.3 10874.5 13714.7 13838.4 14289.2 14404.1 11144.2 12225.9 12421.4 12483.4 12587.7 11454.6 11605.5 11662.5 11900.2 11956.6 11343.5 11879.3 12255.0 12555.6 12662.6 9153.2 9430.0 9440.8 9538.7 9609.0 12257.5 12339.8 12559.7 12999.4 13015.0 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#3] mootools.html Cycles:180 Average:5555.14 Median:5076.20 stddev:3063.60 (60.4%) stddev-sans-first:3055.84 18:21:01 INFO - PROCESS | 7398 | Values: 1344.2 1379.9 1389.3 1457.8 1573.6 7961.9 8137.6 8163.9 8166.6 8181.6 7779.4 7936.4 7947.2 7948.9 7964.3 2081.2 2321.4 2375.5 2384.2 2403.6 2376.2 2382.1 2384.5 2395.2 2396.5 2307.3 2350.7 2357.0 2360.2 2360.6 2050.6 2100.4 2104.8 2305.4 2347.0 2271.5 2299.4 2300.2 2301.4 2305.3 8245.5 8302.7 8317.6 8321.5 8324.5 5040.9 5106.1 5108.5 5144.8 5154.9 3349.1 3437.5 3455.1 3467.5 3474.8 1152.3 1174.2 2022.9 2118.7 2135.9 11649.9 11792.4 11818.9 11820.3 11824.1 4690.9 4743.7 4752.2 4827.5 4875.9 9302.7 9359.8 9433.2 9472.4 9487.8 5139.2 5157.5 5161.3 5162.3 5177.8 4176.4 4489.1 4564.1 4607.3 4611.5 3871.3 3889.6 3930.1 3961.3 4015.8 1210.4 1212.5 1233.9 1252.4 1259.3 803.2 882.8 893.3 909.1 909.5 5543.7 5580.5 5685.4 5691.5 5803.1 916.7 917.3 950.5 962.3 970.1 3704.8 3721.6 3757.5 3757.5 3792.4 10201.7 10576.2 10630.4 10649.4 10650.4 10234.8 10336.2 10348.4 10444.4 10474.5 7856.7 7865.8 7924.4 7945.2 8019.4 5328.2 5400.8 5495.3 5501.1 5534.9 9339.2 9353.5 9539.3 9541.2 9558.5 9065.3 9348.9 9374.7 9378.7 9653.9 6600.1 6740.7 7288.5 7699.3 7762.2 8050.9 8079.8 8149.3 8155.0 8175.2 7130.3 7348.2 7388.0 7455.1 7561.1 7467.1 7497.4 7531.2 7649.0 7656.8 7003.0 7076.8 7082.2 7099.3 7100.0 4816.5 4921.1 4975.1 5017.5 5037.5 4876.6 4941.7 5035.1 5042.9 5046.3 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#4] prototype.html Cycles:180 Average:5542.15 Median:6306.27 stddev:1651.02 (26.2%) stddev-sans-first:1608.87 18:21:01 INFO - PROCESS | 7398 | Values: 342.8 366.5 368.1 368.7 372.0 6044.9 6111.3 6114.7 6142.8 6215.2 5881.9 6113.3 6114.5 6129.2 6129.9 4707.9 4806.5 4818.3 4829.7 4836.3 4802.7 4814.8 4820.3 4829.0 4831.6 4551.3 4634.7 4783.0 4783.4 4800.4 4721.8 4724.8 4754.3 4778.2 4782.8 4239.9 4241.5 4244.3 4249.0 4250.4 6003.0 6018.5 6018.7 6018.8 6033.5 5567.8 5583.8 5590.4 5591.2 5591.9 6438.4 6486.2 6543.9 6559.0 6565.4 4677.9 4702.0 4706.8 4708.1 4714.5 6746.4 6824.6 6837.1 6839.6 6849.9 4823.2 4843.2 4844.0 4845.0 4849.1 4887.4 4893.0 4894.8 4896.3 4897.7 5544.5 5574.3 5586.8 5589.2 5589.5 6363.9 6564.3 6616.7 6630.5 6648.6 1701.9 1733.0 1734.7 1736.3 1736.9 1094.8 1159.1 1160.2 1175.2 1186.4 6319.8 6334.4 6338.7 6340.7 6343.8 6734.2 6739.0 6744.1 6745.2 6756.9 6305.4 6307.1 6307.9 6320.8 6333.4 3593.5 3625.4 3626.3 3626.9 3632.1 6867.1 7147.9 7156.6 7159.7 7165.3 6994.9 7035.3 7038.3 7102.7 7110.8 7118.7 7120.4 7134.5 7134.6 7142.6 6809.5 6858.5 6871.5 6872.6 6879.9 6510.0 6749.0 6760.6 6764.5 6765.3 7055.6 7102.8 7107.3 7108.8 7111.9 4626.7 4645.2 4651.5 4655.3 4658.3 6798.2 6803.4 6805.1 6806.5 6808.9 6487.7 6625.8 6649.2 6652.3 6657.0 6566.0 6576.5 6589.9 6593.7 6600.4 6476.6 6487.7 6502.8 6507.2 6508.4 6617.0 6660.9 6662.1 6671.4 6676.8 6337.5 6467.6 6474.8 6485.2 6489.5 18:21:01 INFO - PROCESS | 7398 | 18:21:01 INFO - PROCESS | 7398 | [#5] yui.html Cycles:180 Average:15341.19 Median:4621.32 stddev:18053.51 (390.7%) stddev-sans-first:18070.79 18:21:01 INFO - PROCESS | 7398 | Values: 725.2 748.8 751.6 755.7 761.0 14388.2 14546.9 14555.4 14588.5 14693.7 12621.4 12662.6 12665.6 12666.1 12720.3 536.5 589.2 595.2 598.0 610.9 582.4 587.0 587.9 588.5 590.1 645.7 655.4 655.4 665.7 665.7 582.7 834.6 857.8 859.1 860.1 636.9 637.1 637.4 637.6 638.1 13931.0 13954.4 13956.8 13981.9 14026.6 11745.6 12176.1 12324.8 12455.8 12575.3 11144.2 11271.5 11317.1 11434.4 11715.7 4609.2 4611.2 4611.7 4619.1 4624.2 58012.4 58847.4 59180.0 59279.0 59344.8 10143.0 10228.8 10285.4 10293.9 10444.4 15302.9 16944.1 16956.9 16987.7 16991.9 8141.4 8235.7 8310.6 8344.7 8348.3 4849.0 4885.9 4994.7 5034.8 5056.9 3345.5 3401.0 3409.5 3414.8 3421.0 526.7 568.7 594.5 595.2 597.0 656.9 679.1 691.6 727.5 751.7 8905.9 9496.8 9653.1 9672.1 9702.4 709.3 748.0 749.9 775.2 775.9 4512.3 4522.0 4623.5 4636.4 4644.8 57255.4 59609.0 59769.0 59775.4 65472.7 54336.5 55280.7 59234.0 60160.7 60445.1 51154.2 52167.7 53389.2 54101.9 54471.7 10022.5 10205.2 10224.1 10252.7 10270.4 38955.8 38964.4 39470.0 40093.8 41021.6 47488.0 50720.5 51252.0 51457.0 52181.7 11679.4 11915.1 11919.3 11978.0 12071.0 13541.1 13547.7 13646.8 13838.4 13848.2 12646.6 12651.5 12665.3 12792.8 13284.6 13516.3 13804.2 14031.2 14092.1 14179.1 12632.4 13120.0 13408.0 13431.5 13436.9 11011.4 11293.4 11428.8 11448.9 11737.4 10902.5 11496.9 11632.5 11658.7 11697.7 18:21:01 INFO - PROCESS | 7398 | -------- Summary: end -------- 18:21:01 INFO - PROCESS | 7398 | 18:21:02 INFO - TEST-INFO | 7398: exit 0 18:21:02 INFO - TEST-OK | dromaeo_css | took 1111811ms 18:21:02 INFO - TEST-START | kraken 18:21:02 INFO - operating with platform_type : linux_ 18:21:02 INFO - Initialising browser for kraken test... 18:21:02 INFO - TEST-INFO | started process 7592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvOgFV2/profile http://localhost:48089/getInfo.html) 18:21:06 INFO - PROCESS | 7592 | __metrics Screen width/height:1600/1200 18:21:06 INFO - PROCESS | 7592 | colorDepth:24 18:21:06 INFO - PROCESS | 7592 | Browser inner width/height: 1024/697 18:21:06 INFO - PROCESS | 7592 | __metrics 18:21:07 ERROR - PROCESS | 7592 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:21:08 INFO - TEST-INFO | 7592: exit 0 18:21:08 INFO - Browser initialized. 18:21:08 INFO - Running cycle 1/1 for kraken test... 18:21:08 INFO - TEST-INFO | started process 7681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvOgFV2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:19 INFO - PROCESS | 7681 | RSS: Main: 142262272 18:21:19 INFO - PROCESS | 7681 | 18:21:22 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48089/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:23 INFO - PROCESS | 7681 | RSS: Main: 197271552 18:21:23 INFO - PROCESS | 7681 | 18:21:25 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48089/tests/kraken/driver.html?testName=audio-dft) 18:21:26 INFO - PROCESS | 7681 | RSS: Main: 180756480 18:21:26 INFO - PROCESS | 7681 | 18:21:29 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48089/tests/kraken/driver.html?testName=audio-fft) 18:21:29 INFO - PROCESS | 7681 | RSS: Main: 272310272 18:21:29 INFO - PROCESS | 7681 | 18:21:31 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48089/tests/kraken/driver.html?testName=audio-oscillator) 18:21:32 INFO - PROCESS | 7681 | RSS: Main: 178520064 18:21:32 INFO - PROCESS | 7681 | 18:21:34 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48089/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:35 INFO - PROCESS | 7681 | RSS: Main: 176963584 18:21:35 INFO - PROCESS | 7681 | 18:21:38 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48089/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:38 INFO - PROCESS | 7681 | RSS: Main: 211058688 18:21:38 INFO - PROCESS | 7681 | 18:21:42 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48089/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:42 INFO - PROCESS | 7681 | RSS: Main: 211308544 18:21:42 INFO - PROCESS | 7681 | 18:21:45 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48089/tests/kraken/driver.html?testName=json-parse-financial) 18:21:46 INFO - PROCESS | 7681 | RSS: Main: 211509248 18:21:46 INFO - PROCESS | 7681 | 18:21:47 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48089/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:21:48 INFO - PROCESS | 7681 | RSS: Main: 190849024 18:21:48 INFO - PROCESS | 7681 | 18:21:49 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:21:50 INFO - PROCESS | 7681 | RSS: Main: 195190784 18:21:50 INFO - PROCESS | 7681 | 18:21:52 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:21:53 INFO - PROCESS | 7681 | RSS: Main: 207929344 18:21:53 INFO - PROCESS | 7681 | 18:21:55 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:21:56 INFO - PROCESS | 7681 | RSS: Main: 213405696 18:21:56 INFO - PROCESS | 7681 | 18:21:58 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:21:59 INFO - PROCESS | 7681 | RSS: Main: 210653184 18:21:59 INFO - PROCESS | 7681 | 18:22:00 INFO - PROCESS | 7681 | Cycle 1(1): loaded http://localhost:48089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48089/tests/kraken/driver.html?testName=ai-astar) 18:22:01 INFO - PROCESS | 7681 | RSS: Main: 274407424 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | __start_tp_report 18:22:01 INFO - PROCESS | 7681 | _x_x_mozilla_page_load 18:22:01 INFO - PROCESS | 7681 | _x_x_mozilla_page_load_details 18:22:01 INFO - PROCESS | 7681 | |i|pagename|runs| 18:22:01 INFO - PROCESS | 7681 | |0;ai-astar;112;110;110;110;111;111;106;108;109;111 18:22:01 INFO - PROCESS | 7681 | |1;audio-beat-detection;166;125;150;177;125;157;148;129;179;126 18:22:01 INFO - PROCESS | 7681 | |2;audio-dft;164;161;161;160;162;162;163;161;161;163 18:22:01 INFO - PROCESS | 7681 | |3;audio-fft;77;76;75;173;75;76;76;76;162;76 18:22:01 INFO - PROCESS | 7681 | |4;audio-oscillator;93;92;147;92;91;92;153;93;92;152 18:22:01 INFO - PROCESS | 7681 | |5;imaging-gaussian-blur;118;117;116;117;116;116;117;116;116;116 18:22:01 INFO - PROCESS | 7681 | |6;imaging-darkroom;145;145;145;146;145;145;145;146;145;144 18:22:01 INFO - PROCESS | 7681 | |7;imaging-desaturate;94;95;95;95;94;94;95;95;95;95 18:22:01 INFO - PROCESS | 7681 | |8;json-parse-financial;53;54;54;52;54;56;55;77;53;52 18:22:01 INFO - PROCESS | 7681 | |9;json-stringify-tinderbox;58;59;59;58;59;58;58;58;58;59 18:22:01 INFO - PROCESS | 7681 | |10;stanford-crypto-aes;73;72;70;73;72;74;73;74;73;73 18:22:01 INFO - PROCESS | 7681 | |11;stanford-crypto-ccm;116;115;116;113;119;116;109;114;128;135 18:22:01 INFO - PROCESS | 7681 | |12;stanford-crypto-pbkdf2;158;154;157;156;163;159;158;157;157;157 18:22:01 INFO - PROCESS | 7681 | |13;stanford-crypto-sha256-iterative;68;67;68;66;66;67;67;65;64;64 18:22:01 INFO - PROCESS | 7681 | __end_tp_report 18:22:01 INFO - PROCESS | 7681 | __start_cc_report 18:22:01 INFO - PROCESS | 7681 | _x_x_mozilla_cycle_collect,1622 18:22:01 INFO - PROCESS | 7681 | __end_cc_report 18:22:01 INFO - PROCESS | 7681 | __startTimestamp1464225721223__endTimestamp 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | ------- Summary: start ------- 18:22:01 INFO - PROCESS | 7681 | Number of tests: 14 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.50 stddev:1.75 (1.6%) stddev-sans-first:1.67 18:22:01 INFO - PROCESS | 7681 | Values: 112.0 110.0 110.0 110.0 111.0 111.0 106.0 108.0 109.0 111.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:153.50 stddev:21.38 (13.9%) stddev-sans-first:21.68 18:22:01 INFO - PROCESS | 7681 | Values: 166.0 125.0 150.0 177.0 125.0 157.0 148.0 129.0 179.0 126.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.01 18:22:01 INFO - PROCESS | 7681 | Values: 164.0 161.0 161.0 160.0 162.0 162.0 163.0 161.0 161.0 163.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:38.72 (51.0%) stddev-sans-first:40.57 18:22:01 INFO - PROCESS | 7681 | Values: 77.0 76.0 75.0 173.0 75.0 76.0 76.0 76.0 162.0 76.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:92.00 stddev:28.32 (30.8%) stddev-sans-first:29.38 18:22:01 INFO - PROCESS | 7681 | Values: 93.0 92.0 147.0 92.0 91.0 92.0 153.0 93.0 92.0 152.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.50 18:22:01 INFO - PROCESS | 7681 | Values: 118.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 18:22:01 INFO - PROCESS | 7681 | Values: 145.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 144.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 18:22:01 INFO - PROCESS | 7681 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:54.00 stddev:7.48 (13.9%) stddev-sans-first:7.86 18:22:01 INFO - PROCESS | 7681 | Values: 53.0 54.0 54.0 52.0 54.0 56.0 55.0 77.0 53.0 52.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 18:22:01 INFO - PROCESS | 7681 | Values: 58.0 59.0 59.0 58.0 59.0 58.0 58.0 58.0 58.0 59.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:73.00 stddev:1.16 (1.6%) stddev-sans-first:1.22 18:22:01 INFO - PROCESS | 7681 | Values: 73.0 72.0 70.0 73.0 72.0 74.0 73.0 74.0 73.0 73.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#11] stanford-crypto-ccm Cycles:10 Average:118.10 Median:116.00 stddev:7.69 (6.6%) stddev-sans-first:8.12 18:22:01 INFO - PROCESS | 7681 | Values: 116.0 115.0 116.0 113.0 119.0 116.0 109.0 114.0 128.0 135.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:157.50 stddev:2.32 (1.5%) stddev-sans-first:2.46 18:22:01 INFO - PROCESS | 7681 | Values: 158.0 154.0 157.0 156.0 163.0 159.0 158.0 157.0 157.0 157.0 18:22:01 INFO - PROCESS | 7681 | 18:22:01 INFO - PROCESS | 7681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.48 (2.2%) stddev-sans-first:1.41 18:22:01 INFO - PROCESS | 7681 | Values: 68.0 67.0 68.0 66.0 66.0 67.0 67.0 65.0 64.0 64.0 18:22:01 INFO - PROCESS | 7681 | -------- Summary: end -------- 18:22:01 INFO - PROCESS | 7681 | 18:22:02 INFO - TEST-INFO | 7681: exit 0 18:22:02 INFO - TEST-OK | kraken | took 60006ms 18:22:02 INFO - SUITE-END | took 1171s 18:22:02 INFO - Completed test suite (00:19:32) 18:22:02 INFO - javascript benchmark 18:22:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3303.4219011672953, 3351.447011243145, 3366.1584603849037, 3373.89878303651, 3396.1000334911255, 5478.369987451444, 5491.65454463657, 5506.091494903346, 5513.338399392073, 5513.9448605513935, 5510.366307874596, 5519.089350257203, 5530.6128571000045, 5535.529479994195, 5555.50000499955, 4950.9504904950845, 5007.549320561149, 5049.4950504949575, 5100.617453690969, 5152.51051150142, 4965.056639238551, 5029.094762942669, 5030.798768049273, 5040.1179793535985, 5044.848654540369, 4971.030649023442, 5029.220470827009, 5039.84880453587, 5041.0422019816115, 5047.873803154932, 5098.388193416795, 5118.718470484122, 5126.948730512684, 5148.639595228336, 5173.379194496647, 4916.139675556763, 4926.187179115442, 5006.774695138727, 5030.647854650176, 5038.244263360525, 5421.647592906488, 5442.292501974757, 5472.151816468454, 5474.644148130359, 5477.3427188737205, 5936.881262374728, 5953.58324917256, 5966.463018328328, 5973.283206015262, 5982.162497250389, 5868.4718375346, 5952.9107063394085, 5999.070144127667, 6009.969950150221, 6021.36775638556, 5537.335519737617, 5573.247611572427, 5573.888522229573, 5591.077472217079, 5597.692126932976, 5689.630174038674, 6072.635641861502, 6099.451049405575, 6108.938910610926, 6113.32753397126, 5684.573656975661, 5751.223584815996, 5782.10377391505, 5784.334801497797, 5786.594938354359, 6000.33996260411, 6004.639721616717, 6008.819735407945, 6009.308929473079, 6009.429104235091, 5806.21616081832, 5914.053751399755, 5975.163477113208, 5977.820665380045, 5979.222701048923, 5781.566382521328, 5877.23595932526, 5877.353491115974, 5877.9118313225335, 5904.822855314347, 4453.332000199931, 4479.574440428154, 4479.574440428154, 4481.193385190697, 4507.323901414748, 3988.720789544762, 4003.9990002499376, 4014.8996275092773, 4026.456428382131, 4059.939100913489, 5592.608517403824, 5596.916046259309, 5621.7189140542805, 5628.746706398222, 5652.434756524314, 5899.911501327483, 5905.793297234576, 5912.290525137011, 5921.851953701192, 5934.703264836741, 5524.088525393346, 5630.211770352154, 5676.290463692038, 5805.593608447368, 5814.098814683731, 5086.3133476980875, 5087.109755792721, 5087.923681144785, 5088.389393272831, 5096.0, 6161.4454699076405, 6170.0, 6171.907421388682, 6173.567850250529, 6178.567500274938, 5848.5321174305955, 6011.188489553854, 6064.151018857276, 6093.451589356979, 6101.938980610137, 6108.725107370268, 6118.47992920589, 6119.44924956756, 6122.112293717475, 6128.203333566607, 5693.089105743061, 5768.798092066758, 5770.13447982806, 5773.134029895549, 5775.306963164448, 5908.763649453974, 5998.640081595118, 6006.309274433322, 6007.189029480965, 6044.395560444096, 5801.042827933344, 5824.621399609012, 5825.271841019872, 5837.766489340379, 5840.503557197516, 5650.717464126859, 5736.569757268305, 5745.540356771532, 5790.536757059509, 5800.216970708901, 5670.914936276042, 5688.260526131577, 5695.886082278248, 5720.856978575569, 5731.541476681772, 5772.336181339199, 5784.248047753765, 5792.565557583114, 5803.709814509173, 5824.970875145597, 5673.74468148926, 5696.971515142398, 5714.314282286152, 5762.222100016612, 5830.970845145747], "value": 5469.656097133425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.6111846740384, 646.9874646178733, 647.7829926974463, 662.9602223866567, 670.152257394396, 35256.2948741025, 35809.283814323695, 35820.104497387576, 35857.46213806792, 36048.27903441933, 37622.62377376226, 38265.0, 38351.808240958824, 38442.231155376874, 38444.23111537775, 8591.656333746643, 8770.342224333168, 8770.386072974863, 8775.824483510325, 8780.648774049063, 8736.781580460476, 8876.6005529751, 8880.644774209057, 8881.822363552725, 8882.0, 8861.689840855572, 8929.464232146092, 8933.419327743708, 8934.687285944994, 8936.55317234139, 8884.244839188645, 8938.64245430182, 8947.50788706621, 8948.418352807046, 8952.0, 8612.440191387603, 8701.869471957925, 8712.912870871336, 8715.433496822687, 8715.564221788885, 37779.4333085004, 37861.43207851885, 38000.619993799985, 38013.80993095045, 38039.809800951094, 3369.578802649669, 3499.142710036032, 3645.7447978641508, 3904.160605469809, 3954.367301231789, 28133.29666758327, 28808.279793005135, 28976.71023289761, 29150.562741558893, 29430.26424339387, 4288.549702281247, 4362.978185109054, 4392.38506609075, 4393.846215382447, 4408.0, 269197.0, 271981.999999998, 272139.0, 272355.0, 272753.0, 33927.83036084853, 50920.74539627278, 51223.23165152526, 51252.231216531785, 51521.454356369366, 32982.835085824896, 33004.17489562732, 33148.66851331504, 33167.0, 33203.50194747032, 13755.518556850462, 13805.723885522435, 13807.51673691416, 13842.86157138436, 13869.029167958146, 11535.250208736574, 11564.248323858923, 11571.479283432098, 11571.710707232218, 11587.88412115868, 8414.11651776567, 8491.490510569385, 8514.276286515715, 8563.871541926876, 8574.657013719381, 3159.036493869346, 3170.9841450792596, 3182.872685092617, 3226.161198088514, 3234.5471633971265, 944.447498213539, 1027.0345874877592, 1071.6624263357019, 1093.830456279278, 1109.7336639206533, 29605.555916661266, 29796.404071918874, 30061.398772024437, 30129.397412051636, 30575.541366879515, 1046.7383154211448, 1074.3392813419846, 1093.2347356850237, 1111.6387174168267, 1136.323887287063, 15021.774673379909, 15627.14050727221, 15741.291641876143, 15783.21083945821, 16014.519564413085, 490714.0, 493497.0, 496266.0, 496710.0, 498484.0, 50390.496095039314, 51806.22290665643, 51875.22187167196, 51964.0, 52101.47898521042, 1312.8293321868096, 1332.000999250562, 1345.0248569786827, 1384.8545902680276, 1405.0375492787268, 27701.58447623247, 28542.42915141727, 28580.285492862844, 32537.511937321433, 33193.668063319055, 49946.50053499564, 50324.0, 50646.73383165451, 50681.49318506768, 50701.74649126879, 426282.0, 426734.0, 429696.0, 431302.0, 431425.0, 35723.0, 39548.0, 40655.59344406518, 40908.1818363637, 41013.0, 38754.80622596869, 40292.0, 40909.38635920523, 42508.14983700371, 43634.781826090664, 36367.090822729646, 36463.45304820483, 36756.0, 36857.0, 37194.8140259297, 35392.823035884656, 35855.64144358523, 35966.460503092996, 35984.28031439409, 36088.0, 36011.0, 36924.81537592402, 37451.625483744814, 37455.25089498249, 37459.0, 33969.66030339665, 36664.0, 36671.0, 37095.0, 37121.25757484889, 25260.873695631406, 25765.484690306464, 25784.61323080193, 25925.70484888222, 25938.221853343675], "value": 17952.924653051014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.8919086442093, 410.1714536635993, 412.6678024190525, 416.26529176004345, 420.5878239325461, 14115.57653270401, 15589.922050389732, 17534.91232543837, 18242.635147297064, 18303.17635706393, 13459.259740714257, 15047.24763761813, 15178.31697573609, 15554.53336399907, 15766.684666306666, 5632.324121105473, 5767.279090113736, 5769.365369809318, 5774.451427114438, 5801.390853960315, 4293.205756934962, 5642.266505354298, 6065.363136870631, 6341.746330146789, 6348.460380867632, 6221.066839973995, 6279.340669229734, 6293.4335909768115, 6312.053192021188, 6315.368463153671, 6140.877182456348, 6513.348665133496, 6518.869622607545, 6519.837004074912, 6523.0, 5679.261695979496, 5733.369329373765, 5747.885042299173, 5789.305283365981, 5802.7388767505145, 21605.243816466347, 21857.344279671477, 22181.667274990887, 22472.0, 22483.21308754186, 2525.5075260324243, 2588.5211235921415, 2611.9608205876925, 2854.200823769348, 2918.460084884282, 14065.507707230197, 14280.785788213081, 14328.570142895729, 14504.129752214902, 14665.853341466556, 1963.2735887721492, 2059.794020597943, 2094.277474271374, 2117.026167962739, 2183.235867446397, 73332.6333368335, 77417.0, 79443.6027819863, 79521.0, 79786.60106699429, 10407.21945854064, 10634.883337249632, 10836.458177091114, 10938.289011214245, 10981.450927453756, 15145.924270378799, 15188.696226075417, 15238.161901095324, 15271.770923436157, 15274.236288185546, 7228.457865660096, 7312.488125831247, 7319.687866171311, 7388.261173882676, 7435.1821299656995, 6753.189617245864, 6799.7960061197255, 6840.0, 6872.41584465326, 6947.826304342432, 6437.130987316652, 6542.084108224853, 6567.0, 6579.210494740566, 6596.175478065242, 1796.4071856287394, 1848.9630207395776, 1871.7192421136665, 1885.1422602716007, 1890.5651700108776, 939.5208443693629, 1031.6440827914357, 1036.5180191211123, 1037.984430233547, 1052.4264275969463, 17157.0, 18230.36193733213, 18302.745661137527, 18555.35056273024, 18728.0, 979.9902000979899, 989.2728844299434, 990.5096976996289, 1001.4191768774093, 1004.091297375876, 6638.800835974928, 10448.164146868232, 10522.842157367646, 10690.465476726287, 10717.732056698489, 344715.0, 498708.0, 502036.0, 554483.0, 555404.0, 93974.0, 95328.99999999862, 96634.0, 98073.50963245139, 99223.50388248013, 502.2290783647084, 505.94181669107786, 507.0872429626657, 510.47676131965034, 510.60683273879647, 11080.778384432428, 11403.657890263305, 11614.825777613512, 11922.344271065174, 12013.159078864395, 40106.59893401029, 40680.98297542585, 40786.18427631372, 41555.37666934938, 41960.79019604882, 58072.12891806711, 59072.40927590841, 59207.40792592019, 59658.0, 59921.0, 9212.815743685223, 9327.673531426368, 9331.953340233254, 9408.905910941077, 9438.339316247797, 10874.510647020745, 13714.65713357174, 13838.37727302294, 14289.214093224973, 14404.135751854923, 11144.2199046066, 12225.877741222474, 12421.441035153259, 12483.375831208221, 12587.74824503523, 11454.599089031844, 11605.535778568763, 11662.475188616363, 11900.226485278428, 11956.641300760992, 11343.546258149581, 11879.287242765462, 12255.0, 12555.560555380518, 12662.556810511956, 9153.17621414076, 9429.952850235704, 9440.811183776148, 9538.666146684833, 9608.95195524018, 12257.509699611915, 12339.75320493603, 12559.686007849512, 12999.41502632365, 13015.0], "value": 9114.41524143755, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.159900062461, 1379.8896088312936, 1389.2775756606566, 1457.7959085728, 1573.5830005048656, 7961.88057179142, 8137.593120343989, 8163.91836081639, 8166.611676015077, 8181.631826567804, 7779.377649788017, 7936.365090792737, 7947.245011723891, 7948.92051079489, 7964.323032542241, 2081.1779347157863, 2321.3500219938446, 2375.5130198309307, 2384.153625462962, 2403.615421532555, 2376.192094687806, 2382.118616112041, 2384.4873352229265, 2395.1736650855514, 2396.4847557775074, 2307.261676263596, 2350.74141844397, 2356.9882150589224, 2360.24472168906, 2360.551495215912, 2050.5591297870956, 2100.4328831215485, 2104.8316134709185, 2305.43516838374, 2346.9530609387803, 2271.4548508357875, 2299.3906614747107, 2300.2409204962323, 2301.4361481437154, 2305.319930620454, 8245.464044837066, 8302.70940517085, 8317.625706843206, 8321.500709957361, 8324.500529968162, 5040.949590504085, 5106.106431374495, 5108.5402313791565, 5144.794208231704, 5154.871128221787, 3349.062262566485, 3437.4671925851535, 3455.1189446691183, 3467.514547963287, 3474.843632036539, 1152.3143729480946, 1174.2484809721784, 2022.8685135466148, 2118.66101423773, 2135.882526461044, 11649.941750291278, 11792.41037948099, 11818.881811181864, 11820.349880756556, 11824.05407567384, 4690.929636055462, 4743.715377077387, 4752.215884379108, 4827.468978412372, 4875.902921842557, 9302.674406395743, 9359.766005849908, 9433.151016408556, 9472.384295020802, 9487.810243795224, 5139.203423469368, 5157.4584668610005, 5161.329027226511, 5162.328897243409, 5177.792888284502, 4176.415301857743, 4489.079738653568, 4564.132814765184, 4607.308903664477, 4611.492735799059, 3871.3225185592974, 3889.5527014393706, 3930.1353702185475, 3961.286968345668, 4015.8393664253686, 1210.449245593253, 1212.5331747277362, 1233.9444724987397, 1252.4176258040054, 1259.2822091407809, 803.2489622203259, 882.8366752150872, 893.271983333581, 909.0954500272271, 909.5315912305168, 5543.695096769717, 5580.525655319262, 5685.403032681509, 5691.544676425876, 5803.071508558694, 916.6516723645105, 917.3074328881652, 950.5247376311844, 962.3359881681617, 970.126885802769, 3704.7962362069807, 3721.553413590332, 3757.4739536464917, 3757.492738480325, 3792.393217085253, 10201.693949181536, 10576.15390768752, 10630.362178269328, 10649.361038337725, 10650.360978341325, 10234.846477302846, 10336.224783141295, 10348.430836304076, 10444.373337599769, 10474.528646210938, 7856.685732570633, 7865.803354916173, 7924.366050715929, 7945.205479452008, 8019.39854510914, 5328.227407026037, 5400.756965936621, 5495.258140151029, 5501.092319767354, 5534.86162845916, 9339.159475647224, 9353.532323383939, 9539.284553658365, 9541.188998934891, 9558.522073896416, 9065.274778017874, 9348.906510934803, 9374.671886484213, 9378.67174648884, 9653.855192172263, 6600.141981542369, 6740.696668650021, 7288.453365997678, 7699.30706236419, 7762.184970578008, 8050.919490805251, 8079.83840323202, 8149.266566009088, 8154.959225204073, 8175.223353781381, 7130.251323610946, 7348.154962179417, 7388.0, 7455.142658594331, 7561.09266887955, 7467.103947526114, 7497.437692173, 7531.246875312425, 7649.0, 7656.80857978555, 7003.0, 7076.752313668997, 7082.150141982995, 7099.254578269208, 7100.0, 4816.54242846943, 4921.138800709933, 4975.10448119342, 5017.523335283142, 5037.5214354636255, 4876.634252431011, 4941.703497790144, 5035.0685123252315, 5042.899142017066, 5046.293518907282], "value": 4517.165999350232, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.79432340595645, 366.54365315182594, 368.09815950920245, 368.6608320345282, 372.03085961071423, 6044.879102417949, 6111.297200821907, 6114.724837382318, 6142.754289828402, 6215.160953271318, 5881.882362352761, 6113.2664080310315, 6114.541409394291, 6129.203203583539, 6129.938700612993, 4707.882302942419, 4806.543378379066, 4818.325434439182, 4829.734364609945, 4836.322914791932, 4802.735849528274, 4814.7833347499445, 4820.301056346828, 4829.0, 4831.613470922335, 4551.2717965125585, 4634.652401069917, 4783.043391321732, 4783.378160839104, 4800.351952486422, 4721.787519561628, 4724.763761811896, 4754.263089221176, 4778.1638213312535, 4782.760861956888, 4239.91520169598, 4241.491021077491, 4244.3209086546, 4249.043965107857, 4250.362445633149, 6003.0, 6018.488428483584, 6018.6689732064715, 6018.759249630009, 6033.48715359195, 5567.805126820542, 5583.776648934037, 5590.440955904418, 5591.189277554772, 5591.8881622367735, 6438.388353106494, 6486.210929300399, 6543.9345606544275, 6559.016147577854, 6565.409113179837, 4677.859664210045, 4702.0, 4706.7646617669325, 4708.1289961356915, 4714.528547145293, 6746.359095885883, 6824.590524568592, 6837.145356830396, 6839.589624622538, 6849.863002739918, 4823.1800593898415, 4843.176659967814, 4844.0, 4844.975775121102, 4849.078675051729, 4887.413510378778, 4892.951070489321, 4894.828680996148, 4896.314515967767, 4897.681650692765, 5544.528715059265, 5574.275344205308, 5586.776528938797, 5589.245451864028, 5589.524890384363, 6363.936360636427, 6564.343565643398, 6616.702248398834, 6630.502712296597, 6648.634325112069, 1701.9404320848957, 1733.0, 1734.7224444088886, 1736.2794440307384, 1736.947891563255, 1094.7810437912449, 1159.0785325666116, 1160.1704781081567, 1175.1538891997748, 1186.3890096600257, 6319.842003949938, 6334.366563343721, 6338.683065846689, 6340.682965851781, 6343.841403964938, 6734.191896972395, 6738.9891516272955, 6744.089547911068, 6745.224299205556, 6756.898646520306, 6305.4009869062365, 6307.148534947723, 6307.905381419282, 6320.810375688737, 6333.429991300805, 3593.5148754918273, 3625.4018087015347, 3626.274745050948, 3626.9093272667865, 3632.0919770057485, 6867.107276054081, 7147.89278160828, 7156.642167891585, 7159.749408770668, 7165.283471652897, 6994.93005069953, 7035.261297563682, 7038.260982596754, 7102.715891364288, 7110.7511237106455, 7118.679659415338, 7120.394766444807, 7134.464915131386, 7134.607596582134, 7142.5714457132735, 6809.489288303298, 6858.485613578901, 6871.5189936703955, 6872.62200578973, 6879.931200687929, 6510.0, 6748.966255168692, 6760.560563563353, 6764.4588432924265, 6765.323467653392, 7055.6472176392, 7102.786916392516, 7107.289271073058, 7108.751193708195, 7111.893321600077, 4626.7223966562115, 4645.163870503342, 4651.511591282867, 4655.2784318430695, 4658.254679251304, 6798.184217893884, 6803.421709154777, 6805.149356330458, 6806.489513286622, 6808.93191068083, 6487.708053137526, 6625.801225963229, 6649.235337936199, 6652.334766523309, 6656.966715166393, 6566.0479230512265, 6576.506761992774, 6589.901151482827, 6593.703283352356, 6600.438962688226, 6476.579022363531, 6487.675616219264, 6502.772402966063, 6507.219133703797, 6508.381703738138, 6616.966915165393, 6660.935110115685, 6662.100616416722, 6671.366220209073, 6676.833079172865, 6337.4613157882095, 6467.579607325509, 6474.773382931575, 6485.15692959931, 6489.513286503626], "value": 5012.267384614492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.1877896755634, 748.8053106192392, 751.6429695894451, 755.6826133024131, 761.0486891385766, 14388.208648524327, 14546.927265363685, 14555.417783288656, 14588.489402870902, 14693.706125877476, 12621.432035558399, 12662.62012139635, 12665.62003139905, 12666.050046246522, 12720.300383478905, 536.4662161149662, 589.1692713274263, 595.2262059322885, 597.9820605381824, 610.9083637454384, 582.4408567774941, 586.9677167755793, 587.8589138606746, 588.4997751910885, 590.10598942602, 645.7094307561592, 655.4134050025225, 655.4199533412916, 665.6804733727789, 665.7337065173921, 582.6999095465839, 834.6327615849037, 857.8498762716499, 859.0593300336122, 860.1011942520081, 636.9299377068519, 637.092143695237, 637.4390536328048, 637.6397335505425, 638.1002786071663, 13931.0, 13954.441822327095, 13956.790648140286, 13981.860181398157, 14026.579202623938, 11745.647630571097, 12176.086793490524, 12324.767523247694, 12455.813162802566, 12575.308358040304, 11144.164187685956, 11271.492782824793, 11317.094632429385, 11434.371109588968, 11715.7071073223, 4609.239475486541, 4611.239145540982, 4611.677182597219, 4619.122366750306, 4624.213883639755, 58012.419875801126, 58847.41152588505, 59180.0, 59279.0, 59344.81310373812, 10143.036411540892, 10228.846567301496, 10285.43430111336, 10293.94853025745, 10444.373337599769, 15302.923485382724, 16944.068076255677, 16956.915215423844, 16987.74518382225, 16991.91504042472, 8141.389395795339, 8235.703630034808, 8310.626021829032, 8344.66621335152, 8348.33213342931, 4848.975755121272, 4885.877853053631, 4994.725290109079, 5034.84895453137, 5056.86220600362, 3345.4981752737285, 3400.9659903400648, 3409.454487282023, 3414.7951122932704, 3421.0, 526.6973413620129, 568.6502800777492, 594.5392320951211, 595.238095238096, 597.0387675841891, 656.9310222426672, 679.0900193740412, 691.6265216782881, 727.4653129949482, 751.7218629107156, 8905.866412003825, 9496.810063798684, 9653.131218190396, 9672.129508344282, 9702.417854928726, 709.3223082862133, 747.9513831600929, 749.9325060744559, 775.1976704111217, 775.9301662850371, 4512.300593408027, 4521.977390113094, 4623.514530974267, 4636.374089497875, 4644.790984405709, 57255.42744572584, 59609.0, 59769.0, 59775.40224597785, 65472.67263663651, 54336.45663543314, 55280.72359638176, 59234.0, 60160.69919650374, 60445.09332360018, 51154.232686509735, 52167.73916130395, 53389.1991620126, 54101.91796164133, 54471.72764136154, 10022.498875056364, 10205.234607404325, 10224.079832815083, 10252.743681408025, 10270.383776973405, 38955.805220973714, 38964.41553376645, 39470.0, 40093.79953100216, 41021.58978410178, 47488.0, 50720.49279507158, 51252.0, 51457.0, 52181.73909130582, 11679.416029198675, 11915.106367022334, 11919.3444360561, 11978.006681514365, 12071.0, 13541.1198272112, 13547.729045418839, 13646.795298070736, 13838.377273022537, 13848.169109853441, 12646.620601381974, 12651.493940242288, 12665.303408312262, 12792.808107878576, 13284.601461956157, 13516.324183790968, 13804.17174969505, 14031.228282444563, 14092.084014539023, 14179.14925104497, 12632.368381581067, 13120.0, 13408.0, 13431.462741490623, 13436.865631343562, 11011.394373309546, 11293.378864162221, 11428.771424571296, 11448.885511144781, 11737.354445505238, 10902.454877255946, 11496.88503114958, 11632.534698612299, 11658.717541070646, 11697.654769701252], "value": 6687.54067474648, "unit": "score"}], "name": "dromaeo_css", "value": 7167.004714999012, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 110.0, 110.0, 111.0, 111.0, 106.0, 108.0, 109.0, 111.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 125.0, 150.0, 177.0, 125.0, 157.0, 148.0, 129.0, 179.0, 126.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 160.0, 162.0, 162.0, 163.0, 161.0, 161.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 173.0, 75.0, 76.0, 76.0, 76.0, 162.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 147.0, 92.0, 91.0, 92.0, 153.0, 93.0, 92.0, 152.0], "value": 109.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 144.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 52.0, 54.0, 56.0, 55.0, 77.0, 53.0, 52.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0, 58.0, 59.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 70.0, 73.0, 72.0, 74.0, 73.0, 74.0, 73.0, 73.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 113.0, 119.0, 116.0, 109.0, 114.0, 128.0, 135.0], "value": 118.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 154.0, 157.0, 156.0, 163.0, 159.0, 158.0, 157.0, 157.0, 157.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 65.0, 64.0, 64.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1509.0, "alertThreshold": 2.0}]} 18:22:02 INFO - Return code: 0 18:22:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:22:02 ERROR - # TBPL SUCCESS # 18:22:02 INFO - Running post-action listener: _resource_record_post_action 18:22:02 INFO - Running post-run listener: _resource_record_post_run 18:22:03 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 498937856; Read time: 20; Write time: 418976 18:22:03 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 249057280; Read time: 0; Write time: 269468 18:22:03 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249692160; Read time: 20; Write time: 149484 18:22:03 INFO - Running post-run listener: _upload_blobber_files 18:22:03 INFO - Blob upload gear active. 18:22:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:03 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:22:03 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:03 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:04 INFO - (blobuploader) - INFO - Done attempting. 18:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:22:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:05 INFO - (blobuploader) - INFO - Done attempting. 18:22:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:05 INFO - Return code: 0 18:22:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89926a67925f3d040c2277662c8426657fac8d4bedbcd314f54001d939ed2f56f11333b1bb0f8d6e1f20400cff6d5bc20a8ef6909d97aff7f433278bb75b7248", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e26e954a5f25423a45a8aa1f8668cdbce14f5db91e94dfccaa6709579563d8351d40d10d6052664bc6078d06a644fb9706e52b61bd520a1323c3da802e4194c"} 18:22:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:05 INFO - Contents: 18:22:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89926a67925f3d040c2277662c8426657fac8d4bedbcd314f54001d939ed2f56f11333b1bb0f8d6e1f20400cff6d5bc20a8ef6909d97aff7f433278bb75b7248", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e26e954a5f25423a45a8aa1f8668cdbce14f5db91e94dfccaa6709579563d8351d40d10d6052664bc6078d06a644fb9706e52b61bd520a1323c3da802e4194c"} 18:22:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:05 INFO - Copying logs to upload dir... 18:22:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.845026 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-05-25 18:22:05.640570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 18:22:05.644039) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89926a67925f3d040c2277662c8426657fac8d4bedbcd314f54001d939ed2f56f11333b1bb0f8d6e1f20400cff6d5bc20a8ef6909d97aff7f433278bb75b7248", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e26e954a5f25423a45a8aa1f8668cdbce14f5db91e94dfccaa6709579563d8351d40d10d6052664bc6078d06a644fb9706e52b61bd520a1323c3da802e4194c"} build_url:https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011529 build_url: 'https://queue.taskcluster.net/v1/task/FQnWc9y1SQG6rB-aTRfA3w/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89926a67925f3d040c2277662c8426657fac8d4bedbcd314f54001d939ed2f56f11333b1bb0f8d6e1f20400cff6d5bc20a8ef6909d97aff7f433278bb75b7248", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e26e954a5f25423a45a8aa1f8668cdbce14f5db91e94dfccaa6709579563d8351d40d10d6052664bc6078d06a644fb9706e52b61bd520a1323c3da802e4194c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 18:22:05.670141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:22:05.670479) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1464222137.172683-730724040 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 18:22:05.754583) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 18:22:05.754906) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 18:22:05.755199) ========= ========= Total master_lag: 0.18 =========